GPRS_C_SDK icon indicating copy to clipboard operation
GPRS_C_SDK copied to clipboard

bad signal results into program crash

Open glararan opened this issue 4 years ago • 7 comments

Hi,

I am using simple program calling to phone to try circut. However results into crash without success call. Note: DIALING is shown in trace.

Source code:

#include <api_os.h>
#include <api_debug.h>
#include <api_call.h>
#include <api_audio.h>
#include <api_event.h>

#include <ctype.h>

#define MAIN_TASK_STACK_SIZE    (2048 * 2)
#define MAIN_TASK_PRIORITY      0 
#define MAIN_TASK_NAME         "Main Task"

// CONFIG
#define CONTACT_NUMBER		"my contact number" // e.g. 123456789

static HANDLE mainTaskHandle = NULL;

static uint8_t flag = 0;
static uint8_t phoneFlag = 0;

static uint8_t first = 0;

void EventDispatch(API_Event_t* pEvent)
{
    switch (pEvent->id)
    {
    case API_EVENT_ID_NO_SIMCARD:
        Trace(10, "!!NO SIM CARD%d!!!!", pEvent->param1);
        break;

    case API_EVENT_ID_SYSTEM_READY:
        {
            Trace(1, "system initialize complete");
            flag |= 1;
        }
        break;

    case API_EVENT_ID_NETWORK_REGISTERED_HOME:
    case API_EVENT_ID_NETWORK_REGISTERED_ROAMING:
        {
            Trace(2, "network register success");
            flag |= 2;
        }
        break;

    case API_EVENT_ID_CALL_DIAL:
        {
            phoneFlag = 1;

            if (pEvent->param1)
                Trace(1, "Dial success");
            else
                Trace(1, "Dial failed");
        }
        break;

    case API_EVENT_ID_CALL_HANGUP:
        {
            phoneFlag = 0;
        }
        break;

    case API_EVENT_ID_CALL_INCOMING:
        {
            Trace(1, "Incoming call...");

            if (!CALL_HangUp())
                Trace(1, "Failed to hangup incoming call");
            else
                Trace(1, "Hanged up incoming call");
        }
        break;

    default:
        break;
    }

    if (flag == 3 && first == 0)
    {
        first = 1;

        if (!CALL_Dial(CONTACT_NUMBER))
            Trace(1, "DIAL FAILED");
        else
            Trace(1, "DIALING");
    }
}

static void MainTask()
{
    API_Event_t* event = NULL;

    while (1)
    {
        if (OS_WaitEvent(mainTaskHandle, (void**)&event, OS_TIME_OUT_WAIT_FOREVER))
        {
            EventDispatch(event);

            OS_Free(event->pParam1);
            OS_Free(event->pParam2);
            OS_Free(event);
        }
    }
}

void main_Main(void)
{
    mainTaskHandle = OS_CreateTask(MainTask, NULL, NULL, MAIN_TASK_STACK_SIZE, MAIN_TASK_PRIORITY, 0, 0, MAIN_TASK_NAME);

    OS_SetUserMainHandle(&mainTaskHandle);
}

coolwatcher trace:

[17:05:52.754]          : SXS_INIT_RMC
[17:05:54.087]          : Detected event: 0xffffff02
[17:05:54.096]          : Detected event: 0xb0070000
[17:05:54.170]   SXR 01 : Allocated Tasks 19 Schedule Disable 1\n
[17:05:54.170]   SXR 01 : Task  0: Task Idle Priority 255
[17:05:54.170]   SXR 01 : is pending.\n
[17:05:54.170]   SXR 01 : Stack  32/8192 Top 0x820b0ed8 <- 0x820b2ed8\n
[17:05:54.170]   SXR 01 : Task  1: Layer 1 Priority   1
[17:05:54.171]   SXR 01 : is pending.\n
[17:05:54.172]   SXR 01 : Stack  32/1536 Top 0x820b2ef0 <- 0x820b34f0\n
[17:05:54.172]   SXR 01 : Task  2: Sim Priority  15
[17:05:54.172]   SXR 01 : is pending.\n
[17:05:54.172]   SXR 01 : Stack  32/800 Top 0x820b3508 <- 0x820b3828\n
[17:05:54.172]   SXR 01 : Task  3: LAPDm Priority  21
[17:05:54.173]   SXR 01 : is pending.\n
[17:05:54.174]   SXR 01 : Stack  32/800 Top 0x820b3840 <- 0x820b3b60\n
[17:05:54.174]   SXR 01 : Task  4: RR Priority  25
[17:05:54.174]   SXR 01 : is pending.\n
[17:05:54.175]   SXR 01 : Stack  32/4096 Top 0x820b3b78 <- 0x820b4b78\n
[17:05:54.175]   SXR 01 : Task  5: RLD Priority  26
[17:05:54.175]   SXR 01 : is pending.\n
[17:05:54.175]   SXR 01 : Stack  32/800 Top 0x820b4b90 <- 0x820b4eb0\n
[17:05:54.175]   SXR 01 : Task  6: RLU Priority  27
[17:05:54.176]   SXR 01 : is pending.\n
[17:05:54.177]   SXR 01 : Stack  32/1536 Top 0x820b4ec8 <- 0x820b54c8\n
[17:05:54.177]   SXR 01 : Task  7: LLC Priority  29
[17:05:54.177]   SXR 01 : is pending.\n
[17:05:54.177]   SXR 01 : Stack  32/1024 Top 0x820b54e0 <- 0x820b58e0\n
[17:05:54.178]   SXR 01 : Task  8: MM Priority  30
[17:05:54.178]   SXR 01 : is pending.\n
[17:05:54.178]   SXR 01 : Stack  32/2000 Top 0x820b58f8 <- 0x820b60c8\n
[17:05:54.179]   SXR 01 : Task  9: CC Priority  35
[17:05:54.179]   SXR 01 : is pending.\n
[17:05:54.179]   SXR 01 : Stack  32/1536 Top 0x820b60e0 <- 0x820b66e0\n
[17:05:54.179]   SXR 01 : Task 10: SS Priority  40
[17:05:54.179]   SXR 01 : is pending.\n
[17:05:54.180]   SXR 01 : Stack  32/1024 Top 0x820b66f8 <- 0x820b6af8\n
[17:05:54.180]   SXR 01 : Task 11: SMS Priority  45
[17:05:54.180]   SXR 01 : is pending.\n
[17:05:54.180]   SXR 01 : Stack  32/1024 Top 0x820b6b10 <- 0x820b6f10\n
[17:05:54.181]   SXR 01 : Task 12: CB Priority  50
[17:05:54.181]   SXR 01 : is pending.\n
[17:05:54.181]   SXR 01 : Stack  32/1024 Top 0x820b6f28 <- 0x820b7328\n
[17:05:54.181]   SXR 01 : Task 13: SM Priority 120
[17:05:54.182]   SXR 01 : is pending.\n
[17:05:54.183]   SXR 01 : Stack  32/1024 Top 0x820b7340 <- 0x820b7740\n
[17:05:54.183]   SXR 01 : Task 14: SNDCP Priority 125
[17:05:54.183]   SXR 01 : is pending.\n
[17:05:54.183]   SXR 01 : Stack  32/1024 Top 0x820b7758 <- 0x820b7b58\n
[17:05:54.184]   SXR 01 : Task 15: API Priority 150
[17:05:54.184]   SXR 01 : is pending.\n
[17:05:54.184]   SXR 01 : Stack  32/1024 Top 0x820b7b70 <- 0x820b7f70\n
[17:05:54.185]   SXR 01 : Task 16: CFW Priority 201
[17:05:54.185]   SXR 01 : is pending.\n
[17:05:54.185]   SXR 01 : Stack  32/20480 Top 0x820b7f88 <- 0x820bcf88\n
[17:05:54.186]   SXR 01 : Task 17: USR_INIT: BAL Init Task Priority   2
[17:05:54.186]   SXR 01 : is pending.\n
[17:05:54.187]   SXR 01 : Stack  32/3072 Top 0x820bcfa0 <- 0x820bdba0\n
[17:05:54.187]   SXR 01 : Task 18: USR_INIT: Decompress Task Priority   3
[17:05:54.188]   SXR 01 : is pending.\n
[17:05:54.188]   SXR 01 : Stack  32/1024 Top 0x820bdbb8 <- 0x820bdfb8\n
[17:05:54.189]       /I : FBDEV: scan min_erase_count/1 mismatch_count/0 mismatch/0
[17:05:54.190]       /I : SFFS mount, device block size/512 block count/749 cache count 4
[17:05:54.190]       /I : SFFS mount move count/0 block free/690
[17:05:54.191]   CSW 02 : Entry CFW_ShellControl\r\n
[17:05:54.191]   CSW 02 : CFW_CONTROL_CMD_POWER_ON\r\n
[17:05:54.191]   CSW 02 : CFW_Init invoked\n
[17:05:54.191]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x0,SimStatus 0x0,Wanted 1
[17:05:54.191]   SIM 01 : (sim_ResetReq)
[17:05:54.312]   SIM 01 : Reset Status: 0
[17:05:55.381]          : Fn 0000147 T1 0000 T2 17 T3 45 Time 00 00 03 563
[17:05:55.381]   SIM 01 : Reset Status: 1
[17:05:55.381]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[17:05:55.381]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[17:05:55.391]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[17:05:55.391]          : Fn 0000149 T1 0000 T2 19 T3 47 Time 00 00 03 574
[17:05:55.495]   SIM 01 : Reset Status: 3
[17:05:57.154]          : Detected event: 0x6c67f234
[17:05:57.254]          : Detected event: 0x89000000
[17:05:57.254]          : Fn 0000553 T1 0000 T2 07 T3 43 Time 00 00 05 436
[17:05:57.289]          : Detected event: 0x99009000
[17:05:57.291]   SIM 01 : (sim_SelectApplicationReq)
[17:05:57.291]          : Fn 0000561 T1 0000 T2 15 T3 00 Time 00 00 05 475
[17:05:57.294]   SIM 07 : Command successful: '3' retries remaining
[17:05:57.294]          : Fn 0000562 T1 0000 T2 16 T3 01 Time 00 00 05 478
[17:05:57.324]          : Detected event: 0x89110001
[17:05:57.340]          : Detected event: 0x89110005
[17:05:57.340]          : Detected event: 0x8911116b
[17:05:57.340]          : Detected event: 0x89019000
[17:05:57.343]          : Detected event: 0x89110009
[17:05:57.343]          : Detected event: 0x8811556b
[17:05:57.384]          : Detected event: 0x89110005
[17:05:57.384]          : Detected event: 0x89111139
[17:05:57.384]          : Detected event: 0x89019000
[17:05:57.384]          : Detected event: 0x89110006
[17:05:57.384]          : Detected event: 0x89112239
[17:05:57.477]   SIM 07 : Command successful: '3' retries remaining
[17:05:57.787]          : Detected event: 0x89300100
[17:05:57.802]          : Detected event: 0x8930064d
[17:05:57.821]          : Detected event: 0x89300568
[17:05:57.824]          : Detected event: 0x89300668
[17:05:57.842]          : Detected event: 0x89300542
[17:05:57.845]          : Detected event: 0x89300642
[17:05:57.862]          : Detected event: 0x89300557
[17:05:57.864]          : Detected event: 0x89300657
[17:05:57.884]          : Detected event: 0x89300544
[17:05:57.888]          : Fn 0000690 T1 0000 T2 14 T3 27 Time 00 00 06 070
[17:05:57.888]   SIM 07 : File not found
[17:05:57.888]          : Detected event: 0x89300553
[17:05:57.904]          : Detected event: 0x89300507
[17:05:57.923]          : Detected event: 0x89300550
[17:05:57.943]          : Detected event: 0x89300548
[17:05:57.953]          : Detected event: 0x8930056b
[17:05:57.970]          : Detected event: 0x89300563
[17:05:57.987]          : Detected event: 0x8930053b
[17:05:58.017]          : Detected event: 0x89300594
[17:05:58.027]          : Detected event: 0x89300994
[17:05:58.027]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:05:58.027]          : Fn 0000720 T1 0000 T2 18 T3 06 Time 00 00 06 209
[17:05:58.027]   SIM 01 : (sim_SetUsimPbkReq)
[17:05:58.052]          : Detected event: 0x89300597
[17:05:58.149]   SIM 01 : (Usim_DecodePbrRecord 0)length = 61, RecordNum = 1, flag = 0
[17:05:58.235]          : Detected event: 0x2244420a
[17:05:58.235]          : Detected event: 0x22446804
[17:05:58.278]          : Detected event: 0x23000042
[17:05:58.294]          : Detected event: 0x23000068
[17:05:58.324]          : Fn 0000783 T1 0000 T2 03 T3 18 Time 00 00 06 502
[17:05:58.324]   SIM 07 : File not found
[17:05:58.325]          : Detected event: 0x22445c00
[17:05:58.338]          : Detected event: 0x22445d2c
[17:05:58.354]          : Detected event: 0x23000061
[17:05:58.391]          : Detected event: 0x2300005d
[17:05:58.402]          : Detected event: 0x2300003b
[17:05:58.416]          : Detected event: 0x23000063
[17:05:58.466]          : Detected event: 0x2300008e
[17:05:58.466]          : Detected event: 0x23000062
[17:05:58.466]          : Detected event: 0x2300003f
[17:05:58.466]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:05:58.466]          : Fn 0000813 T1 0000 T2 07 T3 48 Time 00 00 06 638
[17:05:58.466]   SIM 07 : File not found
[17:05:58.466]          : Detected event: 0x22445f00
[17:05:58.554]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:05:58.700]          : Detected event: 0x5500000a
[17:05:59.982]          : Fn 0001144 T1 0000 T2 00 T3 22 Time 00 00 08 164
[17:05:59.983]   RRI 16 : FreqL for SI13 Ma is null, so store GMa
[17:05:59.983]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:05:59.984]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:05:59.984]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:05:59.984]   MM 09  : Dump : at 0x820994C9 (6/6) : 00 00 00 ff fe 00
[17:05:59.984]   MM 09  : Att 1, Service 0x82, NMO 2
[17:05:59.985]          : Fn 1673827 T1 1262 T2 25 T3 07 Time 00 00 08 168
[17:05:59.986]   PAL 12 : SynchChange[1954,131]
[17:06:00.091]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:00.478]          : Fn 1673933 T1 1262 T2 01 T3 11 Time 00 00 08 659
[17:06:00.591]   PAL 12 : SynchChange[625,357]
[17:06:01.009]          : Fn 1674047 T1 1262 T2 11 T3 23 Time 00 00 09 189
[17:06:01.121]   MMI 01 : system initialize complete
[17:06:01.733]          : Fn 1674205 T1 1262 T2 13 T3 28 Time 00 00 09 916
[17:06:01.733]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[17:06:01.733]   SIM 01 : (sim_RunUmtsAlgoReq)
[17:06:01.735]   SIM 01 : Dump : at 0x820C0302 (34/34) : 10 62 e6 96 74 26 87 e3 23 f6 ff 76 2a cb 8c ce 3b 10 74 1d e7 87 0e 6c 00 00 85 c0 5c 81 08 73 35 6a
[17:06:01.747]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:01.747]          : Fn 1674208 T1 1262 T2 16 T3 31 Time 00 00 09 931
[17:06:01.749]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:01.749]          : Detected event: 0xdd000002
[17:06:01.852]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:01.898]          : Fn 1674240 T1 1262 T2 22 T3 12 Time 00 00 10 080
[17:06:02.010]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:1
[17:06:03.478]          : Fn 1674582 T1 1262 T2 00 T3 48 Time 00 00 11 659
[17:06:03.604]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:2
[17:06:05.009]          : Fn 1674914 T1 1263 T2 20 T3 23 Time 00 00 13 191
[17:06:05.010]          : Detected event: 0x09290001
[17:06:05.011]   SIM 07 : f2 instruction recv data error,exceed max resend main ins times:2
[17:06:05.011]          : Fn 1674915 T1 1263 T2 21 T3 24 Time 00 00 13 193
[17:06:05.031]   SIM 01 : (sim_ResetReq)
[17:06:05.031]          : Fn 1674919 T1 1263 T2 25 T3 28 Time 00 00 13 213
[17:06:05.133]   SIM 01 : Reset Status: 0
[17:06:05.173]          : Fn 1674950 T1 1263 T2 04 T3 08 Time 00 00 13 356
[17:06:05.173]   SIM 01 : Reset Status: 1
[17:06:05.173]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[17:06:05.173]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[17:06:05.184]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[17:06:05.184]          : Fn 1674952 T1 1263 T2 06 T3 10 Time 00 00 13 367
[17:06:05.291]   SIM 01 : Reset Status: 3
[17:06:05.515]          : Detected event: 0x89000000
[17:06:05.515]          : Fn 1675021 T1 1263 T2 23 T3 28 Time 00 00 13 685
[17:06:05.539]          : Detected event: 0x99009000
[17:06:05.541]   SIM 01 : (sim_SelectApplicationReq)
[17:06:05.541]          : Fn 1675030 T1 1263 T2 06 T3 37 Time 00 00 13 723
[17:06:05.542]   SIM 07 : Command successful: '3' retries remaining
[17:06:05.543]   SIM 07 : Command successful: '3' retries remaining
[17:06:05.543]   CSW 01 : Send Sim Open to Stack[sim0]\n
[17:06:05.543]   API 01 : Saving SIM_OPEN_IND[0]
[17:06:05.544]   SIM 01 : (sim_RetryReq)
[17:06:05.544]          : Detected event: 0x1009cccc
[17:06:05.544]   SIM 01 : (sim_RunUmtsAlgoReq)
[17:06:05.545]          : Detected event: 0x89110001
[17:06:05.642]   SIM 01 : Dump : at 0x820C661A (34/34) : 10 62 e6 96 74 26 87 e3 23 f6 ff 76 2a cb 8c ce 3b 10 74 1d e7 87 0e 6c 00 00 85 c0 5c 81 08 73 35 6a
[17:06:05.642]          : Fn 1675052 T1 1263 T2 02 T3 08 Time 00 00 13 826
[17:06:05.660]          : Detected event: 0x89110005
[17:06:05.660]          : Detected event: 0x8911116b
[17:06:05.660]          : Detected event: 0x89019000
[17:06:05.739]          : Detected event: 0x89110009
[17:06:05.739]          : Detected event: 0x8811556b
[17:06:05.739]          : Detected event: 0x89110005
[17:06:05.739]          : Detected event: 0x89111139
[17:06:05.739]          : Detected event: 0x89019000
[17:06:05.739]          : Detected event: 0x89110006
[17:06:05.739]          : Detected event: 0x89112239
[17:06:05.838]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[17:06:06.100]          : Detected event: 0x89300100
[17:06:06.114]          : Detected event: 0x8930064d
[17:06:06.137]          : Detected event: 0x89300568
[17:06:06.137]          : Detected event: 0x89300668
[17:06:06.156]          : Detected event: 0x89300542
[17:06:06.160]          : Detected event: 0x89300642
[17:06:06.175]          : Detected event: 0x89300557
[17:06:06.180]          : Detected event: 0x89300657
[17:06:06.198]          : Detected event: 0x89300544
[17:06:06.203]          : Fn 1675173 T1 1263 T2 19 T3 27 Time 00 00 14 384
[17:06:06.203]   SIM 07 : File not found
[17:06:06.203]          : Detected event: 0x89300553
[17:06:06.219]          : Detected event: 0x89300507
[17:06:06.238]          : Detected event: 0x89300550
[17:06:06.259]          : Detected event: 0x89300548
[17:06:06.268]          : Detected event: 0x8930056b
[17:06:06.285]          : Detected event: 0x89300563
[17:06:06.304]          : Detected event: 0x8930053b
[17:06:06.332]          : Detected event: 0x89300594
[17:06:06.341]          : Detected event: 0x89300994
[17:06:06.341]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:06.342]          : Fn 1675189 T1 1263 T2 09 T3 43 Time 00 00 14 523
[17:06:06.342]   SIM 01 : (sim_SetUsimPbkReq)
[17:06:06.364]          : Detected event: 0x89300597
[17:06:06.466]   SIM 01 : (Usim_DecodePbrRecord 0)length = 61, RecordNum = 1, flag = 0
[17:06:08.091]          : Fn 1675582 T1 1263 T2 12 T3 28 Time 00 00 16 275
[17:06:08.092]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:08.092]          : Fn 1675584 T1 1263 T2 14 T3 30 Time 00 00 16 275
[17:06:08.093]   PAL 12 : SynchChange[4375,132]
[17:06:08.096]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:08.096]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[17:06:08.097]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:06:08.097]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:08.097]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:08.097]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:08.098]   MM 09  : Att 1, Service 0x82, NMO 2
[17:06:08.098]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:08.098]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:06:08.099]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:08.099]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:08.099]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:08.099]   MM 09  : Att 1, Service 0x02, NMO 2
[17:06:08.100]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:08.100]          : Fn 1675585 T1 1263 T2 15 T3 31 Time 00 00 16 284
[17:06:08.101]   PAL 12 : SynchChange[0,134]
[17:06:08.103]   MMI 02 : network register success
[17:06:08.217]   MMI 01 : DIALING
[17:06:08.273]          : Fn 1675622 T1 1263 T2 00 T3 17 Time 00 00 16 455
[17:06:08.388]   PAL 12 : SynchChange[0,1076]
[17:06:09.041]          : Fn 1675788 T1 1263 T2 10 T3 30 Time 00 00 17 225
[17:06:09.041]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:09.042]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:09.042]          : Fn 1675789 T1 1263 T2 11 T3 31 Time 00 00 17 225
[17:06:09.043]   PAL 12 : SynchChange[0,133]
[17:06:09.043]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[17:06:09.044]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:09.044]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:09.044]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:09.044]   MM 09  : Att 1, Service 0x02, NMO 2
[17:06:09.045]   API 01 : api_SimCloseCnf
[17:06:09.045]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[17:06:09.045]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:09.045]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:09.046]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:09.046]   MM 09  : Att 1, Service 0x01, NMO 2
[17:06:09.046]   API 01 : Send saved SIM_OPEN_IND[0]
[17:06:09.047]   API 01 : api_SimCloseCnf
[17:06:09.047]          : Fn 1675790 T1 1263 T2 12 T3 32 Time 00 00 17 231
[17:06:09.047]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:09.059]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:09.059]          : Detected event: 0xdd000002
[17:06:09.059]          : Fn 1675791 T1 1263 T2 13 T3 33 Time 00 00 17 242
[17:06:09.062]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:09.065]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:09.070]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:09.070]          : Detected event: 0x22446800
[17:06:09.072]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:09.076]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:09.076]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:09.076]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:09.078]   MM 01  : Unexpected status from SIM Sw1 : 0xe0, Sw2 : 0xff, SimFile 99\n
[17:06:09.078]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[17:06:09.078]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:09.078]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:09.085]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:09.085]   MM 09  : Att 1, Service 0x01, NMO 2
[17:06:09.085]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:09.085]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:09.179]   API 01 : api_SimCloseCnf
[17:06:10.673]          : Fn 1676121 T1 1264 T2 05 T3 06 Time 00 00 18 856
[17:06:10.776]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:1
[17:06:12.206]          : Fn 1676390 T1 1264 T2 14 T3 20 Time 00 00 20 388
[17:06:12.309]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:2
[17:06:13.737]          : Fn 1676806 T1 1264 T2 14 T3 28 Time 00 00 21 920
[17:06:13.738]          : Detected event: 0x09290001
[17:06:13.738]   SIM 07 : f2 instruction recv data error,exceed max resend main ins times:2
[17:06:13.761]   SIM 01 : (sim_ResetReq)
[17:06:13.761]          : Fn 1676810 T1 1264 T2 18 T3 32 Time 00 00 21 941
[17:06:13.868]   SIM 01 : Reset Status: 0
[17:06:13.900]          : Fn 1676821 T1 1264 T2 03 T3 43 Time 00 00 22 083
[17:06:13.900]   SIM 01 : Reset Status: 1
[17:06:13.901]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[17:06:13.901]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[17:06:13.914]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[17:06:14.025]   SIM 01 : Reset Status: 3
[17:06:14.255]          : Detected event: 0x89000000
[17:06:14.255]          : Fn 1676900 T1 1264 T2 04 T3 20 Time 00 00 22 412
[17:06:14.266]          : Detected event: 0x99009000
[17:06:14.266]   SIM 01 : (sim_SelectApplicationReq)
[17:06:14.277]   SIM 07 : Command successful: '3' retries remaining
[17:06:14.277]   SIM 07 : Command successful: '3' retries remaining
[17:06:14.277]          : Detected event: 0x1009cccc
[17:06:14.277]   CSW 01 : Send Sim Open to Stack[sim0]\n
[17:06:14.277]          : Detected event: 0x89110001
[17:06:14.437]          : Detected event: 0x2244420a
[17:06:14.454]          : Detected event: 0x22446804
[17:06:14.490]          : Detected event: 0x2244420a
[17:06:14.501]          : Detected event: 0x89110005
[17:06:14.501]          : Detected event: 0x8911116b
[17:06:14.501]          : Detected event: 0x89019000
[17:06:14.511]          : Detected event: 0x23000042
[17:06:14.526]          : Detected event: 0x23000068
[17:06:14.545]          : Detected event: 0x23000042
[17:06:14.565]          : Detected event: 0x89110009
[17:06:14.565]          : Detected event: 0x8811556b
[17:06:14.565]   SIM 01 : (sim_RetryReq)
[17:06:14.565]   SIM 07 : File not found
[17:06:14.565]          : Detected event: 0x22445c00
[17:06:14.579]          : Detected event: 0x22445d2c
[17:06:14.590]          : Detected event: 0x23000061
[17:06:14.608]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:14.609]   SIM 07 : File not found
[17:06:14.609]          : Detected event: 0x22445c00
[17:06:14.628]          : Detected event: 0x22445d2c
[17:06:14.648]          : Detected event: 0x89110005
[17:06:14.648]          : Detected event: 0x89111139
[17:06:14.648]          : Detected event: 0x89019000
[17:06:14.669]          : Detected event: 0x2300005d
[17:06:14.673]          : Detected event: 0x2300003b
[17:06:14.689]          : Detected event: 0x23000063
[17:06:14.723]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:14.723]          : Fn 1677014 T1 1264 T2 14 T3 32 Time 00 00 22 879
[17:06:14.723]          : Detected event: 0x2300008e
[17:06:14.723]          : Detected event: 0x23000062
[17:06:14.723]          : Detected event: 0x2300003f
[17:06:14.727]   PAL 12 : SynchChange[0,131]
[17:06:14.727]          : Fn 1677016 T1 1264 T2 16 T3 34 Time 00 00 22 908
[17:06:14.728]   SIM 07 : File not found
[17:06:14.728]          : Detected event: 0x22445f00
[17:06:14.760]          : Detected event: 0x2300005d
[17:06:14.777]          : Detected event: 0x89110006
[17:06:14.777]          : Detected event: 0x89112239
[17:06:14.885]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:15.143]          : Detected event: 0x89300100
[17:06:15.171]          : Detected event: 0x8930064d
[17:06:15.183]          : Fn 1677104 T1 1264 T2 00 T3 20 Time 00 00 23 366
[17:06:15.183]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x83,Wanted 1
[17:06:15.184]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:06:15.184]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:15.184]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:15.184]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:15.185]   MM 09  : Att 1, Service 0x02, NMO 2
[17:06:15.185]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:15.185]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:06:15.186]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:15.186]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:15.186]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:15.186]   MM 09  : Att 1, Service 0x82, NMO 2
[17:06:15.200]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:15.200]          : Fn 1677122 T1 1264 T2 18 T3 38 Time 00 00 23 378
[17:06:15.200]          : Detected event: 0x89300568
[17:06:15.204]          : Detected event: 0x89300668
[17:06:15.221]          : Detected event: 0x89300542
[17:06:15.228]          : Detected event: 0x89300642
[17:06:15.247]          : Detected event: 0x89300557
[17:06:15.247]          : Detected event: 0x89300657
[17:06:15.263]          : Detected event: 0x89300544
[17:06:15.269]   PAL 12 : SynchChange[0,134]
[17:06:15.269]          : Fn 1677138 T1 1264 T2 08 T3 03 Time 00 00 23 453
[17:06:15.269]   SIM 07 : File not found
[17:06:15.270]          : Detected event: 0x89300553
[17:06:15.287]          : Detected event: 0x89300507
[17:06:15.306]          : Detected event: 0x89300550
[17:06:15.325]          : Detected event: 0x89300548
[17:06:15.344]          : Detected event: 0x8930056b
[17:06:15.372]          : Detected event: 0x89300563
[17:06:15.395]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:15.395]          : Fn 1677165 T1 1264 T2 09 T3 30 Time 00 00 23 578
[17:06:15.491]   SIM 07 : main instruction recv data error,resend main ins,resend times:1
[17:06:15.543]          : Fn 1677197 T1 1264 T2 15 T3 11 Time 00 00 23 724
[17:06:15.652]   PAL 12 : SynchChange[625,383]
[17:06:16.152]          : Fn 1677329 T1 1264 T2 17 T3 41 Time 00 00 24 335
[17:06:16.261]   SIM 07 : main instruction recv data error,resend main ins,resend times:2
[17:06:16.760]          : Fn 1677461 T1 1265 T2 19 T3 20 Time 00 00 24 944
[17:06:16.880]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[17:06:16.920]          : Fn 1677495 T1 1265 T2 01 T3 03 Time 00 00 25 104
[17:06:16.921]   SIM 07 : main instruction recv data error,exceed max resend main ins times:2
[17:06:16.921]   SIM 01 : (sim_RunUmtsAlgoReq)
[17:06:16.921]          : Detected event: 0x8930053b
[17:06:16.924]   SIM 01 : Dump : at 0x820C0B5A (34/34) : 10 93 bc 5d 0d 56 79 67 d1 32 3a df d7 5f a8 a0 14 10 19 a4 e0 88 4d 74 00 00 16 32 46 de ca 8d a7 b0
[17:06:16.924]          : Fn 1677496 T1 1265 T2 02 T3 04 Time 00 00 25 108
[17:06:16.924]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:16.928]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:16.928]          : Detected event: 0xdd000002
[17:06:16.928]          : Fn 1677497 T1 1265 T2 03 T3 05 Time 00 00 25 111
[17:06:17.043]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:18.523]          : Fn 1677833 T1 1265 T2 01 T3 35 Time 00 00 26 705
[17:06:18.643]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:1
[17:06:20.059]          : Fn 1678174 T1 1265 T2 04 T3 19 Time 00 00 28 237
[17:06:20.172]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:2
[17:06:21.587]          : Fn 1678506 T1 1265 T2 24 T3 45 Time 00 00 29 769
[17:06:21.588]          : Detected event: 0x09290001
[17:06:21.588]   SIM 07 : f2 instruction recv data error,exceed max resend main ins times:2
[17:06:21.607]   SIM 01 : (sim_ResetReq)
[17:06:21.607]          : Fn 1678511 T1 1265 T2 03 T3 50 Time 00 00 29 790
[17:06:21.714]   SIM 01 : Reset Status: 0
[17:06:21.750]          : Fn 1678542 T1 1265 T2 08 T3 30 Time 00 00 29 932
[17:06:21.750]   SIM 01 : Reset Status: 1
[17:06:21.750]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[17:06:21.750]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[17:06:21.760]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[17:06:21.760]          : Fn 1678544 T1 1265 T2 10 T3 32 Time 00 00 29 944
[17:06:21.869]   SIM 01 : Reset Status: 3
[17:06:22.095]          : Detected event: 0x89000000
[17:06:22.095]          : Fn 1678613 T1 1265 T2 01 T3 50 Time 00 00 30 262
[17:06:22.117]          : Detected event: 0x99009000
[17:06:22.118]   SIM 01 : (sim_SelectApplicationReq)
[17:06:22.118]          : Fn 1678621 T1 1265 T2 09 T3 07 Time 00 00 30 300
[17:06:22.119]   SIM 07 : Command successful: '3' retries remaining
[17:06:22.119]          : Fn 1678622 T1 1265 T2 10 T3 08 Time 00 00 30 303
[17:06:22.120]   SIM 07 : Command successful: '3' retries remaining
[17:06:22.120]   CSW 01 : Send Sim Open to Stack[sim0]\n
[17:06:22.120]   API 01 : Saving SIM_OPEN_IND[0]
[17:06:22.120]   SIM 01 : (sim_RetryReq)
[17:06:22.120]          : Detected event: 0x1009cccc
[17:06:22.121]   SIM 01 : (sim_RunUmtsAlgoReq)
[17:06:22.121]          : Detected event: 0x89110001
[17:06:22.217]   SIM 01 : Dump : at 0x820C7AD6 (34/34) : 10 93 bc 5d 0d 56 79 67 d1 32 3a df d7 5f a8 a0 14 10 19 a4 e0 88 4d 74 00 00 16 32 46 de ca 8d a7 b0
[17:06:22.217]          : Fn 1678643 T1 1265 T2 05 T3 29 Time 00 00 30 401
[17:06:22.233]          : Detected event: 0x89110005
[17:06:22.233]          : Detected event: 0x8911116b
[17:06:22.233]          : Detected event: 0x89019000
[17:06:22.288]          : Detected event: 0x89110009
[17:06:22.288]          : Detected event: 0x8811556b
[17:06:22.325]          : Detected event: 0x89110005
[17:06:22.325]          : Detected event: 0x89111139
[17:06:22.325]          : Detected event: 0x89019000
[17:06:22.325]          : Detected event: 0x89110006
[17:06:22.325]          : Detected event: 0x89112239
[17:06:22.417]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[17:06:22.674]          : Detected event: 0x89300100
[17:06:22.690]          : Detected event: 0x8930064d
[17:06:22.709]          : Detected event: 0x89300568
[17:06:22.713]          : Detected event: 0x89300668
[17:06:22.731]          : Detected event: 0x89300542
[17:06:22.734]          : Detected event: 0x89300642
[17:06:22.749]          : Detected event: 0x89300557
[17:06:22.753]          : Detected event: 0x89300657
[17:06:22.771]          : Detected event: 0x89300544
[17:06:22.781]          : Fn 1678757 T1 1266 T2 15 T3 41 Time 00 00 30 959
[17:06:22.781]   SIM 07 : File not found
[17:06:22.781]          : Detected event: 0x89300553
[17:06:22.792]          : Detected event: 0x89300507
[17:06:22.810]          : Detected event: 0x89300550
[17:06:22.832]          : Detected event: 0x89300548
[17:06:22.841]          : Detected event: 0x8930056b
[17:06:22.858]          : Detected event: 0x89300563
[17:06:22.897]          : Detected event: 0x8930053b
[17:06:22.897]          : Detected event: 0x89300594
[17:06:22.922]          : Detected event: 0x89300994
[17:06:22.922]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:22.922]          : Fn 1678794 T1 1266 T2 00 T3 27 Time 00 00 31 098
[17:06:22.922]   SIM 01 : (sim_SetUsimPbkReq)
[17:06:22.934]          : Detected event: 0x89300597
[17:06:23.038]   SIM 01 : (Usim_DecodePbrRecord 0)length = 61, RecordNum = 1, flag = 0
[17:06:24.769]          : Fn 1679195 T1 1266 T2 11 T3 20 Time 00 00 32 947
[17:06:24.872]   LAP 05 : sim0 sapi0 lap_FSM WARNG: Evt 0x48006 unknown in lap_ReleaseDiscSent\n\n
[17:06:25.003]          : Fn 1679246 T1 1266 T2 10 T3 20 Time 00 00 33 186
[17:06:25.003]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:25.003]          : Fn 1679248 T1 1266 T2 12 T3 22 Time 00 00 33 186
[17:06:25.005]   PAL 12 : SynchChange[4375,130]
[17:06:25.009]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:25.009]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[17:06:25.009]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:06:25.009]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:25.009]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:25.009]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:25.009]   MM 09  : Att 1, Service 0x82, NMO 2
[17:06:25.009]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:25.009]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[17:06:25.009]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:25.010]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:25.010]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:25.010]   MM 09  : Att 1, Service 0x02, NMO 2
[17:06:25.011]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[17:06:25.011]   CC 10  : MMErrorCount 2,Tic 0x439fe, bRejForSimKo 0x1
[17:06:25.011]          : Fn 1679249 T1 1266 T2 13 T3 23 Time 00 00 33 195
[17:06:25.013]          : Detected event: 0xca110000
[17:06:25.014]   PAL 12 : SynchChange[0,120]
[17:06:25.117]   MMI 02 : network register success
[17:06:25.191]          : Fn 1679288 T1 1266 T2 00 T3 11 Time 00 00 33 375
[17:06:25.291]   PAL 12 : SynchChange[625,371]
[17:06:25.963]          : Fn 1679454 T1 1266 T2 10 T3 24 Time 00 00 34 145
[17:06:25.963]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:25.963]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:25.963]          : Fn 1679456 T1 1266 T2 12 T3 26 Time 00 00 34 146
[17:06:25.964]   PAL 12 : SynchChange[4375,129]
[17:06:25.964]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[17:06:25.964]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:25.964]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:25.965]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:25.965]   MM 09  : Att 1, Service 0x02, NMO 2
[17:06:25.965]   API 01 : api_SimCloseCnf
[17:06:25.965]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[17:06:25.965]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:25.966]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:25.966]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:25.966]   MM 09  : Att 1, Service 0x01, NMO 2
[17:06:25.966]   API 01 : Send saved SIM_OPEN_IND[0]
[17:06:25.968]   API 01 : api_SimCloseCnf
[17:06:25.968]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:25.981]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:25.981]          : Detected event: 0xdd000002
[17:06:25.981]          : Fn 1679458 T1 1266 T2 14 T3 28 Time 00 00 34 163
[17:06:25.984]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:25.984]          : Fn 1679459 T1 1266 T2 15 T3 29 Time 00 00 34 166
[17:06:25.985]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:25.985]          : Fn 1679460 T1 1266 T2 16 T3 30 Time 00 00 34 169
[17:06:25.992]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:25.992]          : Fn 1679461 T1 1266 T2 17 T3 31 Time 00 00 34 173
[17:06:25.992]          : Detected event: 0x22446800
[17:06:25.992]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:25.993]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[17:06:26.003]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[17:06:26.003]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[17:06:26.003]   MM 01  : Unexpected status from SIM Sw1 : 0xe0, Sw2 : 0xff, SimFile 99\n
[17:06:26.003]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[17:06:26.003]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 04
[17:06:26.003]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[17:06:26.003]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[17:06:26.003]   MM 09  : Att 1, Service 0x01, NMO 2
[17:06:26.003]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:26.003]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[17:06:26.105]   API 01 : api_SimCloseCnf
[17:06:27.591]          : Fn 1679793 T1 1266 T2 11 T3 06 Time 00 00 35 775
[17:06:27.699]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:1
[17:06:29.124]          : Fn 1680062 T1 1267 T2 20 T3 20 Time 00 00 37 307
[17:06:29.230]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:2
[17:06:30.656]          : Fn 1680470 T1 1267 T2 12 T3 20 Time 00 00 38 838
[17:06:30.656]          : Detected event: 0x09290001
[17:06:30.656]   SIM 07 : f2 instruction recv data error,exceed max resend main ins times:2
[17:06:30.675]   SIM 01 : (sim_ResetReq)
[17:06:30.773]   SIM 01 : Reset Status: 0
[17:06:30.825]   SIM 01 : Reset Status: 1
[17:06:30.825]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[17:06:30.825]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[17:06:30.830]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[17:06:30.933]   SIM 01 : Reset Status: 3
[17:06:31.173]          : Detected event: 0x89000000
[17:06:31.173]          : Fn 1680572 T1 1267 T2 10 T3 20 Time 00 00 39 332
[17:06:31.185]          : Detected event: 0x99009000
[17:06:31.186]   SIM 01 : (sim_SelectApplicationReq)
[17:06:31.189]   SIM 07 : Command successful: '3' retries remaining
[17:06:31.190]   SIM 07 : Command successful: '3' retries remaining
[17:06:31.190]          : Detected event: 0x1009cccc
[17:06:31.190]          : Detected event: 0x89110001
[17:06:31.288]   CSW 01 : Send Sim Open to Stack[sim0]\n
[17:06:31.357]          : Detected event: 0x2244420a
[17:06:31.394]          : Detected event: 0x22446804
[17:06:31.404]          : Detected event: 0x2244420a
[17:06:31.425]          : Detected event: 0x89110005
[17:06:31.425]          : Detected event: 0x8911116b
[17:06:31.425]          : Detected event: 0x89019000
[17:06:31.431]          : Detected event: 0x23000042
[17:06:31.449]          : Detected event: 0x23000068
[17:06:31.466]          : Detected event: 0x23000042
[17:06:31.477]          : Detected event: 0x89110009
[17:06:31.477]          : Detected event: 0x8811556b
[17:06:31.488]   SIM 01 : (sim_RetryReq)
[17:06:31.488]   SIM 07 : File not found
[17:06:31.488]          : Detected event: 0x22445c00
[17:06:31.497]          : Detected event: 0x22445d2c
[17:06:31.497]          : Detected event: 0x9db10000
[17:06:31.497]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[17:06:31.497]          : Detected event: 0x9db00000
[17:06:31.497]   SXR 01 : Memory deletion (880cdda1y) 820beb30 offset 12\n
[17:06:31.498]   SXR 01 : Stack found for Task\n
[17:06:31.498]   SXR 01 : Task  4: RR Priority  25
[17:06:31.498]   SXR 01 : is active.\n
[17:06:31.499]   SXR 01 : Stack 764/4096 Top 0x820b3b78 <- 0x820b4b78\n
[17:06:31.500]   SXR 01 : Back trace\n
[17:06:31.500]   SXR 01 : 81c073a1y\n
[17:06:31.500]   SXR 01 : 81c073a1y\n
[17:06:31.500]   SXR 01 : 880cdda1y\n
[17:06:31.500]   SXR 01 : 880cdd4fy\n
[17:06:31.508]   SXR 01 : 880cdd4fy\n
[17:06:31.508]   SXR 01 : 880cc499y\n
[17:06:31.508]   SXR 01 : 880cc5c9y\n
[17:06:31.508]   SXR 01 : 881ca364y\n
[17:06:31.508]   SXR 01 : 880cc549y\n
[17:06:31.508]   SXR 01 : 8200be8dy\n
[17:06:31.508]   SXR 01 : 881de264y\n
[17:06:31.508]   SXR 01 : 880cc721y\n
[17:06:31.508]   SXR 01 : 881de118y\n
[17:06:31.508]   SXR 01 : 881de118y\n
[17:06:31.508]   SXR 01 : 880763edy\n
[17:06:31.508]   SXR 01 : 88078abdy\n
[17:06:31.508]   SXR 01 : 880763c5y\n
[17:06:31.508]   SXR 01 : 8201068dy\n
[17:06:31.508]   SXR 01 : 88071ad3y\n
[17:06:31.508]   SXR 01 : 8200d0f1y\n
[17:06:31.622]   SXR 01 : 8200be0by\n

What is possibly wrong?

Thanks

edit: trying to use GDB.

on opening I am getting window: "skipping .debug_frame into of ...elf file: found an fde when not expecting it"

then i see output of sxs_Raise instead my source.

console output

(gdb) bt
#0  0x8200e689 in sxs_Raise ()
#1  0x81c073a1 in _sxr_HFree ()
#2  0x880cdd4f in rr_ReadSimFileSt ()
#3  0x880cc499 in rr_ProcessEvt ()
#4  0x880cc5c9 in rr_Parse ()
#5  0x880cc721 in rr_ParseMsg ()
#6  0x880763ed in sxs_Task ()
#7  0x880763c5 in sxs_TimerDelivery ()
Backtrace stopped: frame did not save the PC

(gdb) bt f
#0  0x8200e689 in sxs_Raise ()
No symbol table info available.
#1  0x81c073a1 in _sxr_HFree ()
No symbol table info available.
#2  0x880cdd4f in rr_ReadSimFileSt ()
No symbol table info available.
#3  0x880cc499 in rr_ProcessEvt ()
No symbol table info available.
#4  0x880cc5c9 in rr_Parse ()
No symbol table info available.
#5  0x880cc721 in rr_ParseMsg ()
No symbol table info available.
#6  0x880763ed in sxs_Task ()
No symbol table info available.
#7  0x880763c5 in sxs_TimerDelivery ()
No symbol table info available.
Backtrace stopped: frame did not save the PC

(gdb) 

glararan avatar Feb 05 '21 16:02 glararan

So i made little bit development.

Im afraid its circuit powering issue.

Used demo/call with following changes (to repeat call)

#include "stdbool.h"
#include "stdint.h"
#include "stdio.h"
#include "string.h"

#include "api_os.h"
#include "api_debug.h"
#include "api_event.h"
#include "api_call.h"
#include "api_audio.h"

#include "demo_call.h"

#define MAIN_TASK_STACK_SIZE    (2048 * 2)
#define MAIN_TASK_PRIORITY      0
#define MAIN_TASK_NAME          "Main Test Task"

#define SECOND_TASK_STACK_SIZE    (2048 * 2)
#define SECOND_TASK_PRIORITY      1
#define SECOND_TASK_NAME          "Second Test Task"

static HANDLE mainTaskHandle = NULL;
static HANDLE secondTaskHandle = NULL;

uint8_t flag = 0;
bool isDialSuccess = false;

void EventDispatch(API_Event_t* pEvent)
{
    switch(pEvent->id)
    {
        case API_EVENT_ID_NO_SIMCARD:
            Trace(10,"!!NO SIM CARD%d!!!!",pEvent->param1);
            break;

        case API_EVENT_ID_SYSTEM_READY:
            Trace(1,"system initialize complete");
            break;

        case API_EVENT_ID_NETWORK_REGISTERED_HOME:
        case API_EVENT_ID_NETWORK_REGISTERED_ROAMING:
            Trace(1,"network register success");
            flag = 1;
            break;
        case API_EVENT_ID_CALL_DIAL://param1: isSuccess, param2:error code(CALL_Error_t)
            Trace(1,"Is dial success:%d, error code:%d",pEvent->param1,pEvent->param2);
            if(pEvent->param1)
                isDialSuccess = true;
            break;
        case API_EVENT_ID_CALL_HANGUP:  //param1: is remote release call, param2:error code(CALL_Error_t)
            Trace(1,"Hang up,is remote hang up:%d, error code:%d",pEvent->param1,pEvent->param2);
            break;
        case API_EVENT_ID_CALL_INCOMING:   //param1: number type, pParam1:number
            Trace(1,"Receive a call, number:%s, number type:%d",pEvent->pParam1,pEvent->param1);
            OS_Sleep(5000);
            if(!CALL_Answer())
                Trace(1,"answer fail");
            break;
        case API_EVENT_ID_CALL_ANSWER  :  
            Trace(1,"answer success");
            break;
        case API_EVENT_ID_CALL_DTMF    :  //param1: key
            Trace(1,"received DTMF tone:%c",pEvent->param1);
            break;
        default:
            break;
    }
}

void CallTest()
{
    //initialize
    Trace(1,"start Call test, initialize audio first");

    //make a call
    Trace(1,"start make a call");
    if(!CALL_Dial(DIAL_NUMBER))
    {
        Trace(1,"make a call failed");
        return;
    }
    while(!isDialSuccess)
        OS_Sleep(100);

    Trace(1,"sleep start");
    OS_Sleep(10000);
    Trace(1,"sleep end");
    if(!CALL_HangUp())
        Trace(1,"hang up fail");
}

void SecondTask(void *pData)
{
    while(1)
    {
        if(flag == 1)
        {
            CallTest();
        }
        OS_Sleep(100);
    }
}

void MainTask(void *pData)
{
    API_Event_t* event=NULL;
    flag = 0;
    isDialSuccess = false;

    secondTaskHandle = OS_CreateTask(SecondTask,
        NULL, NULL, SECOND_TASK_STACK_SIZE, SECOND_TASK_PRIORITY, 0, 0, SECOND_TASK_NAME);

    while(1)
    {
        if(OS_WaitEvent(mainTaskHandle, (void**)&event, OS_TIME_OUT_WAIT_FOREVER))
        {
            EventDispatch(event);
            OS_Free(event->pParam1);
            OS_Free(event->pParam2);
            OS_Free(event);
        }
    }
}

void call_Main(void)
{
    mainTaskHandle = OS_CreateTask(MainTask,
        NULL, NULL, MAIN_TASK_STACK_SIZE, MAIN_TASK_PRIORITY, 0, 0, MAIN_TASK_NAME);
    OS_SetUserMainHandle(&mainTaskHandle);    
}

Program never reach following part.. however he successfully dial once!

        case API_EVENT_ID_CALL_DIAL://param1: isSuccess, param2:error code(CALL_Error_t)
            Trace(1,"Is dial success:%d, error code:%d",pEvent->param1,pEvent->param2);
            if(pEvent->param1)
                isDialSuccess = true;
            break;

Log:

[12:03:17.357]          : Detected event: 0xffffffff
[12:03:17.449]          : Detected event: 0xf07a0000
[12:03:17.489]          : Detected event: 0xf07a0002
[12:03:17.489]          : Detected event: 0x89550002
[12:03:17.499]          : Detected event: 0xfe900800
[12:03:17.499]          : Detected event: 0xfe901b00
[12:03:17.519]          : Detected event: 0x00000004
[12:03:17.520]          : Detected event: 0x66660021
[12:03:17.520]          : Detected event: 0x0000000f
[12:03:17.520]          : Detected event: 0x00000000
[12:03:17.527]          : Detected event: 0x001660c8
[12:03:17.614]          : Detected event: 0x00000057
[12:03:17.619]          : Detected event: 0xca1bc2c0
[12:03:17.619]          : Detected event: 0x9999a244
[12:03:17.623]          : Detected event: 0x77770006
[12:03:17.742]          : SXS_INIT_RMC
[12:03:19.088]          : Detected event: 0xffffff00
[12:03:19.091]          : Detected event: 0xb0070000
[12:03:19.173]   SXR 01 : Allocated Tasks 19 Schedule Disable 1\n
[12:03:19.173]   SXR 01 : Task  0: Task Idle Priority 255
[12:03:19.173]   SXR 01 : is pending.\n
[12:03:19.174]   SXR 01 : Stack  32/8192 Top 0x820b0ed8 <- 0x820b2ed8\n
[12:03:19.174]   SXR 01 : Task  1: Layer 1 Priority   1
[12:03:19.175]   SXR 01 : is pending.\n
[12:03:19.175]   SXR 01 : Stack  32/1536 Top 0x820b2ef0 <- 0x820b34f0\n
[12:03:19.175]   SXR 01 : Task  2: Sim Priority  15
[12:03:19.176]   SXR 01 : is pending.\n
[12:03:19.176]   SXR 01 : Stack  32/800 Top 0x820b3508 <- 0x820b3828\n
[12:03:19.176]   SXR 01 : Task  3: LAPDm Priority  21
[12:03:19.176]   SXR 01 : is pending.\n
[12:03:19.177]   SXR 01 : Stack  32/800 Top 0x820b3840 <- 0x820b3b60\n
[12:03:19.177]   SXR 01 : Task  4: RR Priority  25
[12:03:19.177]   SXR 01 : is pending.\n
[12:03:19.178]   SXR 01 : Stack  32/4096 Top 0x820b3b78 <- 0x820b4b78\n
[12:03:19.178]   SXR 01 : Task  5: RLD Priority  26
[12:03:19.178]   SXR 01 : is pending.\n
[12:03:19.179]   SXR 01 : Stack  32/800 Top 0x820b4b90 <- 0x820b4eb0\n
[12:03:19.179]   SXR 01 : Task  6: RLU Priority  27
[12:03:19.181]   SXR 01 : is pending.\n
[12:03:19.182]   SXR 01 : Stack  32/1536 Top 0x820b4ec8 <- 0x820b54c8\n
[12:03:19.182]   SXR 01 : Task  7: LLC Priority  29
[12:03:19.182]   SXR 01 : is pending.\n
[12:03:19.183]   SXR 01 : Stack  32/1024 Top 0x820b54e0 <- 0x820b58e0\n
[12:03:19.183]   SXR 01 : Task  8: MM Priority  30
[12:03:19.183]   SXR 01 : is pending.\n
[12:03:19.183]   SXR 01 : Stack  32/2000 Top 0x820b58f8 <- 0x820b60c8\n
[12:03:19.183]   SXR 01 : Task  9: CC Priority  35
[12:03:19.183]   SXR 01 : is pending.\n
[12:03:19.183]   SXR 01 : Stack  32/1536 Top 0x820b60e0 <- 0x820b66e0\n
[12:03:19.183]   SXR 01 : Task 10: SS Priority  40
[12:03:19.183]   SXR 01 : is pending.\n
[12:03:19.183]   SXR 01 : Stack  32/1024 Top 0x820b66f8 <- 0x820b6af8\n
[12:03:19.184]   SXR 01 : Task 11: SMS Priority  45
[12:03:19.184]   SXR 01 : is pending.\n
[12:03:19.184]   SXR 01 : Stack  32/1024 Top 0x820b6b10 <- 0x820b6f10\n
[12:03:19.185]   SXR 01 : Task 12: CB Priority  50
[12:03:19.185]   SXR 01 : is pending.\n
[12:03:19.185]   SXR 01 : Stack  32/1024 Top 0x820b6f28 <- 0x820b7328\n
[12:03:19.186]   SXR 01 : Task 13: SM Priority 120
[12:03:19.186]   SXR 01 : is pending.\n
[12:03:19.186]   SXR 01 : Stack  32/1024 Top 0x820b7340 <- 0x820b7740\n
[12:03:19.187]   SXR 01 : Task 14: SNDCP Priority 125
[12:03:19.187]   SXR 01 : is pending.\n
[12:03:19.187]   SXR 01 : Stack  32/1024 Top 0x820b7758 <- 0x820b7b58\n
[12:03:19.188]   SXR 01 : Task 15: API Priority 150
[12:03:19.188]   SXR 01 : is pending.\n
[12:03:19.189]   SXR 01 : Stack  32/1024 Top 0x820b7b70 <- 0x820b7f70\n
[12:03:19.189]   SXR 01 : Task 16: CFW Priority 201
[12:03:19.189]   SXR 01 : is pending.\n
[12:03:19.189]   SXR 01 : Stack  32/20480 Top 0x820b7f88 <- 0x820bcf88\n
[12:03:19.190]   SXR 01 : Task 17: USR_INIT: BAL Init Task Priority   2
[12:03:19.190]   SXR 01 : is pending.\n
[12:03:19.191]   SXR 01 : Stack  32/3072 Top 0x820bcfa0 <- 0x820bdba0\n
[12:03:19.191]   SXR 01 : Task 18: USR_INIT: Decompress Task Priority   3
[12:03:19.192]   SXR 01 : is pending.\n
[12:03:19.192]   SXR 01 : Stack  32/1024 Top 0x820bdbb8 <- 0x820bdfb8\n
[12:03:19.197]       /I : FBDEV: scan min_erase_count/1 mismatch_count/0 mismatch/0
[12:03:19.197]       /I : SFFS mount, device block size/512 block count/749 cache count 4
[12:03:19.197]       /I : SFFS mount move count/0 block free/690
[12:03:19.197]   CSW 02 : Entry CFW_ShellControl\r\n
[12:03:19.197]   CSW 02 : CFW_CONTROL_CMD_POWER_ON\r\n
[12:03:19.198]   CSW 02 : CFW_Init invoked\n
[12:03:19.198]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x0,SimStatus 0x0,Wanted 1
[12:03:19.198]   SIM 01 : (sim_ResetReq)
[12:03:19.319]   SIM 01 : Reset Status: 0
[12:03:20.381]          : Fn 0000147 T1 0000 T2 17 T3 45 Time 00 00 03 160
[12:03:20.381]   SIM 01 : Reset Status: 1
[12:03:20.385]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:20.385]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:20.392]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:20.392]          : Fn 0000149 T1 0000 T2 19 T3 47 Time 00 00 03 171
[12:03:20.493]   SIM 01 : Reset Status: 3
[12:03:22.154]          : Detected event: 0x6c67f234
[12:03:22.252]          : Detected event: 0x89000000
[12:03:22.252]          : Fn 0000553 T1 0000 T2 07 T3 43 Time 00 00 05 033
[12:03:22.289]          : Detected event: 0x99009000
[12:03:22.291]   SIM 01 : (sim_SelectApplicationReq)
[12:03:22.291]          : Fn 0000561 T1 0000 T2 15 T3 00 Time 00 00 05 071
[12:03:22.293]   SIM 07 : Command successful: '3' retries remaining
[12:03:22.293]          : Fn 0000562 T1 0000 T2 16 T3 01 Time 00 00 05 074
[12:03:22.322]          : Detected event: 0x89110001
[12:03:22.339]          : Detected event: 0x89110005
[12:03:22.339]          : Detected event: 0x8911116b
[12:03:22.339]          : Detected event: 0x89019000
[12:03:22.345]          : Detected event: 0x89110009
[12:03:22.345]          : Detected event: 0x8811556b
[12:03:22.367]          : Detected event: 0x89110005
[12:03:22.367]          : Detected event: 0x89111139
[12:03:22.367]          : Detected event: 0x89019000
[12:03:22.367]          : Detected event: 0x89110006
[12:03:22.367]          : Detected event: 0x89112239
[12:03:22.478]   SIM 07 : Command successful: '3' retries remaining
[12:03:22.786]          : Detected event: 0x89300100
[12:03:22.800]          : Detected event: 0x8930064d
[12:03:22.817]          : Detected event: 0x89300568
[12:03:22.822]          : Detected event: 0x89300668
[12:03:22.838]          : Detected event: 0x89300542
[12:03:22.844]          : Detected event: 0x89300642
[12:03:22.859]          : Detected event: 0x89300557
[12:03:22.862]          : Detected event: 0x89300657
[12:03:22.878]          : Detected event: 0x89300544
[12:03:22.891]          : Fn 0000690 T1 0000 T2 14 T3 27 Time 00 00 05 666
[12:03:22.891]   SIM 07 : File not found
[12:03:22.891]          : Detected event: 0x89300553
[12:03:22.902]          : Detected event: 0x89300507
[12:03:22.920]          : Detected event: 0x89300550
[12:03:22.939]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[12:03:22.939]          : Fn 0000701 T1 0000 T2 25 T3 38 Time 00 00 05 719
[12:03:22.939]   MM 09  : Dump : at 0x8209953D (5/5) : 00 00 00 ff fe
[12:03:22.939]          : Detected event: 0x89300548
[12:03:22.939]   MM 09  : Dump : at 0x8209954E (6/6) : ff ff ff ff fe fe
[12:03:22.939]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 30 94 c5 50
[12:03:22.939]   MM 09  : Dump : at 0x820994C9 (6/6) : 00 00 00 ff fe 00
[12:03:22.940]   MM 09  : Att 1, Service 0x01, NMO 2
[12:03:22.940]          : Fn 1363339 T1 1028 T2 03 T3 07 Time 00 00 05 720
[12:03:22.959]          : Detected event: 0x8930056b
[12:03:22.973]          : Detected event: 0x89300563
[12:03:22.995]          : Detected event: 0x8930053b
[12:03:23.011]          : Detected event: 0x89300594
[12:03:23.027]          : Detected event: 0x89300994
[12:03:23.027]   PAL 12 : SynchChange[1479,128]
[12:03:23.027]          : Fn 1363348 T1 1028 T2 12 T3 16 Time 00 00 05 808
[12:03:23.027]   SIM 01 : (sim_SetUsimPbkReq)
[12:03:23.047]          : Detected event: 0x89300597
[12:03:23.151]   SIM 01 : (Usim_DecodePbrRecord 0)length = 61, RecordNum = 1, flag = 0
[12:03:23.214]          : Detected event: 0x2244420a
[12:03:23.232]          : Detected event: 0x22446804
[12:03:23.277]          : Detected event: 0x23000042
[12:03:23.291]          : Detected event: 0x23000068
[12:03:23.321]          : Fn 1363393 T1 1028 T2 05 T3 10 Time 00 00 06 100
[12:03:23.321]   SIM 07 : File not found
[12:03:23.322]          : Detected event: 0x22445c00
[12:03:23.344]          : Detected event: 0x22445d2c
[12:03:23.348]          : Detected event: 0x23000061
[12:03:23.392]          : Detected event: 0x2300005d
[12:03:23.404]          : Detected event: 0x2300003b
[12:03:23.426]          : Detected event: 0x23000063
[12:03:23.433]          : Detected event: 0x2300008e
[12:03:23.441]          : Detected event: 0x23000062
[12:03:23.456]          : Detected event: 0x2300003f
[12:03:23.457]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[12:03:23.457]          : Fn 1363450 T1 1028 T2 10 T3 16 Time 00 00 06 236
[12:03:23.457]   SIM 07 : File not found
[12:03:23.457]          : Detected event: 0x22445f00
[12:03:23.563]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[12:03:23.694]          : Detected event: 0x5500000a
[12:03:24.587]          : Fn 1363695 T1 1028 T2 21 T3 06 Time 00 00 07 365
[12:03:24.588]   RRI 16 : FreqL for SI13 Ma is null, so store GMa
[12:03:24.588]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:24.588]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:24.589]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:24.589]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 30 94 c5 50
[12:03:24.589]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:24.589]          : Fn 0118123 T1 0089 T2 05 T3 07 Time 00 00 07 369
[12:03:24.591]   PAL 12 : SynchChange[345,131]
[12:03:24.695]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:24.889]          : Fn 0118188 T1 0089 T2 18 T3 21 Time 00 00 07 670
[12:03:25.000]   PAL 12 : SynchChange[0,357]
[12:03:25.663]          : Fn 0118355 T1 0089 T2 03 T3 35 Time 00 00 08 442
[12:03:25.773]   MMI 01 : system initialize complete
[12:03:26.155]          : Fn 0118462 T1 0089 T2 06 T3 40 Time 00 00 08 935
[12:03:26.155]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[12:03:26.155]   SIM 01 : (sim_RunUmtsAlgoReq)
[12:03:26.157]   SIM 01 : Dump : at 0x820BFEFA (34/34) : 10 8d 82 4e 6a b6 ed 78 6a 41 cc b4 db 9f 48 cd a1 10 66 73 97 e1 2e d2 00 00 8c 32 b8 c6 86 8e 29 9b
[12:03:26.169]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[12:03:26.169]          : Fn 0118465 T1 0089 T2 09 T3 43 Time 00 00 08 950
[12:03:26.172]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[12:03:26.172]          : Detected event: 0xdd000002
[12:03:26.172]          : Fn 0118466 T1 0089 T2 10 T3 44 Time 00 00 08 953
[12:03:26.274]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[12:03:26.480]          : Fn 0118532 T1 0089 T2 24 T3 08 Time 00 00 09 260
[12:03:26.591]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:1
[12:03:26.692]          : Fn 0118577 T1 0089 T2 17 T3 02 Time 00 00 09 465
[12:03:26.789]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:2
[12:03:26.946]          : Fn 0118633 T1 0089 T2 21 T3 07 Time 00 00 09 726
[12:03:26.947]          : Detected event: 0x09290001
[12:03:26.947]   SIM 07 : f2 instruction recv data error,exceed max resend main ins times:2
[12:03:26.966]   SIM 01 : (sim_ResetReq)
[12:03:26.966]          : Fn 0118638 T1 0089 T2 00 T3 12 Time 00 00 09 747
[12:03:27.071]   SIM 01 : Reset Status: 0
[12:03:27.108]          : Fn 0118668 T1 0089 T2 04 T3 42 Time 00 00 09 889
[12:03:27.109]   SIM 01 : Reset Status: 1
[12:03:27.109]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:27.109]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:27.122]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:27.122]          : Fn 0118671 T1 0089 T2 07 T3 45 Time 00 00 09 901
[12:03:27.221]   SIM 01 : Reset Status: 3
[12:03:27.221]          : Fn 0118681 T1 0089 T2 17 T3 04 Time 00 00 10 001
[12:03:27.242]   SIM 01 : (sim_ResetReq)
[12:03:27.282]          : Detected event: 0x09290003
[12:03:27.383]   SIM 01 : Reset Status: 0
[12:03:27.383]          : Fn 0118728 T1 0089 T2 12 T3 00 Time 00 00 10 163
[12:03:27.496]   SIM 01 : Reset Status: 1
[12:03:29.270]          : Fn 0119137 T1 0089 T2 05 T3 01 Time 00 00 12 051
[12:03:29.368]   SIM 01 : Reset Status: 1
[12:03:31.167]          : Fn 0119548 T1 0090 T2 00 T3 04 Time 00 00 13 947
[12:03:31.280]   SIM 01 : Reset Status: 1
[12:03:33.067]          : Fn 0119959 T1 0090 T2 21 T3 07 Time 00 00 15 847
[12:03:33.171]   SIM 01 : Reset Status: 1
[12:03:34.409]          : Fn 0120250 T1 0090 T2 00 T3 43 Time 00 00 17 189
[12:03:34.514]   SIM 01 : Reset Status: 1
[12:03:35.645]          : Fn 0120517 T1 0090 T2 07 T3 04 Time 00 00 18 424
[12:03:35.645]   SIM 01 : Reset Status: 1
[12:03:35.645]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:35.645]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:35.656]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:35.759]   SIM 01 : Reset Status: 3
[12:03:35.931]          : Fn 0120578 T1 0090 T2 16 T3 14 Time 00 00 18 711
[12:03:35.964]   SIM 01 : (sim_DedicFileStatusReq) DF FILE: 1
[12:03:35.964]   CSW 01 : Send Sim Open to Stack[sim0]\n
[12:03:35.964]   API 01 : Saving SIM_OPEN_IND[0]
[12:03:35.964]   SIM 01 : (sim_RetryReq)
[12:03:35.964]   SIM 01 : (sim_RunUmtsAlgoReq)
[12:03:35.971]   SIM 01 : Dump : at 0x820C64BE (34/34) : 10 8d 82 4e 6a b6 ed 78 6a 41 cc b4 db 9f 48 cd a1 10 66 73 97 e1 2e d2 00 00 8c 32 b8 c6 86 8e 29 9b
[12:03:35.972]   SIM 07 : SELECT_DF instruction recv data error,exceed max resend main ins times:0
[12:03:35.974]   MM 08  : Unexpected status from SIM Sw1 : 0x94, Sw2 : 0x4\n
[12:03:35.977]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[12:03:35.978]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:35.979]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:35.979]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:35.979]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:35.979]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:35.979]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:35.979]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:35.979]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:35.979]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:35.979]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:35.980]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:35.980]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:35.980]   API 01 : api_SimCloseCnf
[12:03:35.980]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:35.980]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:35.980]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:35.981]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:35.981]   MM 09  : Att 1, Service 0x01, NMO 2
[12:03:35.981]          : Detected event: 0x09290001
[12:03:35.990]   API 01 : Send saved SIM_OPEN_IND[0]
[12:03:35.990]          : Fn 0120593 T1 0090 T2 05 T3 29 Time 00 00 18 770
[12:03:36.045]   PAL 12 : SynchChange[0,136]
[12:03:36.045]          : Fn 0120595 T1 0090 T2 07 T3 31 Time 00 00 18 824
[12:03:36.064]   SIM 01 : (sim_ResetReq)
[12:03:36.167]   SIM 01 : Reset Status: 0
[12:03:36.207]          : Fn 0120621 T1 0090 T2 07 T3 06 Time 00 00 18 987
[12:03:36.207]   SIM 01 : Reset Status: 1
[12:03:36.207]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:36.207]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:36.217]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:36.322]   SIM 01 : Reset Status: 3
[12:03:36.551]          : Fn 0120714 T1 0091 T2 22 T3 48 Time 00 00 19 331
[12:03:36.587]   SIM 01 : (sim_DedicFileStatusReq) DF FILE: 1
[12:03:36.587]          : Fn 0120718 T1 0091 T2 00 T3 01 Time 00 00 19 365
[12:03:36.587]   CSW 01 : Send Sim Open to Stack[sim0]\n
[12:03:36.652]   API 01 : Saving SIM_OPEN_IND[0]
[12:03:36.707]   SIM 01 : (sim_RetryReq)
[12:03:36.707]          : Fn 0120748 T1 0091 T2 04 T3 31 Time 00 00 19 485
[12:03:36.805]   PAL 12 : SynchChange[0,131]
[12:03:36.895]          : Fn 0120785 T1 0091 T2 15 T3 17 Time 00 00 19 674
[12:03:36.896]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x3,Wanted 1
[12:03:36.901]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:36.906]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:36.906]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:36.906]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:36.906]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:36.906]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:36.906]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:36.906]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:36.906]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:36.910]   API 01 : api_SimCloseCnf
[12:03:36.910]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:36.910]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:36.910]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:36.910]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:36.910]   MM 09  : Att 1, Service 0x01, NMO 2
[12:03:36.911]   API 01 : Send saved SIM_OPEN_IND[0]
[12:03:36.911]          : Fn 0120792 T1 0091 T2 22 T3 24 Time 00 00 19 688
[12:03:37.016]   PAL 12 : SynchChange[0,134]
[12:03:37.134]          : Fn 0120838 T1 0091 T2 16 T3 19 Time 00 00 19 900
[12:03:37.247]   PAL 12 : SynchChange[0,157]
[12:03:38.180]          : Fn 0121045 T1 0091 T2 15 T3 22 Time 00 00 20 959
[12:03:38.181]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x3,Wanted 1
[12:03:38.181]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:38.181]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:38.181]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:38.181]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:38.181]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:38.182]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:38.182]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:38.182]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:38.182]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:38.182]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:38.182]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:38.191]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:38.191]          : Fn 0121070 T1 0091 T2 14 T3 47 Time 00 00 20 971
[12:03:38.308]   PAL 12 : SynchChange[0,137]
[12:03:38.308]          : Fn 0121094 T1 0091 T2 12 T3 20 Time 00 00 21 086
[12:03:38.338]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[12:03:38.338]          : Fn 0121102 T1 0091 T2 20 T3 28 Time 00 00 21 119
[12:03:38.449]   SIM 07 : main instruction recv data error,resend main ins,resend times:1
[12:03:38.495]          : Fn 0121136 T1 0091 T2 02 T3 11 Time 00 00 21 276
[12:03:38.619]   PAL 12 : SynchChange[625,378]
[12:03:39.043]          : Fn 0121254 T1 0091 T2 16 T3 27 Time 00 00 21 821
[12:03:39.151]   SIM 07 : main instruction recv data error,resend main ins,resend times:2
[12:03:39.683]          : Fn 0121392 T1 0091 T2 24 T3 12 Time 00 00 22 459
[12:03:39.776]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[12:03:39.776]          : Detected event: 0xdd000003
[12:03:39.776]          : Fn 0121413 T1 0091 T2 19 T3 33 Time 00 00 22 557
[12:03:39.777]   SIM 07 : main instruction recv data error,exceed max resend main ins times:2
[12:03:39.777]   SIM 01 : (sim_RunGsmAlgoReq)
[12:03:39.780]   SIM 01 : Dump : at 0x820BF672 (16/16) : 8d 82 4e 6a b6 ed 78 6a 41 cc b4 db 9f 48 cd a1
[12:03:39.780]          : Fn 0121414 T1 0091 T2 20 T3 34 Time 00 00 22 560
[12:03:39.782]   SIM 07 : SELECT_DF instruction recv data error,resend main ins,resend times:1
[12:03:39.787]   SIM 07 : SELECT_DF instruction recv data error,resend main ins,resend times:2
[12:03:39.787]          : Fn 0121415 T1 0091 T2 21 T3 35 Time 00 00 22 567
[12:03:39.787]   SIM 07 : SELECT_DF instruction recv data error,exceed max resend main ins times:2
[12:03:39.795]   MM 08  : Unexpected status from SIM Sw1 : 0x0, Sw2 : 0xff\n
[12:03:39.795]          : Fn 0121416 T1 0091 T2 22 T3 36 Time 00 00 22 570
[12:03:39.795]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[12:03:39.795]          : Fn 0121418 T1 0091 T2 24 T3 38 Time 00 00 22 573
[12:03:39.795]   PAL 12 : SynchChange[4375,131]
[12:03:39.795]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:39.796]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:39.796]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:39.796]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:39.797]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:39.797]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:39.797]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:39.797]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:39.798]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:39.803]          : Detected event: 0x09290001
[12:03:39.803]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:39.803]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:39.803]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:39.803]   API 01 : api_SimCloseCnf
[12:03:39.804]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:39.804]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:39.804]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:39.804]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:39.804]   MM 09  : Att 1, Service 0x01, NMO 2
[12:03:39.804]   SIM 01 : (sim_ResetReq)
[12:03:39.804]          : Fn 0121419 T1 0091 T2 25 T3 39 Time 00 00 22 582
[12:03:39.822]   PAL 12 : SynchChange[0,131]
[12:03:39.822]          : Fn 0121422 T1 0091 T2 02 T3 42 Time 00 00 22 599
[12:03:39.932]   SIM 01 : Reset Status: 0
[12:03:39.962]          : Fn 0121450 T1 0091 T2 04 T3 19 Time 00 00 22 741
[12:03:40.073]   SIM 01 : Reset Status: 1
[12:03:41.175]          : Fn 0121716 T1 0091 T2 10 T3 30 Time 00 00 23 954
[12:03:41.279]   SIM 01 : Reset Status: 1
[12:03:42.409]          : Fn 0121910 T1 0091 T2 22 T3 20 Time 00 00 25 189
[12:03:42.409]   SIM 01 : Reset Status: 1
[12:03:42.409]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:42.410]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:42.420]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:42.532]   SIM 01 : Reset Status: 3
[12:03:42.690]          : Fn 0122012 T1 0092 T2 20 T3 20 Time 00 00 25 470
[12:03:42.723]   SIM 01 : (sim_DedicFileStatusReq) DF FILE: 1
[12:03:42.820]   CSW 01 : Send Sim Open to Stack[sim0]\n
[12:03:43.043]   SIM 01 : (sim_RetryReq)
[12:03:43.124]          : Fn 0122139 T1 0092 T2 17 T3 45 Time 00 00 25 905
[12:03:43.227]   PAL 12 : SynchChange[0,132]
[12:03:43.316]          : Fn 0122141 T1 0092 T2 19 T3 47 Time 00 00 26 097
[12:03:43.317]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x3,Wanted 1
[12:03:43.317]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:43.317]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:43.317]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:43.318]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:43.318]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:43.318]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:43.319]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:43.319]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:43.319]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:43.319]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:43.322]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:43.328]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:43.328]          : Fn 0122183 T1 0092 T2 09 T3 38 Time 00 00 26 108
[12:03:43.411]   PAL 12 : SynchChange[0,136]
[12:03:43.411]          : Fn 0122201 T1 0092 T2 01 T3 05 Time 00 00 26 192
[12:03:43.423]   SIM 07 : main instruction recv data error,resend main ins,resend times:1
[12:03:43.423]          : Fn 0122203 T1 0092 T2 03 T3 07 Time 00 00 26 203
[12:03:43.427]   SIM 07 : main instruction recv data error,resend main ins,resend times:2
[12:03:43.427]          : Fn 0122204 T1 0092 T2 04 T3 08 Time 00 00 26 206
[12:03:43.438]   SIM 07 : main instruction recv data error,exceed max resend main ins times:2
[12:03:43.438]          : Fn 0122205 T1 0092 T2 05 T3 09 Time 00 00 26 211
[12:03:43.440]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:1
[12:03:43.440]          : Fn 0122206 T1 0092 T2 06 T3 10 Time 00 00 26 215
[12:03:43.440]   SIM 07 : SELECT_EF instruction recv data error,resend main ins,resend times:2
[12:03:43.440]          : Detected event: 0xdd000002
[12:03:43.484]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:2
[12:03:43.485]          : Fn 0122217 T1 0092 T2 17 T3 21 Time 00 00 26 265
[12:03:43.586]   PAL 12 : SynchChange[0,383]
[12:03:43.603]          : Fn 0122242 T1 0092 T2 16 T3 46 Time 00 00 26 382
[12:03:43.670]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:1
[12:03:43.670]          : Fn 0122257 T1 0092 T2 05 T3 10 Time 00 00 26 450
[12:03:43.702]   SIM 07 : f2 instruction recv data error,resend main ins,resend times:2
[12:03:43.702]          : Fn 0122264 T1 0092 T2 12 T3 17 Time 00 00 26 483
[12:03:43.702]   SIM 07 : f2 instruction recv data error,exceed max resend main ins times:2
[12:03:43.704]          : Detected event: 0x09290001
[12:03:43.704]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[12:03:43.733]   SIM 01 : (sim_ResetReq)
[12:03:43.733]          : Fn 0122268 T1 0092 T2 16 T3 21 Time 00 00 26 504
[12:03:43.826]   SIM 01 : Reset Status: 0
[12:03:43.840]          : Fn 0122294 T1 0092 T2 16 T3 47 Time 00 00 26 621
[12:03:43.964]   SIM 01 : Reset Status: 1
[12:03:44.686]          : Fn 0122477 T1 0092 T2 17 T3 26 Time 00 00 27 466
[12:03:44.792]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[12:03:45.545]          : Fn 0122663 T1 0092 T2 21 T3 08 Time 00 00 28 324
[12:03:45.651]   SIM 01 : Reset Status: 1
[12:03:47.194]          : Fn 0123002 T1 0092 T2 22 T3 41 Time 00 00 29 973
[12:03:47.292]   SIM 01 : Reset Status: 1
[12:03:48.840]          : Fn 0123377 T1 0093 T2 07 T3 08 Time 00 00 31 620
[12:03:48.953]   SIM 01 : Reset Status: 1
[12:03:50.173]          : Fn 0123666 T1 0093 T2 10 T3 42 Time 00 00 32 954
[12:03:50.173]   SIM 01 : Reset Status: 1
[12:03:50.173]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:50.174]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:50.184]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:50.184]          : Fn 0123668 T1 0093 T2 12 T3 44 Time 00 00 32 965
[12:03:50.289]   SIM 01 : Reset Status: 3
[12:03:50.296]          : Fn 0123692 T1 0093 T2 10 T3 17 Time 00 00 33 076
[12:03:50.399]   SIM 01 : Reset Status: 1
[12:03:51.548]          : Fn 0123963 T1 0093 T2 21 T3 33 Time 00 00 34 328
[12:03:51.548]   SIM 01 : Reset Status: 1
[12:03:51.548]   SIM 06 : ApplicationSelectByFullDf /ApplicationselectByPartialDf / EfDirPresent/ EfAtrPresent = 1 1 1 0
[12:03:51.548]   SIM 06 : DfSelectByFullDf / DfSelectByPartialDf / DfSelectByPath / DfSelectByFileID= 1 1 1 1
[12:03:51.559]   SIM 06 : SfiSupport / RecordNumberSupport / RecordIDSupport = 1 1 0
[12:03:51.559]          : Fn 0123966 T1 0093 T2 24 T3 36 Time 00 00 34 338
[12:03:51.666]   SIM 01 : Reset Status: 3
[12:03:51.828]          : Fn 0124022 T1 0093 T2 02 T3 41 Time 00 00 34 608
[12:03:51.829]   SIM 01 : (sim_RunGsmAlgoReq)
[12:03:51.913]   SIM 01 : Dump : at 0x820BF862 (16/16) : 8d 82 4e 6a b6 ed 78 6a 41 cc b4 db 9f 48 cd a1
[12:03:51.947]   SIM 01 : (sim_DedicFileStatusReq) DF FILE: 1
[12:03:51.948]   CSW 01 : Send Sim Open to Stack[sim0]\n
[12:03:51.979]   API 01 : Saving SIM_OPEN_IND[0]
[12:03:51.979]          : Fn 0124056 T1 0093 T2 10 T3 24 Time 00 00 34 756
[12:03:52.089]   SIM 01 : (sim_RetryReq)
[12:03:52.102]          : Fn 0124083 T1 0093 T2 11 T3 00 Time 00 00 34 881
[12:03:52.216]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[12:03:52.868]          : Fn 0124226 T1 0093 T2 24 T3 41 Time 00 00 35 645
[12:03:52.981]   SIM 07 : SELECT_EF instruction recv data error,exceed max resend main ins times:0
[12:03:54.106]          : Fn 0124517 T1 0093 T2 03 T3 26 Time 00 00 36 885
[12:03:54.106]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:54.106]          : Fn 0124518 T1 0093 T2 04 T3 27 Time 00 00 36 885
[12:03:54.106]   PAL 12 : SynchChange[0,135]
[12:03:54.109]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:54.109]          : Fn 0124519 T1 0093 T2 05 T3 28 Time 00 00 36 890
[12:03:54.110]   PAL 12 : SynchChange[0,134]
[12:03:54.110]   MM 01  : mm_SaveIEvt ID = 0xaff01\n
[12:03:54.110]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:54.110]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:54.111]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:54.111]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:54.111]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:54.111]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:54.112]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:54.112]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:54.112]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:54.112]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:54.113]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:54.114]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:54.114]          : Fn 0124520 T1 0093 T2 06 T3 29 Time 00 00 36 895
[12:03:54.165]   MMI 01 : network register success
[12:03:54.165]          : Fn 0124531 T1 0093 T2 17 T3 40 Time 00 00 36 945
[12:03:54.166]   MMI 01 : start Call test, initialize audio first
[12:03:54.274]   MMI 01 : start make a call
[12:03:54.501]          : Fn 0124604 T1 0093 T2 12 T3 11 Time 00 00 37 282
[12:03:54.608]   PAL 12 : SynchChange[625,371]
[12:03:55.236]          : Fn 0124762 T1 0094 T2 14 T3 16 Time 00 00 38 016
[12:03:55.236]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:55.236]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x0,Wanted 0
[12:03:55.236]          : Fn 0124764 T1 0094 T2 16 T3 18 Time 00 00 38 016
[12:03:55.238]   PAL 12 : SynchChange[4375,135]
[12:03:55.238]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:55.238]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:55.239]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:55.239]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:55.239]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:55.239]   API 01 : api_SimCloseCnf
[12:03:55.239]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 ff fe
[12:03:55.239]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:55.239]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:55.240]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:55.241]   MM 09  : Att 1, Service 0x01, NMO 2
[12:03:55.241]   API 01 : Send saved SIM_OPEN_IND[0]
[12:03:55.354]   API 01 : api_SimCloseCnf
[12:03:55.416]          : Fn 0124802 T1 0094 T2 02 T3 05 Time 00 00 38 196
[12:03:55.524]   PAL 12 : SynchChange[0,133]
[12:03:55.558]          : Fn 0124804 T1 0094 T2 04 T3 07 Time 00 00 38 338
[12:03:55.559]   MM 03  : mmp_SndRrGprsReq DetachStuff is 0x1,SimStatus 0x3,Wanted 1
[12:03:55.559]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:55.560]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:55.560]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:55.560]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:55.560]   MM 09  : Att 1, Service 0x01, NMO 2
[12:03:55.561]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:55.561]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:55.561]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:55.562]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:55.562]   MM 09  : Att 1, Service 0x02, NMO 2
[12:03:55.562]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:55.562]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:55.562]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:55.563]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:55.563]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:55.563]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:55.574]   MM 01  : mm_SaveIEvt ID = 0x30008\n
[12:03:55.574]          : Fn 0124836 T1 0094 T2 10 T3 39 Time 00 00 38 353
[12:03:55.680]   PAL 12 : SynchChange[0,130]
[12:03:55.915]          : Fn 0124910 T1 0094 T2 06 T3 11 Time 00 00 38 694
[12:03:56.029]   PAL 12 : SynchChange[0,371]
[12:03:57.162]          : Fn 0125180 T1 0094 T2 16 T3 26 Time 00 00 39 941
[12:03:57.162]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[12:03:57.162]   SIM 01 : (sim_RunGsmAlgoReq)
[12:03:57.260]   SIM 01 : Dump : at 0x820BEA22 (16/16) : 09 f6 be db ca 8b 1c 0d 0c 65 7c 8b 2c 76 df 55
[12:03:59.282]          : Fn 0125639 T1 0094 T2 07 T3 26 Time 00 00 42 063
[12:03:59.284]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:03:59.284]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:03:59.284]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:03:59.284]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:03:59.284]   MM 09  : Att 1, Service 0x82, NMO 2
[12:03:59.284]          : Fn 0125640 T1 0094 T2 08 T3 27 Time 00 00 42 063
[12:03:59.286]   PAL 12 : SynchChange[0,132]
[12:03:59.289]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:03:59.289]          : Fn 0125641 T1 0094 T2 09 T3 28 Time 00 00 42 068
[12:03:59.293]   PAL 12 : SynchChange[0,129]
[12:03:59.293]          : Fn 0125642 T1 0094 T2 10 T3 29 Time 00 00 42 073
[12:03:59.401]   MMI 01 : network register success
[12:03:59.681]          : Fn 0125726 T1 0094 T2 16 T3 11 Time 00 00 42 460
[12:03:59.792]   PAL 12 : SynchChange[4375,200]
[12:03:59.967]          : Fn 0125787 T1 0094 T2 25 T3 21 Time 00 00 42 748
[12:04:00.011]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:00.011]          : Fn 0125796 T1 0094 T2 08 T3 30 Time 00 00 42 791
[12:04:00.119]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:00.293]          : Fn 0125859 T1 0094 T2 19 T3 42 Time 00 00 43 074
[12:04:00.404]   PAL 12 : SynchChange[4375,255]
[12:04:00.487]          : Fn 0125900 T1 0094 T2 08 T3 32 Time 00 00 43 267
[12:04:00.507]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.507]          : Fn 0125904 T1 0094 T2 12 T3 36 Time 00 00 43 285
[12:04:00.507]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.528]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.529]          : Fn 0125909 T1 0094 T2 17 T3 41 Time 00 00 43 309
[12:04:00.546]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.546]          : Fn 0125913 T1 0094 T2 21 T3 45 Time 00 00 43 327
[12:04:00.547]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.565]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.565]          : Fn 0125917 T1 0094 T2 25 T3 49 Time 00 00 43 345
[12:04:00.567]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.567]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[12:04:00.567]   SIM 01 : (sim_RunGsmAlgoReq)
[12:04:00.588]   SIM 01 : Dump : at 0x820BEFCE (16/16) : e7 77 58 ce 5c e5 97 63 66 97 60 2d 97 7f f6 e2
[12:04:00.588]          : Fn 0125922 T1 0094 T2 04 T3 03 Time 00 00 43 369
[12:04:00.588]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.626]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.627]          : Fn 0125930 T1 0094 T2 12 T3 11 Time 00 00 43 405
[12:04:00.627]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.649]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.649]          : Fn 0125935 T1 0094 T2 17 T3 16 Time 00 00 43 429
[12:04:00.657]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.657]          : Fn 0125936 T1 0094 T2 18 T3 17 Time 00 00 43 436
[12:04:00.678]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:00.678]          : Fn 0125941 T1 0094 T2 23 T3 22 Time 00 00 43 457
[12:04:00.774]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:00.807]          : Fn 0125969 T1 0094 T2 25 T3 50 Time 00 00 43 585
[12:04:00.828]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.828]          : Fn 0125974 T1 0095 T2 04 T3 04 Time 00 00 43 609
[12:04:00.828]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.846]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.847]          : Fn 0125978 T1 0095 T2 08 T3 08 Time 00 00 43 627
[12:04:00.888]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.888]          : Fn 0125988 T1 0095 T2 18 T3 18 Time 00 00 43 669
[12:04:00.889]   PAL 12 : SynchChange[4375,291]
[12:04:00.889]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.889]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.924]   L1S 01 : Missing encoded blocks 0/2 NbTx 2 TxBitMap 0x0\n
[12:04:00.924]          : Fn 0125995 T1 0095 T2 25 T3 25 Time 00 00 43 705
[12:04:00.924]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.947]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.947]          : Fn 0126000 T1 0095 T2 04 T3 30 Time 00 00 43 728
[12:04:00.966]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.966]          : Fn 0126004 T1 0095 T2 08 T3 34 Time 00 00 43 746
[12:04:00.966]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:00.988]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:00.988]          : Fn 0126008 T1 0095 T2 12 T3 38 Time 00 00 43 765
[12:04:01.087]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.146]          : Fn 0126043 T1 0095 T2 21 T3 22 Time 00 00 43 926
[12:04:01.164]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.164]          : Fn 0126047 T1 0095 T2 25 T3 26 Time 00 00 43 945
[12:04:01.164]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.192]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.192]          : Fn 0126052 T1 0095 T2 04 T3 31 Time 00 00 43 968
[12:04:01.206]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.206]          : Fn 0126056 T1 0095 T2 08 T3 35 Time 00 00 43 986
[12:04:01.206]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.224]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.224]          : Fn 0126060 T1 0095 T2 12 T3 39 Time 00 00 44 005
[12:04:01.247]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.247]          : Fn 0126065 T1 0095 T2 17 T3 44 Time 00 00 44 028
[12:04:01.248]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.266]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.266]          : Fn 0126069 T1 0095 T2 21 T3 48 Time 00 00 44 046
[12:04:01.276]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.276]          : Fn 0126070 T1 0095 T2 22 T3 49 Time 00 00 44 054
[12:04:01.276]   MM 15  : mmActif = 0x6\n
[12:04:01.286]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:01.286]          : Fn 0126073 T1 0095 T2 25 T3 01 Time 00 00 44 067
[12:04:01.295]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:01.295]          : Fn 0126075 T1 0095 T2 01 T3 03 Time 00 00 44 075
[12:04:01.402]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:04:01.428]          : Fn 0126104 T1 0095 T2 04 T3 32 Time 00 00 44 208
[12:04:01.447]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.447]          : Fn 0126108 T1 0095 T2 08 T3 36 Time 00 00 44 226
[12:04:01.447]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.465]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.465]          : Fn 0126112 T1 0095 T2 12 T3 40 Time 00 00 44 245
[12:04:01.488]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.488]          : Fn 0126117 T1 0095 T2 17 T3 45 Time 00 00 44 268
[12:04:01.489]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.507]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.507]          : Fn 0126121 T1 0095 T2 21 T3 49 Time 00 00 44 286
[12:04:01.527]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.527]          : Fn 0126125 T1 0095 T2 25 T3 02 Time 00 00 44 305
[12:04:01.527]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.548]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.548]          : Fn 0126130 T1 0095 T2 04 T3 07 Time 00 00 44 328
[12:04:01.567]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.567]          : Fn 0126134 T1 0095 T2 08 T3 11 Time 00 00 44 346
[12:04:01.567]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.585]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.585]          : Fn 0126138 T1 0095 T2 12 T3 15 Time 00 00 44 365
[12:04:01.611]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.611]          : Fn 0126143 T1 0095 T2 17 T3 20 Time 00 00 44 388
[12:04:01.611]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.627]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.627]          : Fn 0126147 T1 0095 T2 21 T3 24 Time 00 00 44 406
[12:04:01.644]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.644]          : Fn 0126151 T1 0095 T2 25 T3 28 Time 00 00 44 425
[12:04:01.644]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.668]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.668]          : Fn 0126156 T1 0095 T2 04 T3 33 Time 00 00 44 448
[12:04:01.687]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.687]          : Fn 0126160 T1 0095 T2 08 T3 37 Time 00 00 44 466
[12:04:01.687]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.705]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.705]          : Fn 0126164 T1 0095 T2 12 T3 41 Time 00 00 44 485
[12:04:01.728]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.728]          : Fn 0126169 T1 0095 T2 17 T3 46 Time 00 00 44 508
[12:04:01.729]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.746]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.746]          : Fn 0126173 T1 0095 T2 21 T3 50 Time 00 00 44 526
[12:04:01.765]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.765]          : Fn 0126177 T1 0095 T2 25 T3 03 Time 00 00 44 545
[12:04:01.765]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.788]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.788]          : Fn 0126182 T1 0095 T2 04 T3 08 Time 00 00 44 568
[12:04:01.817]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.817]          : Fn 0126186 T1 0095 T2 08 T3 12 Time 00 00 44 586
[12:04:01.817]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.827]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.827]          : Fn 0126190 T1 0095 T2 12 T3 16 Time 00 00 44 605
[12:04:01.847]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.847]          : Fn 0126195 T1 0095 T2 17 T3 21 Time 00 00 44 628
[12:04:01.866]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.866]          : Fn 0126199 T1 0095 T2 21 T3 25 Time 00 00 44 646
[12:04:01.885]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.885]          : Fn 0126203 T1 0095 T2 25 T3 29 Time 00 00 44 665
[12:04:01.885]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.907]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.907]          : Fn 0126208 T1 0095 T2 04 T3 34 Time 00 00 44 688
[12:04:01.908]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.926]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.926]          : Fn 0126212 T1 0095 T2 08 T3 38 Time 00 00 44 706
[12:04:01.944]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.944]          : Fn 0126216 T1 0095 T2 12 T3 42 Time 00 00 44 725
[12:04:01.944]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.967]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.967]          : Fn 0126221 T1 0095 T2 17 T3 47 Time 00 00 44 748
[12:04:01.968]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:01.986]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:01.986]          : Fn 0126225 T1 0095 T2 21 T3 00 Time 00 00 44 766
[12:04:01.986]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.005]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.005]          : Fn 0126229 T1 0095 T2 25 T3 04 Time 00 00 44 785
[12:04:02.005]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.028]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.028]          : Fn 0126234 T1 0095 T2 04 T3 09 Time 00 00 44 808
[12:04:02.029]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.047]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.047]          : Fn 0126238 T1 0095 T2 08 T3 13 Time 00 00 44 826
[12:04:02.047]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.065]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.065]          : Fn 0126242 T1 0095 T2 12 T3 17 Time 00 00 44 845
[12:04:02.065]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.093]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.093]          : Fn 0126247 T1 0095 T2 17 T3 22 Time 00 00 44 868
[12:04:02.093]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.107]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.107]          : Fn 0126251 T1 0095 T2 21 T3 26 Time 00 00 44 886
[12:04:02.107]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.124]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.124]          : Fn 0126255 T1 0095 T2 25 T3 30 Time 00 00 44 905
[12:04:02.124]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.148]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.148]          : Fn 0126260 T1 0095 T2 04 T3 35 Time 00 00 44 928
[12:04:02.148]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.166]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.166]          : Fn 0126264 T1 0095 T2 08 T3 39 Time 00 00 44 946
[12:04:02.166]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.185]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.185]          : Fn 0126268 T1 0095 T2 12 T3 43 Time 00 00 44 965
[12:04:02.185]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.207]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.207]          : Fn 0126273 T1 0095 T2 17 T3 48 Time 00 00 44 988
[12:04:02.208]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.235]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.235]          : Fn 0126277 T1 0095 T2 21 T3 01 Time 00 00 45 006
[12:04:02.235]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.244]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.244]          : Fn 0126281 T1 0095 T2 25 T3 05 Time 00 00 45 025
[12:04:02.244]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.267]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.267]          : Fn 0126286 T1 0095 T2 04 T3 10 Time 00 00 45 048
[12:04:02.268]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.286]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.286]          : Fn 0126290 T1 0095 T2 08 T3 14 Time 00 00 45 066
[12:04:02.286]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.307]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.307]          : Fn 0126294 T1 0095 T2 12 T3 18 Time 00 00 45 085
[12:04:02.307]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.327]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.327]          : Fn 0126299 T1 0095 T2 17 T3 23 Time 00 00 45 108
[12:04:02.328]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.346]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.346]          : Fn 0126303 T1 0095 T2 21 T3 27 Time 00 00 45 126
[12:04:02.347]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.365]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.365]          : Fn 0126307 T1 0095 T2 25 T3 31 Time 00 00 45 145
[12:04:02.365]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.369]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.369]          : Fn 0126308 T1 0095 T2 00 T3 32 Time 00 00 45 149
[12:04:02.407]   PAL 12 : SynchChange[625,190]
[12:04:02.407]          : Fn 0126316 T1 0095 T2 08 T3 40 Time 00 00 45 187
[12:04:02.408]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.426]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.426]          : Fn 0126320 T1 0095 T2 12 T3 44 Time 00 00 45 205
[12:04:02.426]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.449]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.449]          : Fn 0126325 T1 0095 T2 17 T3 49 Time 00 00 45 229
[12:04:02.449]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.466]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.466]          : Fn 0126329 T1 0095 T2 21 T3 02 Time 00 00 45 247
[12:04:02.467]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.485]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.485]          : Fn 0126333 T1 0095 T2 25 T3 06 Time 00 00 45 265
[12:04:02.485]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.508]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.508]          : Fn 0126338 T1 0095 T2 04 T3 11 Time 00 00 45 289
[12:04:02.508]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.526]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.526]          : Fn 0126342 T1 0095 T2 08 T3 15 Time 00 00 45 307
[12:04:02.527]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.545]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.545]          : Fn 0126346 T1 0095 T2 12 T3 19 Time 00 00 45 325
[12:04:02.545]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.569]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.569]          : Fn 0126351 T1 0095 T2 17 T3 24 Time 00 00 45 349
[12:04:02.569]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.587]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.587]          : Fn 0126355 T1 0095 T2 21 T3 28 Time 00 00 45 367
[12:04:02.588]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.605]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.605]          : Fn 0126359 T1 0095 T2 25 T3 32 Time 00 00 45 385
[12:04:02.605]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.628]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.628]          : Fn 0126364 T1 0095 T2 04 T3 37 Time 00 00 45 409
[12:04:02.628]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.646]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.647]          : Fn 0126368 T1 0095 T2 08 T3 41 Time 00 00 45 427
[12:04:02.650]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.666]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.666]          : Fn 0126372 T1 0095 T2 12 T3 45 Time 00 00 45 445
[12:04:02.666]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.689]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.689]          : Fn 0126377 T1 0095 T2 17 T3 50 Time 00 00 45 468
[12:04:02.689]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.707]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.707]          : Fn 0126381 T1 0095 T2 21 T3 03 Time 00 00 45 487
[12:04:02.708]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.726]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.726]          : Fn 0126385 T1 0095 T2 25 T3 07 Time 00 00 45 505
[12:04:02.726]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.749]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.749]          : Fn 0126390 T1 0095 T2 04 T3 12 Time 00 00 45 528
[12:04:02.749]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.768]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.768]          : Fn 0126394 T1 0095 T2 08 T3 16 Time 00 00 45 547
[12:04:02.768]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.786]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.786]          : Fn 0126398 T1 0095 T2 12 T3 20 Time 00 00 45 565
[12:04:02.786]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.809]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.809]          : Fn 0126403 T1 0095 T2 17 T3 25 Time 00 00 45 588
[12:04:02.809]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.827]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.827]          : Fn 0126407 T1 0095 T2 21 T3 29 Time 00 00 45 607
[12:04:02.828]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.847]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.847]          : Fn 0126411 T1 0095 T2 25 T3 33 Time 00 00 45 625
[12:04:02.847]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.870]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.870]          : Fn 0126416 T1 0095 T2 04 T3 38 Time 00 00 45 649
[12:04:02.870]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.887]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.887]          : Fn 0126420 T1 0095 T2 08 T3 42 Time 00 00 45 667
[12:04:02.888]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.907]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.907]          : Fn 0126424 T1 0095 T2 12 T3 46 Time 00 00 45 685
[12:04:02.907]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.929]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.929]          : Fn 0126429 T1 0095 T2 17 T3 00 Time 00 00 45 709
[12:04:02.929]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.947]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.947]          : Fn 0126433 T1 0095 T2 21 T3 04 Time 00 00 45 727
[12:04:02.948]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.966]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.966]          : Fn 0126437 T1 0095 T2 25 T3 08 Time 00 00 45 745
[12:04:02.966]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:02.989]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:02.989]          : Fn 0126442 T1 0095 T2 04 T3 13 Time 00 00 45 769
[12:04:02.989]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.007]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.007]          : Fn 0126446 T1 0095 T2 08 T3 17 Time 00 00 45 787
[12:04:03.007]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.025]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.025]          : Fn 0126450 T1 0095 T2 12 T3 21 Time 00 00 45 805
[12:04:03.025]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.048]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.048]          : Fn 0126455 T1 0095 T2 17 T3 26 Time 00 00 45 829
[12:04:03.048]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.066]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.066]          : Fn 0126459 T1 0095 T2 21 T3 30 Time 00 00 45 847
[12:04:03.067]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.086]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.086]          : Fn 0126463 T1 0095 T2 25 T3 34 Time 00 00 45 865
[12:04:03.086]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.109]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.109]          : Fn 0126468 T1 0095 T2 04 T3 39 Time 00 00 45 889
[12:04:03.109]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.127]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.127]          : Fn 0126472 T1 0095 T2 08 T3 43 Time 00 00 45 907
[12:04:03.128]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.146]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.146]          : Fn 0126476 T1 0095 T2 12 T3 47 Time 00 00 45 925
[12:04:03.146]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.170]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.170]          : Fn 0126481 T1 0095 T2 17 T3 01 Time 00 00 45 949
[12:04:03.170]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.187]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.187]          : Fn 0126485 T1 0095 T2 21 T3 05 Time 00 00 45 967
[12:04:03.188]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.206]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.206]          : Fn 0126489 T1 0095 T2 25 T3 09 Time 00 00 45 985
[12:04:03.206]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.228]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.228]          : Fn 0126494 T1 0095 T2 04 T3 14 Time 00 00 46 009
[12:04:03.228]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.247]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.247]          : Fn 0126498 T1 0095 T2 08 T3 18 Time 00 00 46 027
[12:04:03.248]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.265]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.265]          : Fn 0126502 T1 0095 T2 12 T3 22 Time 00 00 46 045
[12:04:03.265]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.299]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.299]          : Fn 0126507 T1 0095 T2 17 T3 27 Time 00 00 46 069
[12:04:03.299]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.307]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.307]          : Fn 0126511 T1 0095 T2 21 T3 31 Time 00 00 46 087
[12:04:03.307]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.325]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.326]          : Fn 0126515 T1 0095 T2 25 T3 35 Time 00 00 46 105
[12:04:03.326]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.349]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.349]          : Fn 0126520 T1 0095 T2 04 T3 40 Time 00 00 46 129
[12:04:03.349]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.367]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.367]          : Fn 0126524 T1 0095 T2 08 T3 44 Time 00 00 46 147
[12:04:03.367]   L1S 02 : Tx Dummy on Tn 6 TxP 5\n
[12:04:03.450]   L1S 02 : Tx Dummy on Tn 7 TxP 5\n
[12:04:03.450]          : Fn 0126541 T1 0095 T2 25 T3 10 Time 00 00 46 230
[12:04:03.450]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:04:03.451]          : Fn 0126543 T1 0095 T2 01 T3 12 Time 00 00 46 230
[12:04:03.451]   PAL 12 : SynchChange[1250,140]
[12:04:03.452]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:04:03.452]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:04:03.452]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:04:03.559]   MM 09  : Att 1, Service 0x82, NMO 2
[12:04:05.177]          : Fn 0126917 T1 0095 T2 11 T3 29 Time 00 00 47 957
[12:04:05.276]   PAL 12 : SynchChange[0,129]
[12:04:05.329]          : Fn 0126950 T1 0095 T2 18 T3 11 Time 00 00 48 110
[12:04:05.429]   PAL 12 : SynchChange[0,375]
[12:04:06.360]          : Fn 0127173 T1 0095 T2 07 T3 30 Time 00 00 49 140
[12:04:06.360]   SIM 01 : =========AUTHENTIACATION BEGIN =============
[12:04:06.361]   SIM 01 : (sim_RunGsmAlgoReq)
[12:04:06.469]   SIM 01 : Dump : at 0x820BF09A (16/16) : 8c 39 3c 3c 15 5a 8c 01 09 96 c1 5f cb 34 ec 8d
[12:04:07.775]          : Fn 0127480 T1 0096 T2 02 T3 31 Time 00 00 50 556
[12:04:07.890]   PAL 12 : SynchChange[1250,466]
[12:04:13.469]          : Fn 0179312 T1 0135 T2 16 T3 47 Time 00 00 56 249
[12:04:13.575]   PAL 12 : SynchChange[1791,1400]
[12:04:45.372]          : Fn 0186223 T1 0140 T2 11 T3 22 Time 00 01 28 150
[12:04:45.483]   MM 01  : mm_SaveIEvt ID = 0xc0006\n
[12:05:20.899]          : Fn 0193921 T1 0146 T2 13 T3 19 Time 00 02 03 677
[12:05:21.012]   MMI 01 : Hang up,is remote hang up:1, error code:2
[12:05:21.278]          : Fn 0194004 T1 0146 T2 18 T3 00 Time 00 02 04 059
[12:05:21.407]   PAL 12 : SynchChange[0,166]
[12:05:23.190]          : Fn 0194418 T1 0146 T2 16 T3 06 Time 00 02 05 970
[12:05:23.191]   RRI 16 : FreqL for SI13 Ma is null, so store GMa
[12:05:23.191]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:05:23.191]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:05:23.191]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:05:23.192]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:05:23.195]   MM 09  : Att 1, Service 0x82, NMO 2
[12:05:23.203]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:05:23.203]          : Fn 0194421 T1 0146 T2 19 T3 09 Time 00 02 05 983
[12:05:23.308]   PAL 12 : SynchChange[0,179]
[12:05:23.448]          : Fn 0194474 T1 0146 T2 20 T3 11 Time 00 02 06 228
[12:05:23.536]   PAL 12 : SynchChange[2500,199]
[12:05:23.536]          : Fn 0194492 T1 0146 T2 12 T3 29 Time 00 02 06 315
[12:05:23.634]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:05:23.738]          : Fn 0194536 T1 0146 T2 04 T3 22 Time 00 02 06 519
[12:05:23.761]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:05:23.762]          : Fn 0194541 T1 0146 T2 09 T3 27 Time 00 02 06 542
[12:05:23.762]   MM 15  : mmActif = 0x6\n
[12:05:23.783]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:05:23.783]          : Fn 0194546 T1 0146 T2 14 T3 32 Time 00 02 06 564
[12:05:23.887]   MM 01  : mm_CheckConnStatus[0] mmActif: 6\n
[12:05:24.195]          : Fn 0194635 T1 0146 T2 25 T3 19 Time 00 02 06 973
[12:05:24.195]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.216]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.216]          : Fn 0194640 T1 0146 T2 04 T3 24 Time 00 02 06 996
[12:05:24.216]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.234]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.234]          : Fn 0194644 T1 0146 T2 08 T3 28 Time 00 02 07 015
[12:05:24.235]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.254]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.254]          : Fn 0194648 T1 0146 T2 12 T3 32 Time 00 02 07 033
[12:05:24.254]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.276]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.276]          : Fn 0194653 T1 0146 T2 17 T3 37 Time 00 02 07 056
[12:05:24.276]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.294]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.294]          : Fn 0194657 T1 0146 T2 21 T3 41 Time 00 02 07 075
[12:05:24.295]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.313]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.313]          : Fn 0194661 T1 0146 T2 25 T3 45 Time 00 02 07 093
[12:05:24.313]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.338]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.338]          : Fn 0194666 T1 0146 T2 04 T3 50 Time 00 02 07 116
[12:05:24.354]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.354]          : Fn 0194670 T1 0146 T2 08 T3 03 Time 00 02 07 135
[12:05:24.355]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.375]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.375]          : Fn 0194674 T1 0146 T2 12 T3 07 Time 00 02 07 153
[12:05:24.375]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.408]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.409]          : Fn 0194679 T1 0146 T2 17 T3 12 Time 00 02 07 176
[12:05:24.409]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.422]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.422]          : Fn 0194683 T1 0146 T2 21 T3 16 Time 00 02 07 195
[12:05:24.422]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.433]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.433]          : Fn 0194687 T1 0146 T2 25 T3 20 Time 00 02 07 213
[12:05:24.433]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.457]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.457]          : Fn 0194692 T1 0146 T2 04 T3 25 Time 00 02 07 236
[12:05:24.457]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.474]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.474]          : Fn 0194696 T1 0146 T2 08 T3 29 Time 00 02 07 255
[12:05:24.476]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.494]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.494]          : Fn 0194700 T1 0146 T2 12 T3 33 Time 00 02 07 273
[12:05:24.494]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.516]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.516]          : Fn 0194705 T1 0146 T2 17 T3 38 Time 00 02 07 296
[12:05:24.516]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.536]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.536]          : Fn 0194709 T1 0146 T2 21 T3 42 Time 00 02 07 315
[12:05:24.537]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.556]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.556]          : Fn 0194713 T1 0146 T2 25 T3 46 Time 00 02 07 333
[12:05:24.556]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.578]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.578]          : Fn 0194718 T1 0146 T2 04 T3 00 Time 00 02 07 356
[12:05:24.578]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.594]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.594]          : Fn 0194722 T1 0146 T2 08 T3 04 Time 00 02 07 375
[12:05:24.595]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.614]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.614]          : Fn 0194726 T1 0146 T2 12 T3 08 Time 00 02 07 393
[12:05:24.614]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.638]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.638]          : Fn 0194731 T1 0146 T2 17 T3 13 Time 00 02 07 416
[12:05:24.638]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.654]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.654]          : Fn 0194735 T1 0146 T2 21 T3 17 Time 00 02 07 435
[12:05:24.655]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.673]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.673]          : Fn 0194739 T1 0146 T2 25 T3 21 Time 00 02 07 453
[12:05:24.673]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.697]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.697]          : Fn 0194744 T1 0146 T2 04 T3 26 Time 00 02 07 476
[12:05:24.697]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.716]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.716]          : Fn 0194748 T1 0146 T2 08 T3 30 Time 00 02 07 495
[12:05:24.717]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.733]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.733]          : Fn 0194752 T1 0146 T2 12 T3 34 Time 00 02 07 513
[12:05:24.733]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.758]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.758]          : Fn 0194757 T1 0146 T2 17 T3 39 Time 00 02 07 536
[12:05:24.758]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.776]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.776]          : Fn 0194761 T1 0146 T2 21 T3 43 Time 00 02 07 555
[12:05:24.777]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.796]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.796]          : Fn 0194765 T1 0146 T2 25 T3 47 Time 00 02 07 573
[12:05:24.796]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.816]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.816]          : Fn 0194770 T1 0146 T2 04 T3 01 Time 00 02 07 596
[12:05:24.816]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.836]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.836]          : Fn 0194774 T1 0146 T2 08 T3 05 Time 00 02 07 615
[12:05:24.836]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.858]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.858]          : Fn 0194778 T1 0146 T2 12 T3 09 Time 00 02 07 633
[12:05:24.858]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.877]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.877]          : Fn 0194783 T1 0146 T2 17 T3 14 Time 00 02 07 656
[12:05:24.877]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.894]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.894]          : Fn 0194787 T1 0146 T2 21 T3 18 Time 00 02 07 675
[12:05:24.895]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.914]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.914]          : Fn 0194791 T1 0146 T2 25 T3 22 Time 00 02 07 693
[12:05:24.914]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.936]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.936]          : Fn 0194796 T1 0146 T2 04 T3 27 Time 00 02 07 716
[12:05:24.936]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.955]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.955]          : Fn 0194800 T1 0146 T2 08 T3 31 Time 00 02 07 735
[12:05:24.955]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.975]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.975]          : Fn 0194804 T1 0146 T2 12 T3 35 Time 00 02 07 753
[12:05:24.975]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:24.996]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:24.997]          : Fn 0194809 T1 0146 T2 17 T3 40 Time 00 02 07 776
[12:05:24.997]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.031]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.031]          : Fn 0194813 T1 0146 T2 21 T3 44 Time 00 02 07 795
[12:05:25.031]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.034]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.034]          : Fn 0194817 T1 0146 T2 25 T3 48 Time 00 02 07 813
[12:05:25.034]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.064]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.064]          : Fn 0194822 T1 0146 T2 04 T3 02 Time 00 02 07 836
[12:05:25.064]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.074]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.074]          : Fn 0194826 T1 0146 T2 08 T3 06 Time 00 02 07 855
[12:05:25.075]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.094]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.094]          : Fn 0194830 T1 0146 T2 12 T3 10 Time 00 02 07 873
[12:05:25.094]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.119]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.119]          : Fn 0194835 T1 0146 T2 17 T3 15 Time 00 02 07 896
[12:05:25.119]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.135]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.135]          : Fn 0194839 T1 0146 T2 21 T3 19 Time 00 02 07 915
[12:05:25.135]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.159]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.159]          : Fn 0194843 T1 0146 T2 25 T3 23 Time 00 02 07 933
[12:05:25.160]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.177]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.177]          : Fn 0194848 T1 0146 T2 04 T3 28 Time 00 02 07 956
[12:05:25.177]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.197]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.197]          : Fn 0194852 T1 0146 T2 08 T3 32 Time 00 02 07 975
[12:05:25.197]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.213]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.213]          : Fn 0194856 T1 0146 T2 12 T3 36 Time 00 02 07 993
[12:05:25.213]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.239]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.239]          : Fn 0194861 T1 0146 T2 17 T3 41 Time 00 02 08 016
[12:05:25.239]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.255]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.255]          : Fn 0194865 T1 0146 T2 21 T3 45 Time 00 02 08 035
[12:05:25.256]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.280]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.280]          : Fn 0194869 T1 0146 T2 25 T3 49 Time 00 02 08 053
[12:05:25.280]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.297]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.297]          : Fn 0194874 T1 0146 T2 04 T3 03 Time 00 02 08 076
[12:05:25.297]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.314]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.314]          : Fn 0194878 T1 0146 T2 08 T3 07 Time 00 02 08 095
[12:05:25.316]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.336]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.336]          : Fn 0194882 T1 0146 T2 12 T3 11 Time 00 02 08 113
[12:05:25.336]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.357]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.357]          : Fn 0194887 T1 0146 T2 17 T3 16 Time 00 02 08 136
[12:05:25.357]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.380]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.380]          : Fn 0194891 T1 0146 T2 21 T3 20 Time 00 02 08 155
[12:05:25.383]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.394]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.394]          : Fn 0194895 T1 0146 T2 25 T3 24 Time 00 02 08 173
[12:05:25.394]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.427]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.427]          : Fn 0194900 T1 0146 T2 04 T3 29 Time 00 02 08 196
[12:05:25.427]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.439]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.439]          : Fn 0194904 T1 0146 T2 08 T3 33 Time 00 02 08 215
[12:05:25.440]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.454]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.454]          : Fn 0194908 T1 0146 T2 12 T3 37 Time 00 02 08 233
[12:05:25.454]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.478]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.478]          : Fn 0194913 T1 0146 T2 17 T3 42 Time 00 02 08 256
[12:05:25.478]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.501]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.501]          : Fn 0194917 T1 0146 T2 21 T3 46 Time 00 02 08 275
[12:05:25.501]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.513]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.513]          : Fn 0194921 T1 0146 T2 25 T3 50 Time 00 02 08 293
[12:05:25.513]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.537]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.537]          : Fn 0194926 T1 0147 T2 04 T3 04 Time 00 02 08 316
[12:05:25.537]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.557]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.557]          : Fn 0194930 T1 0147 T2 08 T3 08 Time 00 02 08 335
[12:05:25.559]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.573]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.573]          : Fn 0194934 T1 0147 T2 12 T3 12 Time 00 02 08 353
[12:05:25.573]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.597]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.597]          : Fn 0194939 T1 0147 T2 17 T3 17 Time 00 02 08 376
[12:05:25.597]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.614]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.614]          : Fn 0194943 T1 0147 T2 21 T3 21 Time 00 02 08 395
[12:05:25.615]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.643]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.643]          : Fn 0194947 T1 0147 T2 25 T3 25 Time 00 02 08 413
[12:05:25.643]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.656]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.657]          : Fn 0194952 T1 0147 T2 04 T3 30 Time 00 02 08 436
[12:05:25.657]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.674]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.674]          : Fn 0194956 T1 0147 T2 08 T3 34 Time 00 02 08 455
[12:05:25.675]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.693]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.694]          : Fn 0194960 T1 0147 T2 12 T3 38 Time 00 02 08 473
[12:05:25.694]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.716]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.716]          : Fn 0194965 T1 0147 T2 17 T3 43 Time 00 02 08 496
[12:05:25.716]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.734]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.734]          : Fn 0194969 T1 0147 T2 21 T3 47 Time 00 02 08 515
[12:05:25.736]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.754]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.754]          : Fn 0194973 T1 0147 T2 25 T3 00 Time 00 02 08 533
[12:05:25.754]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.778]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.778]          : Fn 0194978 T1 0147 T2 04 T3 05 Time 00 02 08 556
[12:05:25.778]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.794]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.794]          : Fn 0194982 T1 0147 T2 08 T3 09 Time 00 02 08 575
[12:05:25.795]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.813]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.813]          : Fn 0194986 T1 0147 T2 12 T3 13 Time 00 02 08 593
[12:05:25.813]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.850]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.850]          : Fn 0194991 T1 0147 T2 17 T3 18 Time 00 02 08 616
[12:05:25.851]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.854]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.854]          : Fn 0194995 T1 0147 T2 21 T3 22 Time 00 02 08 635
[12:05:25.855]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.873]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.873]          : Fn 0194999 T1 0147 T2 25 T3 26 Time 00 02 08 653
[12:05:25.873]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.902]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.902]          : Fn 0195004 T1 0147 T2 04 T3 31 Time 00 02 08 676
[12:05:25.902]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.915]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.915]          : Fn 0195008 T1 0147 T2 08 T3 35 Time 00 02 08 695
[12:05:25.916]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.933]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.933]          : Fn 0195012 T1 0147 T2 12 T3 39 Time 00 02 08 713
[12:05:25.933]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.956]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.956]          : Fn 0195017 T1 0147 T2 17 T3 44 Time 00 02 08 736
[12:05:25.956]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.975]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.975]          : Fn 0195021 T1 0147 T2 21 T3 48 Time 00 02 08 755
[12:05:25.977]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:25.994]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:25.994]          : Fn 0195025 T1 0147 T2 25 T3 01 Time 00 02 08 773
[12:05:25.994]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.018]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.018]          : Fn 0195030 T1 0147 T2 04 T3 06 Time 00 02 08 796
[12:05:26.018]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.034]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.034]          : Fn 0195034 T1 0147 T2 08 T3 10 Time 00 02 08 815
[12:05:26.035]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.067]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.067]          : Fn 0195038 T1 0147 T2 12 T3 14 Time 00 02 08 833
[12:05:26.067]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.077]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.077]          : Fn 0195043 T1 0147 T2 17 T3 19 Time 00 02 08 856
[12:05:26.077]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.100]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.100]          : Fn 0195047 T1 0147 T2 21 T3 23 Time 00 02 08 875
[12:05:26.100]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.114]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.114]          : Fn 0195051 T1 0147 T2 25 T3 27 Time 00 02 08 893
[12:05:26.114]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.142]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.142]          : Fn 0195056 T1 0147 T2 04 T3 32 Time 00 02 08 916
[12:05:26.142]   L1S 02 : Tx Dummy on Tn 4 TxP 5\n
[12:05:26.224]   L1S 02 : Tx Dummy on Tn 5 TxP 5\n
[12:05:26.225]          : Fn 0195075 T1 0147 T2 23 T3 00 Time 00 02 08 999
[12:05:26.225]   PAL 12 : SynchChange[2500,168]
[12:05:26.225]   MM 09  : Dump : at 0x8209953D (5/5) : 32 f0 20 07 25
[12:05:26.225]   MM 09  : Dump : at 0x8209954E (6/6) : 32 f0 20 07 25 02
[12:05:26.225]   MM 09  : Dump : at 0x820994B7 (6/6) : 32 f0 20 07 25 02
[12:05:26.225]   MM 09  : Dump : at 0x820994C9 (6/6) : 32 f0 20 07 25 02
[12:05:26.326]   MM 09  : Att 1, Service 0x82, NMO 2
[12:05:28.229]          : Fn 0195510 T1 0147 T2 16 T3 27 Time 00 02 11 009
[12:05:28.341]   PAL 12 : SynchChange[0,126]
[12:07:40.248]          : Fn 0224114 T1 0169 T2 20 T3 20 Time 00 04 23 028
[12:07:40.358]   RRD 05 : rrd_FSM 0 WARNG: Evt 0x38081 unknown in rrd_VOID\n\n

My circuit scheme: image

PS: red part and battery are not connected yet! Most parts are assembled by JLCPCB (except Ai thinker and sim)

Power inductor for powering ai thinker - https://lcsc.com/product-detail/Power-Inductors_SXN-Shun-Xiang-Nuo-Elec-SMMS0650-4R7M_C149582.html Its little bit noisy but it should do work.. or might not in my case?

EDIT:

Can it be compilation linking issue?

[LDGEN]             >>> cust.ld
[LD]                test.elf
[ElfCombine]        Elf binary & map file

[GEN]               strip platform symbol
                    |
                     --S:/GRPS/SDK/platform/compilation/platform_symbols_to_strip
mips-elf-ld.exe: hex/test/test.elf: access beyond end of merged section (-99877408)

[SREC]              test.srec for flash/romulator
[LOD]               S:/GRPS/SDK/hex/test/test_flash.lod
[LODCOMBINE]        Combine with Platform lod
[LODCOMBINE]        Combine sucessful

glararan avatar Feb 06 '21 11:02 glararan

did your call demo work on the A9G Putting module? would you like me to try it...?

jcherrabi avatar Feb 06 '21 20:02 jcherrabi

@jcherrabi dont have pudding module at the moment.. if you can try would be nice.

glararan avatar Feb 07 '21 12:02 glararan

So finally i got working demo/call after downloading zkemble v2.129.

I just tested out my original code on older circuit which had everything ok. Well results similiar = freeze.

EDIT: so i have some progres...

first.. what the hell.. I replaced antenna https://lcsc.com/product-detail/Antennas_BAT-WIRELESS-BW433JWX50-10ZJ_C496575.html with another. It eliminates most of crashes... as A9 was searching all time for network registration. However my code sometimes fails to call..

glararan avatar Feb 07 '21 20:02 glararan

This might give some clues into the power issues and calling problems https://github.com/Ai-Thinker-Open/GPRS_C_SDK/issues/421

I replaced antenna https://lcsc.com/product-detail/Antennas_BAT-WIRELESS-BW433JWX50-10ZJ_C496575.html

That's a 433MHz antenna, not really meant for GSM frequencies, but it sounds like it works better than whatever you were using before :P

ZakKemble avatar Feb 10 '21 22:02 ZakKemble

@zkemble yea I have there two 470uF caps following 100uF tantalum, following 100nF.

Probably I can close as issue was resolved by bad antenna.

Link I posted is antenna first time I used. Then I replaced it with some another I found home from old gsm module. How did you recognize its 433MHz? I looked at datasheet couldn't find clue. So I thought its GSM one :D my bad.

EDIT: oh now I can see in link is W433. That can indicate 433MHz.. didnt know that..

glararan avatar Feb 11 '21 08:02 glararan

So I can confirm its SDK bug. This friday I went deploy device in building made of metal (90%). My short antenna wasnt able to catch enough signal which resulted into crash. However as I moved it few centimeters away I was able to catch signal - no crash. In bad place we used larger antenna.

However this issue should be opened. Unfortunately this SDK has no futher development :/

glararan avatar Feb 14 '21 12:02 glararan