none
win2003 搭建VPN服务器,客户端连接错误代码718 RRS feed

  • 问题

  • 几台win2003 系统的服务器都搭建了VPN(PPTP),配置相同但其中一台失败,客户端经过“正在核对用户名和密码... ”之后提示错误代码718,单网卡,windows验证和记账,如果客户端故意填错用户名或密码就提示用户名或密码错误

    C:\WINDOWS\tracing

    下面是服务器开启调试模式后新增的log,如果不开调试是不增加任何内容的。

    RTM.LOG

    [3976] 11:31:27: Adding dest 01BA46E0 to change list 15: 
    [3976] 11:31:27: Dest: 222.95.180.216 Mask: 255.255.255.255
    [3976] 11:31:27: 

    [3976] 11:31:27: Adding Route with address: 
    [3976] 11:31:27: Dest: 222.95.180.216 Mask: 255.255.255.255
    [3976] 11:31:27: Dest = 01BA46E0 and Route = 01BA4798

    [2292] 11:31:28: Entering ProcessChangedDestLists: 2292
    [2292] 11:31:28: ProcessChangedDestLists: Number of changed dests: 1
    [2292] 11:31:28: Next dest 01BA46E0 in list 15: 
    [2292] 11:31:28: Dest: 222.95.180.216 Mask: 255.255.255.255
    [2292] 11:31:28: 

    [2292] 11:31:28: Notifying CN 0 BEGIN
    [2292] 11:31:28: Returning dest 01BA46E0 to CN 0:
    [2292] 11:31:28: Dest: 222.95.180.216 Mask: 255.255.255.255
    [2292] 11:31:28: 

    [2292] 11:31:28: Notifying CN 0 END

    [2292] 11:31:28: Notifying CN 1 BEGIN
    [2292] 11:31:28: Returning dest 01BA46E0 to CN 1:
    [2292] 11:31:28: Dest: 222.95.180.216 Mask: 255.255.255.255
    [2292] 11:31:28: 

    [2292] 11:31:28: Notifying CN 1 END

    [2292] 11:31:28: ProcessChangedDestLists: Dests Removed: 1
    [2292] 11:31:28: Leaving  ProcessChangedDestLists: 2292

    IPRouterManager.LOG

    [2292] 11:31:28: Error adding route, Stack bit == 0
    [2292] 11:31:28: ProcessDefaultRouteChanges: Not default route 222.95.180.216/32

    IASSAM.LOG

    [1360] 03-09 11:31:36:796: NT-SAM Names handler received request with user identity vpn.
    [1360] 03-09 11:31:36:796: Prepending default domain.
    [1360] 03-09 11:31:36:796: NameMapper::prependDefaultDomain
    [1360] 03-09 11:31:36:796: SAM-Account-Name is "NOP-AA9F7A6C4C0\vpn".
    [1360] 03-09 11:31:36:796: NT-SAM Authentication handler received request for NOP-AA9F7A6C4C0\vpn.
    [1360] 03-09 11:31:36:796: Processing MS-CHAP v2 authentication.
    [1360] 03-09 11:31:36:796: LogonUser succeeded.
    [1360] 03-09 11:31:36:796: NT-SAM User Authorization handler received request for NOP-AA9F7A6C4C0\vpn.
    [1360] 03-09 11:31:36:796: Using NT5 local user parameters.
    [1360] 03-09 11:31:36:796: Using cached SAM connection to local account domain.
    [1360] 03-09 11:31:36:796: Successfully retrieved per-user attributes.

    IASNAP.LOG

    [1360] 03-09 11:31:36:796: Auto-generation of Session-Timeout is disabled.

    RASCHAP.LOG

    [1972] 03-09 11:31:32:796: ChapBegin(fS=1,bA=0x81)
    [1972] 03-09 11:31:32:796: ChapBegin done.
    [1972] 03-09 11:31:32:796: ChapMakeMessage,RBuf=00000000
    [1972] 03-09 11:31:32:796: CS_Initial...
    [1972] 03-09 11:31:32:796: MakeChallengeMessage...
    01 00 00 24 10 58 E6 E3 2B 73 C5 1D 98 FD 11 D6 |...$.X..+s......|
    30 02 9A 46 EA 4E 4F 50 2D 41 41 39 46 37 41 36 |0..F.NOP-AA9F7A6|
    43 34 43 30 00 00 00 00 00 00 00 00 00 00 00 00 |C4C0............|
    [1972] 03-09 11:31:34:625: ChapMakeMessage,RBuf=00000000
    [1972] 03-09 11:31:34:625: CS_ChallengeSent...
    [1972] 03-09 11:31:34:625: CS_Initial...
    [1972] 03-09 11:31:34:625: MakeChallengeMessage...
    01 01 00 24 10 0F BF 9A 83 65 A3 FC B1 38 8A EF |...$.....e...8..|
    55 84 C7 A9 3C 4E 4F 50 2D 41 41 39 46 37 41 36 |U...<NOP-AA9F7A6|
    43 34 43 30 00 00 00 00 00 00 00 00 00 00 00 00 |C4C0............|
    [1972] 03-09 11:31:36:625: ChapMakeMessage,RBuf=00000000
    [1972] 03-09 11:31:36:625: CS_ChallengeSent...
    [1972] 03-09 11:31:36:625: CS_Initial...
    [1972] 03-09 11:31:36:625: MakeChallengeMessage...
    01 02 00 24 10 12 95 A0 59 1A 69 2E DE 6B F2 E7 |...$....Y.i..k..|
    E9 35 BE 2B D3 4E 4F 50 2D 41 41 39 46 37 41 36 |.5.+.NOP-AA9F7A6|
    43 34 43 30 00 00 00 00 00 00 00 00 00 00 00 00 |C4C0............|
    [1972] 03-09 11:31:36:796: ChapMakeMessage,RBuf=00C43A6A
    [1972] 03-09 11:31:36:796: CS_ChallengeSent...
    [1972] 03-09 11:31:36:796: ChapMakeMessage,RBuf=00000000
    [1972] 03-09 11:31:36:796: Result=0,Tries=2
    [1972] 03-09 11:31:36:796: CS_Done...
    03 02 00 2E 53 3D 32 39 41 30 46 43 43 34 44 41 |....S=29A0FCC4DA|
    32 33 33 43 37 35 35 37 31 43 38 43 38 43 42 32 |233C75571C8C8CB2|
    30 37 33 31 43 39 42 37 43 41 33 41 39 41 00 00 |0731C9B7CA3A9A..|
    [1972] 03-09 11:34:03:625: ChapEnd

    RASMAN.LOG

    [1900] 03-09 11:31:27:453: WorkerThread: Async work event signaled on port: VPN4-127
    [1900] 03-09 11:31:27:453: OVEVT_DEV_ASYNCOP. pOverlapped = 0x12db50
    [1900] 03-09 11:31:27:453: ServiceWorkRequest: Async op event 27 for port VPN4-127 returned 600
    [1900] 03-09 11:31:27:453: WorkerThread: Async work event signaled on port: VPN4-127
    [1900] 03-09 11:31:27:453: OVEVT_DEV_ASYNCOP. pOverlapped = 0x12db50
    [1900] 03-09 11:31:27:453: ServiceWorkRequest: Async op event 27 for port VPN4-127 returned 600
    [1900] 03-09 11:31:27:453: WorkerThread: Async work event signaled on port: VPN4-127
    [1900] 03-09 11:31:27:453: OVEVT_DEV_ASYNCOP. pOverlapped = 0x12db50
    [1900] 03-09 11:31:27:453: d:\nt\net\rras\ras\rasman\rasman\request.c: 7623: port 128 state chg: prev=1, new=5
    [1900] 03-09 11:31:27:453: RasmanReceiveFlags = 0x0
    [1900] 03-09 11:31:27:453: d:\nt\net\rras\ras\rasman\rasman\worker.c: 2356: port 128 async reqtype chg: prev=27, new=0
    [1900] 03-09 11:31:27:453: ServiceWorkRequest: Async op event 27 for port VPN4-127 returned 0
    [1788] 03-09 11:31:27:453: ConnectCompleteRequest: entered for port 128
    [1788] 03-09 11:31:27:453: d:\nt\net\rras\ras\rasman\rasman\request.c: 7499: port 128 state chg: prev=5, new=2
    [1788] 03-09 11:31:27:453: AllocBundle: pBundle=0x8f82430

    [1788] 03-09 11:31:27:453: d:\nt\net\rras\ras\rasman\rasman\request.c 9017: Mapping Cookie to handle. port = VPN4-127(0x2b804e8), Bundlehandle = 0x35, linkhandle = 0x35
    [1788] 03-09 11:31:27:453: DwSaveIpAddressInfo: pszAddress=222.95.180.216
    [1788] 03-09 11:31:27:453: ConnectCompleteRequest: DwSaveIpAddressInfo returned 0x0
    [1788] 03-09 11:31:27:453: Connection Completed on port: VPN4-127, error code: 0
    [1788] 03-09 11:31:27:453: d:\nt\net\rras\ras\rasman\rasman\request.c: 6691: port 128 async reqtype chg: prev=0, new=26
    [1900] 03-09 11:31:32:625: Completed receive - First Frame on VPN4-127, handle=0x70c
    [1972] 03-09 11:31:32:625: GetConnectInfo: port VPN4-127
    [1972] 03-09 11:31:32:625: GetConnectInfo: size=52, rc=0xx0
    [1972] 03-09 11:31:32:625: GetConnectInfo: port VPN4-127
    [1972] 03-09 11:31:32:625: GetConnectInfo: size=52, rc=0xx0
    [1972] 03-09 11:31:32:625: GetConnectInfo: port VPN4-127
    [1972] 03-09 11:31:32:625: GetConnectInfo: size=52, rc=0xx0
    [1972] 03-09 11:31:32:625: GetConnectInfo: port VPN4-127
    [1972] 03-09 11:31:32:625: GetConnectInfo: size=52, rc=0xx0
    [1972] 03-09 11:31:32:625: PppStarted...VPN4-127
    [1972] 03-09 11:31:32:625: PortReceive: Invalid SendRcvBuffer passed
    [1788] 03-09 11:34:07:625: PortDisconnectRequest on VPN4-127 Connection=0x0 ,RasmanReceiveFlags=0x4
    [1788] 03-09 11:34:07:625: PortDisconnectRequest: Disconnecting VPN4-127
    [1788] 03-09 11:34:07:625: Disconnecting Port 0xVPN4-127, reason 0
    [1788] 03-09 11:34:07:625: DisconnectPort: Saving Bundle stats for port VPN4-127
    [1788] 03-09 11:34:07:625: d:\nt\net\rras\ras\rasman\rasman\util.c 1997: Disconnected Port 128, reason 0. rc=0x258
    [1788] 03-09 11:34:07:625: FreeBundle: freeing pBundle=0x8f82430
    [1788] 03-09 11:34:07:625: d:\nt\net\rras\ras\rasman\rasman\util.c: 2218: port 128 state chg: prev=2, new=3
    [1788] 03-09 11:34:07:625: d:\nt\net\rras\ras\rasman\rasman\util.c: 2288: port 128 async reqtype chg: prev=26, new=9
    [1788] 03-09 11:34:07:625: Disconnect request on port: VPN4-127
    [1900] 03-09 11:34:07:625: OVEVT_RASMAN_ADJUST_TIMER
    [1900] 03-09 11:34:07:625: OVEVT_RASMAN_ADJUST_TIMER
    [1900] 03-09 11:34:07:687: WorkerThread: Disconnect event signaled on port: VPN4-127
    [1900] 03-09 11:34:07:687: OVEVT_DEV_STATECHANGE. pOverlapped = 0x12db90
    [1900] 03-09 11:34:07:687: d:\nt\net\rras\ras\rasman\rasman\request.c: 4723: port 128 state chg: prev=3, new=4
    [1900] 03-09 11:34:07:687: d:\nt\net\rras\ras\rasman\rasman\request.c: 4734: port 128 async reqtype chg: prev=9, new=0
    [1900] 03-09 11:34:07:687: CompleteDisconnectRequest: signalling 0x1c98 for VPN4-127
    [1900] 03-09 11:34:07:687: Disconnect completed on port: VPN4-127
    [1788] 03-09 11:34:07:687: DeviceListenRequest: Clearing Autoclose flag on port VPN4-127
    [1788] 03-09 11:34:07:687: d:\nt\net\rras\ras\rasman\rasman\util.c: 2552: port 128 state chg: prev=4, new=1
    [1788] 03-09 11:34:07:687: d:\nt\net\rras\ras\rasman\rasman\util.c: 2583: port 128 async reqtype chg: prev=0, new=27
    [1788] 03-09 11:34:07:687: Listen posted on port: VPN4-127, error code 600

    RASTAPI.LOG

    [1920] 03-09 11:31:27:453: RasTapicallback: msg=23 , param1=0l , param2=65967l
    [1920] 03-09 11:31:27:453: RasTapicallback: msg=2 , param1=2l , param2=0l
    [1920] 03-09 11:31:27:453: RasTapicallback: linecallstate=0x2
    [1920] 03-09 11:31:27:453: DwProcessOfferEvent: hcall=0x101af
    [1920] 03-09 11:31:27:453: DwProcessOfferEvent: Accepting call on VPN4-127 hcall = 0x101af
    [1920] 03-09 11:31:27:453: WAN 微型端口 (PPTP) InClients=1, TotalDialInClients=1
    [1920] 03-09 11:31:27:453: DwProcessOfferEvent 0x0
    [1900] 03-09 11:31:27:453: DeviceWork: VPN4-127. State = 2
    [1900] 03-09 11:31:27:453: DeviceWork: VPN4-127. lineAccept returned 0x1019e
    [1920] 03-09 11:31:27:453: RasTapicallback: msg=12 , param1=65950l , param2=2147483720l
    [1920] 03-09 11:31:27:453: LINE_REPLY. param1=0x1019e
    [1920] 03-09 11:31:27:453: RasTapiCallback: Changing Listen State for VPN4-127 from 2 -> 3
    [1900] 03-09 11:31:27:453: DeviceWork: VPN4-127. State = 2
    [1900] 03-09 11:31:27:453: DeviceWork: VPN4-127. lineAnswer returned 0x102d2
    [1920] 03-09 11:31:27:453: RasTapicallback: msg=2 , param1=256l , param2=0l
    [1920] 03-09 11:31:27:453: RasTapicallback: linecallstate=0x100
    [1920] 03-09 11:31:27:453: DwGetConnectInfo
    [1920] 03-09 11:31:27:453: DwGetIDInformation
    [1920] 03-09 11:31:27:453: DwGetIDInformation. 0
    [1920] 03-09 11:31:27:453: SizeRequired for CallID=16
    [1920] 03-09 11:31:27:453: DwGetIDInformation
    [1920] 03-09 11:31:27:453: GetIDInformation: CallerID=222.95.180.216
    [1920] 03-09 11:31:27:453: RasTapiCallback: called id info. not avail
    [1920] 03-09 11:31:27:453: DwGetIDInformation. 0
    [1920] 03-09 11:31:27:453: DwGetConnectInfo. 0x0
    [1920] 03-09 11:31:27:453: RasTapiCallback: DwGetConnectInforeturned 0x0
    [1920] 03-09 11:31:27:453: RasTapiCallback: Connected on VPN4-127
    [1920] 03-09 11:31:27:453: RasTapiCallback: Incoming Call
    [1920] 03-09 11:31:27:453: RasTapicallback: msg=12 , param1=66258l , param2=0l
    [1920] 03-09 11:31:27:453: LINE_REPLY. param1=0x102d2
    [1900] 03-09 11:31:27:453: DeviceWork: VPN4-127. State = 2
    [1900] 03-09 11:31:27:453: DeviceWork: Changing State for VPN4-127 from 2 -> 3
    [1788] 03-09 11:31:27:453: PortConnect: VPN4-127
    [1788] 03-09 11:31:27:453:  
    [1788] 03-09 11:34:07:625: PortDisconnect: VPN4-127
    [1788] 03-09 11:34:07:625: InitiatePortDisconnection: VPN4-127
    [1788] 03-09 11:34:07:625: WAN 微型端口 (PPTP) InClients=0, TotalDialInClients=0
    [1788] 03-09 11:34:07:625: InitiatePortDisconnection: Changing state for VPN4-127 from 3 -> 5, id=0x10227
    [1788] 03-09 11:34:07:625:  
    [1788] 03-09 11:34:07:625:  
    [1920] 03-09 11:34:07:687: RasTapicallback: msg=2 , param1=1l , param2=0l
    [1920] 03-09 11:34:07:687: RasTapicallback: linecallstate=0x1
    [1920] 03-09 11:34:07:687: RasTapicallback: msg=12 , param1=66087l , param2=0l
    [1920] 03-09 11:34:07:687: LINE_REPLY. param1=0x10227
    [1920] 03-09 11:34:07:687: RasTapiCallback: lineDropped. port VPN4-127, id=0xffffffff
    [1920] 03-09 11:34:07:687: RasTapiCallback: Idle Received for port VPN4-127
    [1920] 03-09 11:34:07:687: RasTapiCallback: changing state of VPN4-127. 5 -> 1
    [1920] 03-09 11:34:07:687: RasTapiCallback: lineDeallocateCall for VPN4-127,hcall = 0x101af
    [1900] 03-09 11:34:07:687: PortTestSignalState: DeviceState = 0
    [1788] 03-09 11:34:07:687: DeviceListen: Changing State for VPN4-127 from 1 -> 2
    [1788] 03-09 11:34:07:687: DeviceListen: Changing Listen State for VPN4-127 from 4 -> 2
    [1788] 03-09 11:34:07:687:  

    tapi32.LOG

    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: msg=23, hDev=x10258, p1=x0, p2=x101af, p3=x4
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: posting msg, hwnd=x2004c
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: msg=2, hDev=x101af, p1=x2, p2=x0, p3=x0
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [INFO ] Calling app's callback, hDev=x10258, Msg=23, dwInst=x10a350 P1=x0, P2=x101af P3=x4
    [1920] 11:31:27:453: [INFO ] Calling app's callback, hDev=x101af, Msg=2, dwInst=x10a350 P1=x2, P2=x0 P3=x0
    [1920] 11:31:27:453: [TRACE] DoFunc  -- enter
    [1920] 11:31:27:453: [TRACE] AllocClientResources: enter
    [1920] 11:31:27:453: [TRACE] AllocClientResources: exit, returning x0
    [1920] 11:31:27:453: [TRACE] DoFunc: back from srv- return code=0x00000000
    [1920] 11:31:27:453: [TRACE] DoFunc: result = x0
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1900] 11:31:27:453: [TRACE] DoFunc  -- enter
    [1900] 11:31:27:453: [TRACE] AllocClientResources: enter
    [1900] 11:31:27:453: [TRACE] AllocClientResources: exit, returning x0
    [1900] 11:31:27:453: [TRACE] DoFunc: back from srv- return code=0x0001019e
    [1900] 11:31:27:453: [TRACE] DoFunc: result = x1019e
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: msg=12, hDev=x0, p1=x1019e, p2=x80000048, p3=x0
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: posting msg, hwnd=x2004c
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [INFO ] Calling app's callback, hDev=x0, Msg=12, dwInst=x10a350 P1=x1019e, P2=x80000048 P3=x0
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1900] 11:31:27:453: [TRACE] DoFunc  -- enter
    [1900] 11:31:27:453: [TRACE] AllocClientResources: enter
    [1900] 11:31:27:453: [TRACE] AllocClientResources: exit, returning x0
    [1900] 11:31:27:453: [TRACE] DoFunc: back from srv- return code=0x000102d2
    [1900] 11:31:27:453: [TRACE] DoFunc: result = x102d2
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: msg=2, hDev=x101af, p1=x100, p2=x0, p3=x0
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: posting msg, hwnd=x2004c
    [1960] 11:31:27:453: [INFO ] AsyncEventsThread: msg=12, hDev=x0, p1=x102d2, p2=x0, p3=x0
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [INFO ] Calling app's callback, hDev=x101af, Msg=2, dwInst=x10a350 P1=x100, P2=x0 P3=x0
    [1920] 11:31:27:453: [TRACE] DoFunc  -- enter
    [1920] 11:31:27:453: [TRACE] AllocClientResources: enter
    [1920] 11:31:27:453: [TRACE] AllocClientResources: exit, returning x0
    [1920] 11:31:27:453: [TRACE] DoFunc: back from srv- return code=0x00000000
    [1920] 11:31:27:453: [TRACE] DoFunc: result = x0
    [1920] 11:31:27:453: [INFO ] Calling app's callback, hDev=x0, Msg=12, dwInst=x10a350 P1=x102d2, P2=x0 P3=x0
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1920] 11:31:27:453: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:31:27:453: [TRACE] No entries - spurious entry.
    [1788] 11:31:27:453: [TRACE] DoFunc  -- enter
    [1788] 11:31:27:453: [TRACE] AllocClientResources: enter
    [1788] 11:31:27:453: [TRACE] AllocClientResources: exit, returning x0
    [1788] 11:31:27:453: [TRACE] DoFunc: back from srv- return code=0x00000000
    [1788] 11:31:27:453: [TRACE] DoFunc: result = x0
    [1788] 11:31:27:453: [TRACE] DoFunc  -- enter
    [1788] 11:31:27:453: [TRACE] AllocClientResources: enter
    [1788] 11:31:27:453: [TRACE] AllocClientResources: exit, returning x0
    [1788] 11:31:27:453: [TRACE] DoFunc: back from srv- return code=0x00000000
    [1788] 11:31:27:453: [TRACE] DoFunc: result = x0
    [1788] 11:34:07:625: [TRACE] DoFunc  -- enter
    [1788] 11:34:07:625: [TRACE] AllocClientResources: enter
    [1788] 11:34:07:625: [TRACE] AllocClientResources: exit, returning x0
    [1788] 11:34:07:625: [TRACE] DoFunc: back from srv- return code=0x00010227
    [1788] 11:34:07:625: [TRACE] DoFunc: result = x10227
    [1960] 11:34:07:687: [INFO ] AsyncEventsThread: msg=2, hDev=x101af, p1=x1, p2=x0, p3=x0
    [1960] 11:34:07:687: [INFO ] AsyncEventsThread: posting msg, hwnd=x2004c
    [1960] 11:34:07:687: [INFO ] AsyncEventsThread: msg=12, hDev=x0, p1=x10227, p2=x0, p3=x0
    [1920] 11:34:07:687: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:34:07:687: [INFO ] Calling app's callback, hDev=x101af, Msg=2, dwInst=x10a350 P1=x1, P2=x0 P3=x0
    [1920] 11:34:07:687: [INFO ] Calling app's callback, hDev=x0, Msg=12, dwInst=x10a350 P1=x10227, P2=x0 P3=x0
    [1920] 11:34:07:687: [TRACE] lineDeallocateCall: enter on thread: 0x00000780
    [1920] 11:34:07:687: [INFO ]   hCall = 0x000101af
    [1920] 11:34:07:687: [TRACE] DoFunc  -- enter
    [1920] 11:34:07:687: [TRACE] AllocClientResources: enter
    [1920] 11:34:07:687: [TRACE] AllocClientResources: exit, returning x0
    [1920] 11:34:07:687: [TRACE] DoFunc: back from srv- return code=0x00000000
    [1920] 11:34:07:687: [TRACE] DoFunc: result = x0
    [1920] 11:34:07:687: [TRACE] No entries - spurious entry.
    [1920] 11:34:07:687: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:34:07:687: [TRACE] No entries - spurious entry.
    [1920] 11:34:07:687: [TRACE] TAPIWndProc: received WM_ASYNCEVENT, hwnd=x0002004C
    [1920] 11:34:07:687: [TRACE] No entries - spurious entry.

    KMDDSP.LOG

    [1944] 11:31:27:453: !   AsyncEventsThread: got a line event
    [1944] 11:31:27:453: !   ProcessEvent: event(000A7708), msg(1f4), ht_line(00480000), ht_call(00000000), p1(0000905D), p2(8000005F), p3(00000000)
    [1944] 11:31:27:453: !   PE::fnLineEvent(NEWCALL): htline(00010247), call(00780004)
    [1944] 11:31:27:453: !   AsyncEventsThread: got a line event
    [1944] 11:31:27:453: !   ProcessEvent: event(000A7708), msg(2), ht_line(00480000), ht_call(8000005F), p1(00000002), p2(00000000), p3(00000100)
    [1944] 11:31:27:453: !   PE::fnLineEvent(CALLSTATE): htline(00010247), htcall(00010349), p1(00000002), p2(00000000), p3(00000100)
    [1856] 11:31:27:453:     lineGetCallInfo(189): call(00780004)
    [1856] 11:31:27:453: !   SyncDriverRequest: oid(GetCallInfo), devID(1), reqID(1fe), hdCall(0000905D)
    [984] 11:31:27:453:     lineGetCallInfo(190): call(00780004)
    [984] 11:31:27:453: !   SyncDriverRequest: oid(GetCallInfo), devID(1), reqID(1ff), hdCall(0000905D)
    [984] 11:31:27:453:     lineAccept(48): reqID(1019e), call(00780004)
    [984] 11:31:27:453: !   AsyncDriverRequest: oid(Accept), devID(1), ReqID(1019e), reqID(200), hdCall(905d)
    [1944] 11:31:27:453: !   AsyncEventsThread: got a completed req
    [1944] 11:31:27:453: !   AsyncEventsThread: req(000B2428) with reqID(1019e) returned lRes(80000048)
    [1944] 11:31:27:453:     AsyncEventsThread: call compproc with ReqID(1019e), lRes(80000048)
    [984] 11:31:27:453:     lineAnswer(48): reqID(102d2), call(00780004)
    [984] 11:31:27:453: !   AsyncDriverRequest: oid(Answer), devID(1), ReqID(102d2), reqID(201), hdCall(905d)
    [1944] 11:31:27:453: !   AsyncEventsThread: got a line event
    [1944] 11:31:27:453: !   ProcessEvent: event(000A7708), msg(2), ht_line(00480000), ht_call(8000005F), p1(00000100), p2(00000000), p3(00000100)
    [1944] 11:31:27:453: !   PE::fnLineEvent(CALLSTATE): htline(00010247), htcall(00010349), p1(00000100), p2(00000000), p3(00000100)
    [1944] 11:31:27:453: !   AsyncEventsThread: got a completed req
    [1944] 11:31:27:453: !   AsyncEventsThread: req(000B2428) with reqID(102d2) returned lRes(0)
    [1944] 11:31:27:453:     AsyncEventsThread: call compproc with ReqID(102d2), lRes(0)
    [984] 11:31:27:453:     lineGetCallInfo(191): call(00780004)
    [984] 11:31:27:453: !   SyncDriverRequest: oid(GetCallInfo), devID(1), reqID(202), hdCall(0000905D)
    [984] 11:31:27:453:     lineGetCallInfo(192): call(00780004)
    [984] 11:31:27:453: !   SyncDriverRequest: oid(GetCallInfo), devID(1), reqID(203), hdCall(0000905D)
    [984] 11:31:27:453:     lineGetID(50): line(00000000), call(00780004), addressID(0), select(4)
    [984] 11:31:27:453: !   SyncDriverRequest: oid(GetID), devID(1), reqID(204), hdLine(00000000)
    [3212] 11:34:07:625:     lineDrop(54): reqID(10227), call(00780004)
    [3212] 11:34:07:625: !   AsyncDriverRequest: oid(Drop), devID(1), ReqID(10227), reqID(205), hdCall(905d)
    [1944] 11:34:07:687: !   AsyncEventsThread: got a completed req
    [1944] 11:34:07:687: !   AsyncEventsThread: req(000B2428) with reqID(10227) returned lRes(0)
    [1944] 11:34:07:687:     lineDrop_post: lRes(0)
    [1944] 11:34:07:687: !   postDrop::fnLineEvent(CALLSTATE_IDLE): htline(00010247), htcall(00010349)
    [1944] 11:34:07:687:     AsyncEventsThread: call compproc with ReqID(10227), lRes(0)
    [1944] 11:34:07:687: !   AsyncEventsThread: got a line event
    [1944] 11:34:07:687: !   ProcessEvent: event(000A7708), msg(2), ht_line(00480000), ht_call(8000005F), p1(00004000), p2(00000000), p3(00000100)
    [3212] 11:34:07:687:     lineCloseCall(48): call(00780004)
    [3212] 11:34:07:687: !   SyncDriverRequest: oid(CloseCall), devID(1), reqID(206), hdCall(0000905D)

    tapisrv.LOG

    [1944] 11:31:27:453: [TRACE] LineEventProc
    [1944] 11:31:27:453: [TRACE] CreatetCall: enter, ptLine=005D5F10
    [1944] 11:31:27:453: [INFO ] CreatetCall: calling NewObject ptCall 005D6148
    [1944] 11:31:27:453: [TRACE] CreatetCall: NewObject returned 0x10349
    [1944] 11:31:27:453: [TRACE] CreatetCall: exit, new ptCall=005D6148
    [1944] 11:31:27:453: [INFO ] LineEventProcSP: HTapiLine=00010247, HTapiCall=00010349, msg=2, P1=x2, P2=x0, P3=x100
    [1856] 11:31:27:453: [INFO ] Got a line spevent, htLine = 0x10247, htCall = 0x10349, dwMsg = 0x2
    [1856] 11:31:27:453: [TRACE] LineEventProc
    [1856] 11:31:27:453: [EVENT] LineEventProc: LINE_CALLSTATE event x2
    [1856] 11:31:27:453: [TRACE] CreatetCallClient: enter, ptCall=005D6148,
    [1856] 11:31:27:453: [INFO ] CreatetCallClient: calling NewObject, ptCallClient = [005D61C4]
    [1856] 11:31:27:453: [TRACE] CreatetCallClient: exit, new ptCallClient=005D61C4
    [1856] 11:31:27:453: [INFO ] LineEventProc: i = [0] corresponding ptCallClient [005D61C4]
    [1856] 11:31:27:453: [INFO ] LineEventProc: ptCallClient->ptLineClient[005D5E28]
    [1856] 11:31:27:453: [INFO ] LineEventProc: ptLineClient->ptLineApp[005D2CD0]
    [1856] 11:31:27:453: [INFO ] LineEventProc: setting msg->InitContext to ptLineApp[005D2CD0]->InitContext of [800003FF]
    [1856] 11:31:27:453: [INFO ] LineEventProc: setting msg->OpenContext to [80000311]
    [1856] 11:31:27:453: [INFO ] FMsgDisbled: dwAPIVersion<= TAPI_VERSION3_0, msg will be enabled
    [1856] 11:31:27:453: [TRACE] FMsgDisabled return 0
    [1856] 11:31:27:453: [INFO ] LineEventProc: sending LINE_APPNEWCALL, ptClient[005D26A0]
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer - enter
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [1856] 11:31:27:453: [INFO ] FMsgDisbled: dwAPIVersion<= TAPI_VERSION3_0, msg will be enabled
    [1856] 11:31:27:453: [TRACE] FMsgDisabled return 0
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer - enter
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [3212] 11:31:27:453: [TRACE] GetAsyncEvents: enter (TID=3212)
    [3212] 11:31:27:453: [INFO ] M ebfused:x60  pEvtBuf: 0x005D27B0  pDataOut:0x005D27B0  pDataIn:0x005D2810
    [3212] 11:31:27:453: [TRACE] GetAsyncEvents: return dwUsedBufferSize:x60
    [984] 11:31:27:453: [TRACE] LineProlog:  -- enter
    [984] 11:31:27:453: [INFO ] LineProlog: dwArg 101af
    [984] 11:31:27:453: [INFO ] LineProlog: ANY_RT_HCALL 
    [984] 11:31:27:453: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [984] 11:31:27:453: [TRACE] LienProlog: exit, result = x0
    [984] 11:31:27:453: [TRACE] LineEpilogSync: exit, result=x0
    [984] 11:31:27:453: [TRACE] LineProlog:  -- enter
    [984] 11:31:27:453: [INFO ] LineProlog: dwArg 101af
    [984] 11:31:27:453: [INFO ] LineProlog: ANY_RT_HCALL 
    [984] 11:31:27:453: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [984] 11:31:27:453: [INFO ] LineProlog: OpenContext 80000311
    [984] 11:31:27:453: [TRACE] LienProlog: exit, result = x1019e
    [984] 11:31:27:453: [TRACE] LineEpilogAsyc: exit, result=x1019e
    [1944] 11:31:27:453: [TRACE] CompletionProcSP: enter, dwReqID=x1019e, lResult=x80000048
    [1856] 11:31:27:453: [INFO ] Got an async completion event, requestID = 0x1019e, htXxx = 0x10247, lResult = 0x80000048
    [1856] 11:31:27:453: [TRACE] CompletionProc: enter, dwReqID=x1019e, lResult=x80000048
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer - enter
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [3212] 11:31:27:453: [TRACE] GetAsyncEvents: enter (TID=3212)
    [3212] 11:31:27:453: [INFO ] M ebfused:x28  pEvtBuf: 0x005D27B0  pDataOut:0x005D27B0  pDataIn:0x005D27D8
    [3212] 11:31:27:453: [TRACE] GetAsyncEvents: return dwUsedBufferSize:x28
    [984] 11:31:27:453: [TRACE] LineProlog:  -- enter
    [984] 11:31:27:453: [INFO ] LineProlog: dwArg 101af
    [984] 11:31:27:453: [INFO ] LineProlog: ANY_RT_HCALL 
    [984] 11:31:27:453: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [984] 11:31:27:453: [INFO ] LineProlog: OpenContext 80000311
    [984] 11:31:27:453: [TRACE] LienProlog: exit, result = x102d2
    [984] 11:31:27:453: [TRACE] LineEpilogAsyc: exit, result=x102d2
    [1944] 11:31:27:453: [INFO ] LineEventProcSP: HTapiLine=00010247, HTapiCall=00010349, msg=2, P1=x100, P2=x0, P3=x100
    [1856] 11:31:27:453: [INFO ] Got a line spevent, htLine = 0x10247, htCall = 0x10349, dwMsg = 0x2
    [1856] 11:31:27:453: [TRACE] LineEventProc
    [1856] 11:31:27:453: [EVENT] LineEventProc: LINE_CALLSTATE event x100
    [1856] 11:31:27:453: [INFO ] LineEventProc: i = [0] corresponding ptCallClient [005D61C4]
    [1856] 11:31:27:453: [INFO ] LineEventProc: ptCallClient->ptLineClient[005D5E28]
    [1856] 11:31:27:453: [INFO ] LineEventProc: ptLineClient->ptLineApp[005D2CD0]
    [1856] 11:31:27:453: [INFO ] LineEventProc: setting msg->InitContext to ptLineApp[005D2CD0]->InitContext of [800003FF]
    [1856] 11:31:27:453: [INFO ] LineEventProc: setting msg->OpenContext to [80000311]
    [1856] 11:31:27:453: [INFO ] FMsgDisbled: dwAPIVersion<= TAPI_VERSION3_0, msg will be enabled
    [1856] 11:31:27:453: [TRACE] FMsgDisabled return 0
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer - enter
    [1944] 11:31:27:453: [TRACE] CompletionProcSP: enter, dwReqID=x102d2, lResult=x0
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [1856] 11:31:27:453: [INFO ] Got an async completion event, requestID = 0x102d2, htXxx = 0x10247, lResult = 0x0
    [1856] 11:31:27:453: [TRACE] CompletionProc: enter, dwReqID=x102d2, lResult=x0
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer - enter
    [1856] 11:31:27:453: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [3212] 11:31:27:453: [TRACE] GetAsyncEvents: enter (TID=3212)
    [3212] 11:31:27:453: [INFO ] M ebfused:x54  pEvtBuf: 0x005D27B0  pDataOut:0x005D27B0  pDataIn:0x005D2804
    [3212] 11:31:27:453: [TRACE] GetAsyncEvents: return dwUsedBufferSize:x54
    [984] 11:31:27:453: [TRACE] LineProlog:  -- enter
    [984] 11:31:27:453: [INFO ] LineProlog: dwArg 101af
    [984] 11:31:27:453: [INFO ] LineProlog: ANY_RT_HCALL 
    [984] 11:31:27:453: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [984] 11:31:27:453: [TRACE] LienProlog: exit, result = x0
    [984] 11:31:27:453: [TRACE] LineEpilogSync: exit, result=x0
    [984] 11:31:27:453: [TRACE] LineProlog:  -- enter
    [984] 11:31:27:453: [INFO ] LineProlog: dwArg 101af
    [984] 11:31:27:453: [INFO ] LineProlog: ANY_RT_HCALL 
    [984] 11:31:27:453: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [984] 11:31:27:453: [TRACE] LienProlog: exit, result = x0
    [984] 11:31:27:453: [TRACE] LineEpilogSync: exit, result=x0
    [984] 11:31:27:453: [TRACE] LineProlog:  -- enter
    [984] 11:31:27:453: [INFO ] LineProlog: dwArg 101af
    [984] 11:31:27:453: [INFO ] LineProlog: ANY_RT_HCALL 
    [984] 11:31:27:453: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [984] 11:31:27:453: [TRACE] LienProlog: exit, result = x0
    [984] 11:31:27:453: [TRACE] LineEpilogSync: exit, result=x0
    [3212] 11:34:07:625: [TRACE] LineProlog:  -- enter
    [3212] 11:34:07:625: [INFO ] LineProlog: dwArg 101af
    [3212] 11:34:07:625: [INFO ] LineProlog: ANY_RT_HCALL 
    [3212] 11:34:07:625: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [3212] 11:34:07:625: [INFO ] LineProlog: OpenContext 80000311
    [3212] 11:34:07:625: [TRACE] LienProlog: exit, result = x10227
    [3212] 11:34:07:625: [TRACE] LineEpilogAsyc: exit, result=x10227
    [1944] 11:34:07:687: [INFO ] LineEventProcSP: HTapiLine=00010247, HTapiCall=00010349, msg=2, P1=x1, P2=x0, P3=x0
    [1944] 11:34:07:687: [TRACE] CompletionProcSP: enter, dwReqID=x10227, lResult=x0
    [1856] 11:34:07:687: [INFO ] Got a line spevent, htLine = 0x10247, htCall = 0x10349, dwMsg = 0x2
    [1856] 11:34:07:687: [TRACE] LineEventProc
    [1856] 11:34:07:687: [EVENT] LineEventProc: LINE_CALLSTATE event x1
    [1856] 11:34:07:687: [INFO ] LineEventProc: i = [0] corresponding ptCallClient [005D61C4]
    [1856] 11:34:07:687: [INFO ] LineEventProc: ptCallClient->ptLineClient[005D5E28]
    [1856] 11:34:07:687: [INFO ] LineEventProc: ptLineClient->ptLineApp[005D2CD0]
    [1856] 11:34:07:687: [INFO ] LineEventProc: setting msg->InitContext to ptLineApp[005D2CD0]->InitContext of [800003FF]
    [1856] 11:34:07:687: [INFO ] LineEventProc: setting msg->OpenContext to [80000311]
    [1856] 11:34:07:687: [INFO ] FMsgDisbled: dwAPIVersion<= TAPI_VERSION3_0, msg will be enabled
    [1856] 11:34:07:687: [TRACE] FMsgDisabled return 0
    [1856] 11:34:07:687: [TRACE] WriteEventBuffer - enter
    [1856] 11:34:07:687: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [1856] 11:34:07:687: [INFO ] Got an async completion event, requestID = 0x10227, htXxx = 0x10247, lResult = 0x0
    [1856] 11:34:07:687: [TRACE] CompletionProc: enter, dwReqID=x10227, lResult=x0
    [1856] 11:34:07:687: [TRACE] WriteEventBuffer - enter
    [1856] 11:34:07:687: [TRACE] WriteEventBuffer: SetEvent 00000150 for local client
    [984] 11:34:07:687: [TRACE] GetAsyncEvents: enter (TID=984)
    [984] 11:34:07:687: [INFO ] M ebfused:x54  pEvtBuf: 0x005D27B0  pDataOut:0x005D27B0  pDataIn:0x005D2804
    [984] 11:34:07:687: [TRACE] GetAsyncEvents: return dwUsedBufferSize:x54
    [3212] 11:34:07:687: [TRACE] LineProlog:  -- enter
    [3212] 11:34:07:687: [INFO ] LineProlog: dwArg 101af
    [3212] 11:34:07:687: [INFO ] LineProlog: ANY_RT_HCALL 
    [3212] 11:34:07:687: [INFO ] LineProlog: ReferenceObject returned ptCallClient 005D61C4
    [3212] 11:34:07:687: [TRACE] LienProlog: exit, result = x0
    [3212] 11:34:07:687: [TRACE] DestroytCallClient: enter, ptCallCli=x005D61C4
    [3212] 11:34:07:687: [TRACE] DestroytCall: enter, ptCall=x005D6148
    [3212] 11:34:07:687: [TRACE] LineEpilogSync: exit, result=x0

    RASAUTH.LOG

    [1360] 11:31:36:796: RasAuthProviderAuthenticateUser called
    [1360] 11:31:36:796: Type=44, Length=2, Value=
    [1360] 11:31:36:796: 38 39 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |89..............|
    [1360] 11:31:36:796: Type=4, Length=4, Value=0x31d4b343
    [1360] 11:31:36:796: Type=6, Length=4, Value=0x2
    [1360] 11:31:36:796: Type=7, Length=4, Value=0x1
    [1360] 11:31:36:796: Type=5, Length=4, Value=0x80
    [1360] 11:31:36:796: Type=26, Length=10, Value=
    [1360] 11:31:36:796: 00 00 01 37 09 06 00 00 01 37 00 00 00 00 00 00 |...7.....7......|
    [1360] 11:31:36:796: Type=26, Length=16, Value=
    [1360] 11:31:36:796: 00 00 01 37 12 0C 4D 53 52 41 53 56 35 2E 32 30 |...7..MSRASV5.20|
    [1360] 11:31:36:796: Type=61, Length=4, Value=0x5
    [1360] 11:31:36:796: Type=64, Length=4, Value=0x1
    [1360] 11:31:36:796: Type=65, Length=4, Value=0x1
    [1360] 11:31:36:796: Type=31, Length=14, Value=
    [1360] 11:31:36:796: 32 32 32 2E 39 35 2E 31 38 30 2E 32 31 36 00 00 |222.95.180.216..|
    [1360] 11:31:36:796: Type=66, Length=14, Value=
    [1360] 11:31:36:796: 32 32 32 2E 39 35 2E 31 38 30 2E 32 31 36 00 00 |222.95.180.216..|
    [1360] 11:31:36:796: Type=26, Length=16, Value=
    [1360] 11:31:36:796: 00 00 01 37 23 0C 4D 53 52 41 53 56 35 2E 32 30 |...7#.MSRASV5.20|
    [1360] 11:31:36:796: Type=26, Length=17, Value=
    [1360] 11:31:36:796: 00 00 01 37 22 0D 4D 53 52 41 53 2D 30 2D 4E 4F |...7".MSRAS-0-NO|
    [1360] 11:31:36:796: 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |P...............|
    [1360] 11:31:36:796: Type=1, Length=3, Value=
    [1360] 11:31:36:796: 76 70 6E 00 00 00 00 00 00 00 00 00 00 00 00 00 |vpn.............|
    [1360] 11:31:36:796: Type=26, Length=22, Value=
    [1360] 11:31:36:796: 00 00 01 37 0B 12 12 95 A0 59 1A 69 2E DE 6B F2 |...7.....Y.i..k.|
    [1360] 11:31:36:796: E7 E9 35 BE 2B D3 00 00 00 00 00 00 00 00 00 00 |..5.+...........|
    [1360] 11:31:36:796: Type=26, Length=56, Value=
    [1360] 11:31:36:796: 00 00 01 37 19 34 02 00 F4 67 43 1C 6F 55 7A 08 |...7.4...gC.oUz.|
    [1360] 11:31:36:796: F3 10 32 74 18 F7 C7 31 00 00 00 00 00 00 00 00 |..2t...1........|
    [1360] 11:31:36:796: 55 41 68 51 5B 2D FB EC 35 DF FD 27 32 AC 11 81 |UAhQ[-..5..'2...|
    [1360] 11:31:36:796: 37 A1 F9 8D 9E 76 9C 55 00 00 00 00 00 00 00 00 |7....v.U........|
    [1360] 11:31:36:796: Inserting attribute type 44
    [1360] 11:31:36:796: Inserting attribute type 4
    [1360] 11:31:36:796: Inserting attribute type 6
    [1360] 11:31:36:796: Inserting attribute type 7
    [1360] 11:31:36:796: Inserting attribute type 5
    [1360] 11:31:36:796: Inserting attribute type 26
    [1360] 11:31:36:796: Inserting attribute type 26
    [1360] 11:31:36:796: Inserting attribute type 61
    [1360] 11:31:36:796: Inserting attribute type 64
    [1360] 11:31:36:796: Inserting attribute type 65
    [1360] 11:31:36:796: Inserting attribute type 31
    [1360] 11:31:36:796: Inserting attribute type 66
    [1360] 11:31:36:796: Inserting attribute type 26
    [1360] 11:31:36:796: Inserting attribute type 26
    [1360] 11:31:36:796: Inserting attribute type 1
    [1360] 11:31:36:796: Inserting attribute type 26
    [1360] 11:31:36:796: Inserting attribute type 26
    [1360] 11:31:36:796: Inserting attribute type 4108
    [1360] 11:31:36:796: IASResponse = ACCESS_ACCEPT
    [1360] 11:31:36:796: Received attribute 4111
    [1360] 11:31:36:796: Received attribute 4106
    [1360] 11:31:36:796: Received attribute 4105
    [1360] 11:31:36:796: Received attribute 4105
    [1360] 11:31:36:796: Received attribute 8
    [1360] 11:31:36:796: Received attribute 7
    [1360] 11:31:36:796: Received attribute 6
    [1360] 11:31:36:796: Received attribute 25
    [1360] 11:31:36:796: Received attribute 26
    [1360] 11:31:36:796: Received attribute 26
    [1360] 11:31:36:796: Received attribute 26
    [1360] 11:31:36:796: Received attribute 26
    [1360] 11:31:36:796: Received attribute 26
    [1360] 11:31:36:796: Type=4111, Length=4, Value=0x1
    [1360] 11:31:36:796: Type=4106, Length=4, Value=0xd
    [1360] 11:31:36:796: Type=4105, Length=4, Value=0x5
    [1360] 11:31:36:796: Type=4105, Length=4, Value=0x4
    [1360] 11:31:36:796: Type=8, Length=4, Value=0xfffffffe
    [1360] 11:31:36:796: Type=7, Length=4, Value=0x1
    [1360] 11:31:36:796: Type=6, Length=4, Value=0x2
    [1360] 11:31:36:796: Type=25, Length=30, Value=
    [1360] 11:31:36:796: 53 FC 05 D4 00 00 01 37 00 01 31 D4 B3 43 01 CE |S......7..1..C..|
    [1360] 11:31:36:796: 1C 26 97 FE 5C 8C 00 00 00 00 00 00 00 11 00 00 |.&..\...........|
    [1360] 11:31:36:796: Type=26, Length=40, Value=
    [1360] 11:31:36:796: MS vendor specific 17
    [1360] 11:31:36:796: Type=26, Length=40, Value=
    [1360] 11:31:36:796: MS vendor specific 16
    [1360] 11:31:36:796: Type=26, Length=49, Value=
    [1360] 11:31:36:796: 00 00 01 37 1A 2D 02 53 3D 32 39 41 30 46 43 43 |...7.-.S=29A0FCC|
    [1360] 11:31:36:796: 34 44 41 32 33 33 43 37 35 35 37 31 43 38 43 38 |4DA233C75571C8C8|
    [1360] 11:31:36:796: 43 42 32 30 37 33 31 43 39 42 37 43 41 33 41 39 |CB20731C9B7CA3A9|
    [1360] 11:31:36:796: 41 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |A...............|
    [1360] 11:31:36:796: Type=26, Length=68, Value=
    [1360] 11:31:36:796: 00 00 01 37 0A 40 02 4E 4F 50 2D 41 41 39 46 37 |...7.@.NOP-AA9F7|
    [1360] 11:31:36:796: 41 36 43 34 43 30 08 3F 3F 3F AE 6B 3F C3 B2 3F |A6C4C0.???.k?..?|
    [1360] 11:31:36:796: 3F 3F F2 81 DF 5D 9D DF 3F A7 F6 DA 6E 3F 3F 3F |??...]..?...n???|
    [1360] 11:31:36:796: 3F A9 83 E3 64 84 43 3F E1 BF 3F 3F 3F 3F 3F 3F |?...d.C?..??????|
    [1360] 11:31:36:796: 3F C2 52 3F 00 00 00 00 00 00 00 00 00 00 00 00 |?.R?............|
    [1360] 11:31:36:796: Type=26, Length=78, Value=
    [1360] 11:31:36:796: 00 00 01 37 16 4A 01 00 00 00 48 00 00 00 01 00 |...7.J....H.....|
    [1360] 11:31:36:796: 00 00 01 00 FF FF 28 00 00 00 01 00 00 00 20 00 |......(....... .|
    [1360] 11:31:36:796: 00 00 00 00 00 00 01 00 00 00 01 00 00 00 01 00 |................|
    [1360] 11:31:36:796: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
    [1360] 11:31:36:796: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
    [1360] 11:34:09:687: RasStopAccounting called
    [1360] 11:34:09:687: Type=4, Length=4, Value=0x31d4b343
    [1360] 11:34:09:687: Type=6, Length=4, Value=0x2
    [1360] 11:34:09:687: Type=7, Length=4, Value=0x1
    [1360] 11:34:09:687: Type=5, Length=4, Value=0x80
    [1360] 11:34:09:687: Type=26, Length=10, Value=
    [1360] 11:34:09:687: 00 00 01 37 09 06 00 00 01 37 00 00 00 00 00 00 |...7.....7......|
    [1360] 11:34:09:687: Type=26, Length=16, Value=
    [1360] 11:34:09:687: 00 00 01 37 12 0C 4D 53 52 41 53 56 35 2E 32 30 |...7..MSRASV5.20|
    [1360] 11:34:09:687: Type=61, Length=4, Value=0x5
    [1360] 11:34:09:687: Type=64, Length=4, Value=0x1
    [1360] 11:34:09:687: Type=65, Length=4, Value=0x1
    [1360] 11:34:09:687: Type=31, Length=14, Value=
    [1360] 11:34:09:687: 32 32 32 2E 39 35 2E 31 38 30 2E 32 31 36 00 00 |222.95.180.216..|
    [1360] 11:34:09:687: Type=66, Length=14, Value=
    [1360] 11:34:09:687: 32 32 32 2E 39 35 2E 31 38 30 2E 32 31 36 00 00 |222.95.180.216..|
    [1360] 11:34:09:687: Type=26, Length=16, Value=
    [1360] 11:34:09:687: 00 00 01 37 23 0C 4D 53 52 41 53 56 35 2E 32 30 |...7#.MSRASV5.20|
    [1360] 11:34:09:687: Type=26, Length=17, Value=
    [1360] 11:34:09:687: 00 00 01 37 22 0D 4D 53 52 41 53 2D 30 2D 4E 4F |...7".MSRAS-0-NO|
    [1360] 11:34:09:687: 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |P...............|
    [1360] 11:34:09:687: Type=25, Length=30, Value=
    [1360] 11:34:09:687: 53 FC 05 D4 00 00 01 37 00 01 31 D4 B3 43 01 CE |S......7..1..C..|
    [1360] 11:34:09:687: 1C 26 97 FE 5C 8C 00 00 00 00 00 00 00 11 00 00 |.&..\...........|
    [1360] 11:34:09:687: Type=26, Length=68, Value=
    [1360] 11:34:09:687: 00 00 01 37 0A 40 02 4E 4F 50 2D 41 41 39 46 37 |...7.@.NOP-AA9F7|
    [1360] 11:34:09:687: 41 36 43 34 43 30 08 3F 3F 3F AE 6B 3F C3 B2 3F |A6C4C0.???.k?..?|
    [1360] 11:34:09:687: 3F 3F F2 81 DF 5D 9D DF 3F A7 F6 DA 6E 3F 3F 3F |??...]..?...n???|
    [1360] 11:34:09:687: 3F A9 83 E3 64 84 43 3F E1 BF 3F 3F 3F 3F 3F 3F |?...d.C?..??????|
    [1360] 11:34:09:687: 3F C2 52 3F 00 00 00 00 00 00 00 00 00 00 00 00 |?.R?............|
    [1360] 11:34:09:687: Type=44, Length=2, Value=
    [1360] 11:34:09:687: 38 39 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |89..............|
    [1360] 11:34:09:687: Type=1, Length=3, Value=
    [1360] 11:34:09:687: 76 70 6E 00 00 00 00 00 00 00 00 00 00 00 00 00 |vpn.............|
    [1360] 11:34:09:687: Type=12, Length=4, Value=0x578
    [1360] 11:34:09:687: Type=50, Length=3, Value=
    [1360] 11:34:09:687: 31 30 31 00 00 00 00 00 00 00 00 00 00 00 00 00 |101.............|
    [1360] 11:34:09:687: Type=51, Length=4, Value=0x1
    [1360] 11:34:09:687: Type=55, Length=4, Value=0x513aadaf
    [1360] 11:34:09:687: Type=45, Length=4, Value=0x2
    [1360] 11:34:09:687: Type=26, Length=10, Value=
    [1360] 11:34:09:687: 00 00 01 37 08 06 00 00 00 00 00 00 00 00 00 00 |...7............|
    [1360] 11:34:09:687: Type=46, Length=4, Value=0x9b
    [1360] 11:34:09:687: Type=43, Length=4, Value=0x351
    [1360] 11:34:09:687: Type=42, Length=4, Value=0x9b
    [1360] 11:34:09:687: Type=48, Length=4, Value=0x52
    [1360] 11:34:09:687: Type=47, Length=4, Value=0x5
    [1360] 11:34:09:687: Type=49, Length=4, Value=0x6
    [1360] 11:34:09:687: Inserting attribute type 4
    [1360] 11:34:09:687: Inserting attribute type 6
    [1360] 11:34:09:687: Inserting attribute type 7
    [1360] 11:34:09:687: Inserting attribute type 5
    [1360] 11:34:09:687: Inserting attribute type 26
    [1360] 11:34:09:687: Inserting attribute type 26
    [1360] 11:34:09:687: Inserting attribute type 61
    [1360] 11:34:09:687: Inserting attribute type 64
    [1360] 11:34:09:687: Inserting attribute type 65
    [1360] 11:34:09:687: Inserting attribute type 31
    [1360] 11:34:09:687: Inserting attribute type 66
    [1360] 11:34:09:687: Inserting attribute type 26
    [1360] 11:34:09:687: Inserting attribute type 26
    [1360] 11:34:09:687: Inserting attribute type 25
    [1360] 11:34:09:687: Inserting attribute type 26
    [1360] 11:34:09:687: Inserting attribute type 44
    [1360] 11:34:09:687: Inserting attribute type 1
    [1360] 11:34:09:687: Inserting attribute type 12
    [1360] 11:34:09:687: Inserting attribute type 50
    [1360] 11:34:09:687: Inserting attribute type 51
    [1360] 11:34:09:687: Inserting attribute type 55
    [1360] 11:34:09:687: Inserting attribute type 45
    [1360] 11:34:09:687: Inserting attribute type 26
    [1360] 11:34:09:687: Inserting attribute type 46
    [1360] 11:34:09:687: Inserting attribute type 43
    [1360] 11:34:09:687: Inserting attribute type 42
    [1360] 11:34:09:687: Inserting attribute type 48
    [1360] 11:34:09:687: Inserting attribute type 47
    [1360] 11:34:09:687: Inserting attribute type 49
    [1360] 11:34:09:687: Inserting attribute type 4108
    [1360] 11:34:09:687: IASResponse = 4, FailureReason = 0x0


    2013年3月9日 12:00

答案

全部回复

  • PPP.LOG

    [1972] 03-09 11:31:32:625: Line up event occurred on port 128
    [1972] 03-09 11:31:32:625: PortName: VPN4-127
    [1972] 03-09 11:31:32:625: Starting PPP on link with IfType=0x0,IPIf=0x0,IPXIf=0x0
    [1972] 03-09 11:31:32:625: RasGetBuffer returned 2c09158 for SendBuf
    [1972] 03-09 11:31:32:625: FsmInit called for protocol = c021, port = 128
    [1972] 03-09 11:31:32:625: ConfigInfo = 81620a
    [1972] 03-09 11:31:32:625: APs available = 42
    [1972] 03-09 11:31:32:625: FsmReset called for protocol = c021, port = 128
    [1972] 03-09 11:31:32:625: Inserting port in bucket # 6
    [1972] 03-09 11:31:32:625: Inserting bundle in bucket # 29
    [1972] 03-09 11:31:32:625: FsmOpen event received for protocol c021 on port 128
    [1972] 03-09 11:31:32:625: FsmThisLayerStarted called for protocol = c021, port = 128
    [1972] 03-09 11:31:32:625: FsmUp event received for protocol c021 on port 128
    [1972] 03-09 11:31:32:625: <PPP packet sent at 03/09/2013 03:31:32:625
    [1972] 03-09 11:31:32:625: <Protocol = LCP, Type = Configure-Req, Length = 0x1c, Id = 0x0, Port = 128
    [1972] 11:31:32:625: <C0 21 01 00 00 1A 01 04 05 78 03 05 C2 23 81 05 |.!.......x...#..|
    [1972] 11:31:32:625: <06 0B F7 3B F7 07 02 08 02 0D 03 06 00 00 00 00 |...;............|
    [1972] 03-09 11:31:32:625:  
    [1972] 03-09 11:31:32:625: InsertInTimerQ called portid=100,Id=0,Protocol=c021,EventType=0,fAuth=0
    [1972] 03-09 11:31:32:625: InsertInTimerQ called portid=100,Id=0,Protocol=0,EventType=3,fAuth=0
    [1972] 03-09 11:31:32:625: >PPP packet received at 03/09/2013 03:31:32:625
    [1972] 03-09 11:31:32:625: >Protocol = LCP, Type = Configure-Req, Length = 0x17, Id = 0x2, Port = 128
    [1972] 11:31:32:625: >C0 21 01 02 00 15 01 04 05 78 05 06 1F 2B 33 70 |.!.......x...+3p|
    [1972] 11:31:32:625: >07 02 08 02 0D 03 06 00 00 00 00 00 00 00 00 00 |................|
    [1972] 03-09 11:31:32:625:  
    [1972] 03-09 11:31:32:625: <PPP packet sent at 03/09/2013 03:31:32:625
    [1972] 03-09 11:31:32:625: <Protocol = LCP, Type = Configure-Ack, Length = 0x17, Id = 0x2, Port = 128
    [1972] 11:31:32:625: <C0 21 02 02 00 15 01 04 05 78 05 06 1F 2B 33 70 |.!.......x...+3p|
    [1972] 11:31:32:625: <07 02 08 02 0D 03 06 00 00 00 00 00 00 00 00 00 |................|
    [1972] 03-09 11:31:32:625:  
    [1900] 03-09 11:31:32:796: Packet received (28 bytes) for hPort 128
    [1900] 03-09 11:31:32:796: Packet received (20 bytes) for hPort 128
    [1972] 03-09 11:31:32:796: >PPP packet received at 03/09/2013 03:31:32:796
    [1900] 03-09 11:31:32:796: Packet received (21 bytes) for hPort 128
    [1972] 03-09 11:31:32:796: >Protocol = LCP, Type = Configure-Ack, Length = 0x1c, Id = 0x0, Port = 128
    [1972] 11:31:32:796: >C0 21 02 00 00 1A 01 04 05 78 03 05 C2 23 81 05 |.!.......x...#..|
    [1972] 11:31:32:796: >06 0B F7 3B F7 07 02 08 02 0D 03 06 00 00 00 00 |...;............|
    [1972] 03-09 11:31:32:796:  
    [1972] 03-09 11:31:32:796: RemoveFromTimerQ called portid=100,Id=0,Protocol=c021,EventType=0,fAuth=0
    [1972] 03-09 11:31:32:796: FsmThisLayerUp called for protocol = c021, port = 128
    [1972] 03-09 11:31:32:796: LCP Local Options-------------
    [1972] 03-09 11:31:32:796:  MRU=1400,ACCM=-1,Auth=c223,MagicNumber=200752119,PFC=ON,ACFC=ON
    [1972] 03-09 11:31:32:796:  Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0,BAP=OFF
    [1972] 03-09 11:31:32:796: LCP Remote Options-------------
    [1972] 03-09 11:31:32:796:  MRU=1400,ACCM=-1,Auth=0,MagicNumber=522924912,PFC=ON,ACFC=ON
    [1972] 03-09 11:31:32:796:  Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
    [1972] 03-09 11:31:32:796: LCP Configured successfully
    [1972] 03-09 11:31:32:796: Authenticating phase started
    [1972] 03-09 11:31:32:796: Calling APWork in APStart
    [1972] 03-09 11:31:32:796: <PPP packet sent at 03/09/2013 03:31:32:796
    [1972] 03-09 11:31:32:796: <Protocol = CHAP, Type = Protocol specific, Length = 0x26, Id = 0x0, Port = 128
    [1972] 11:31:32:796: <C2 23 01 00 00 24 10 58 E6 E3 2B 73 C5 1D 98 FD |.#...$.X..+s....|
    [1972] 11:31:32:796: <11 D6 30 02 9A 46 EA 4E 4F 50 2D 41 41 39 46 37 |..0..F.NOP-AA9F7|
    [1972] 11:31:32:796: <41 36 43 34 43 30 00 00 00 00 00 00 00 00 00 00 |A6C4C0..........|
    [1972] 03-09 11:31:32:796:  
    [1972] 03-09 11:31:32:796: InsertInTimerQ called portid=100,Id=0,Protocol=c223,EventType=0,fAuth=1
    [1972] 03-09 11:31:32:796: >PPP packet received at 03/09/2013 03:31:32:796
    [1972] 03-09 11:31:32:796: >Protocol = LCP, Type = Identification, Length = 0x14, Id = 0x3, Port = 128
    [1972] 11:31:32:796: >C0 21 0C 03 00 12 1F 2B 33 70 4D 53 52 41 53 56 |.!.....+3pMSRASV|
    [1972] 11:31:32:796: >35 2E 32 30 00 00 00 00 00 00 00 00 00 00 00 00 |5.20............|
    [1972] 03-09 11:31:32:796:  
    [1972] 03-09 11:31:32:796: Identification packet received
    [1972] 03-09 11:31:32:796: Remote identification = MSRASV5.20
    [1972] 03-09 11:31:32:796: >PPP packet received at 03/09/2013 03:31:32:796
    [1972] 03-09 11:31:32:796: >Protocol = LCP, Type = Identification, Length = 0x15, Id = 0x4, Port = 128
    [1972] 11:31:32:796: >C0 21 0C 04 00 13 1F 2B 33 70 4D 53 52 41 53 2D |.!.....+3pMSRAS-|
    [1972] 11:31:32:796: >30 2D 4E 4F 50 00 00 00 00 00 00 00 00 00 00 00 |0-NOP...........|
    [1972] 03-09 11:31:32:796:  
    [1972] 03-09 11:31:32:796: Identification packet received
    [1972] 03-09 11:31:32:796: Remote identification = MSRAS-0-NOP
    [1972] 03-09 11:31:34:625: Recv timeout event received for portid=100,Id=0,Protocol=c223,fAuth=1
    [1972] 03-09 11:31:34:625: <PPP packet sent at 03/09/2013 03:31:34:625
    [1972] 03-09 11:31:34:625: <Protocol = CHAP, Type = Protocol specific, Length = 0x26, Id = 0x1, Port = 128
    [1972] 11:31:34:625: <C2 23 01 01 00 24 10 0F BF 9A 83 65 A3 FC B1 38 |.#...$.....e...8|
    [1972] 11:31:34:625: <8A EF 55 84 C7 A9 3C 4E 4F 50 2D 41 41 39 46 37 |..U...<NOP-AA9F7|
    [1972] 11:31:34:625: <41 36 43 34 43 30 00 00 00 00 00 00 00 00 00 00 |A6C4C0..........|
    [1972] 03-09 11:31:34:625:  
    [1972] 03-09 11:31:34:625: InsertInTimerQ called portid=100,Id=0,Protocol=c223,EventType=0,fAuth=1
    [1972] 03-09 11:31:36:625: Recv timeout event received for portid=100,Id=0,Protocol=c223,fAuth=1
    [1972] 03-09 11:31:36:625: <PPP packet sent at 03/09/2013 03:31:36:625
    [1972] 03-09 11:31:36:625: <Protocol = CHAP, Type = Protocol specific, Length = 0x26, Id = 0x2, Port = 128
    [1972] 11:31:36:625: <C2 23 01 02 00 24 10 12 95 A0 59 1A 69 2E DE 6B |.#...$....Y.i..k|
    [1972] 11:31:36:625: <F2 E7 E9 35 BE 2B D3 4E 4F 50 2D 41 41 39 46 37 |...5.+.NOP-AA9F7|
    [1972] 11:31:36:625: <41 36 43 34 43 30 00 00 00 00 00 00 00 00 00 00 |A6C4C0..........|
    [1972] 03-09 11:31:36:625:  
    [1972] 03-09 11:31:36:625: InsertInTimerQ called portid=100,Id=0,Protocol=c223,EventType=0,fAuth=1
    [1900] 03-09 11:31:36:796: Packet received (59 bytes) for hPort 128
    [1972] 03-09 11:31:36:796: >PPP packet received at 03/09/2013 03:31:36:796
    [1972] 03-09 11:31:36:796: >Protocol = CHAP, Type = Protocol specific, Length = 0x3b, Id = 0x2, Port = 128
    [1972] 11:31:36:796: >C2 23 02 02 00 39 31 F4 67 43 1C 6F 55 7A 08 F3 |.#...91.gC.oUz..|
    [1972] 11:31:36:796: >10 32 74 18 F7 C7 31 00 00 00 00 00 00 00 00 55 |.2t...1........U|
    [1972] 11:31:36:796: >41 68 51 5B 2D FB EC 35 DF FD 27 32 AC 11 81 37 |AhQ[-..5..'2...7|
    [1972] 11:31:36:796: >A1 F9 8D 9E 76 9C 55 00 76 70 6E 00 00 00 00 00 |....v.U.vpn.....|
    [1972] 03-09 11:31:36:796:  
    [1972] 03-09 11:31:36:796: <PPP packet sent at 03/09/2013 03:31:36:796
    [1972] 03-09 11:31:36:796: <Protocol = CHAP, Type = Protocol specific, Length = 0x30, Id = 0x2, Port = 128
    [1972] 11:31:36:796: <C2 23 03 02 00 2E 53 3D 32 39 41 30 46 43 43 34 |.#....S=29A0FCC4|
    [1972] 11:31:36:796: <44 41 32 33 33 43 37 35 35 37 31 43 38 43 38 43 |DA233C75571C8C8C|
    [1972] 11:31:36:796: <42 32 30 37 33 31 43 39 42 37 43 41 33 41 39 41 |B20731C9B7CA3A9A|
    [1972] 03-09 11:31:36:796:  
    [1972] 03-09 11:31:36:796: MPPE-Send/Recv-Keys set
    [1972] 03-09 11:31:36:796: Auth Attribute Domain = NOP-AA9F7A6C4C0
    [1972] 03-09 11:31:36:796: Auth Attribute Idle Timeout Seconds = 0
    [1972] 03-09 11:31:36:796: AuthAttribute MaxChannelsAllowed = -1
    [1972] 03-09 11:31:36:796: FsmThisLayerUp called for protocol = c223, port = 128
    [1972] 03-09 11:31:36:796: NotifyCaller(hPort=128, dwMsgId=17)
    [1972] 03-09 11:31:36:796: Callback phase started
    [1972] 03-09 11:31:36:796: CallbackPriv in CB = 1
    [1972] 03-09 11:31:36:796: <PPP packet sent at 03/09/2013 03:31:36:796
    [1972] 03-09 11:31:36:796: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1, Port = 128
    [1972] 11:31:36:796: <C0 29 01 01 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:36:796:  
    [1972] 03-09 11:31:36:796: InsertInTimerQ called portid=100,Id=1,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:38:625: Recv timeout event received for portid=100,Id=1,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:38:625: <PPP packet sent at 03/09/2013 03:31:38:625
    [1972] 03-09 11:31:38:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2, Port = 128
    [1972] 11:31:38:625: <C0 29 01 02 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:38:625:  
    [1972] 03-09 11:31:38:625: InsertInTimerQ called portid=100,Id=2,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:38:625: Recv timeout event received for portid=100,Id=0,Protocol=c223,fAuth=1
    [1972] 03-09 11:31:40:625: Recv timeout event received for portid=100,Id=2,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:40:625: <PPP packet sent at 03/09/2013 03:31:40:625
    [1972] 03-09 11:31:40:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3, Port = 128
    [1972] 11:31:40:625: <C0 29 01 03 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:40:625:  
    [1972] 03-09 11:31:40:625: InsertInTimerQ called portid=100,Id=3,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:42:625: Recv timeout event received for portid=100,Id=3,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:42:625: <PPP packet sent at 03/09/2013 03:31:42:625
    [1972] 03-09 11:31:42:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x4, Port = 128
    [1972] 11:31:42:625: <C0 29 01 04 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:42:625:  
    [1972] 03-09 11:31:42:625: InsertInTimerQ called portid=100,Id=4,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:44:625: Recv timeout event received for portid=100,Id=4,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:44:625: <PPP packet sent at 03/09/2013 03:31:44:625
    [1972] 03-09 11:31:44:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x5, Port = 128
    [1972] 11:31:44:625: <C0 29 01 05 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:44:625:  
    [1972] 03-09 11:31:44:625: InsertInTimerQ called portid=100,Id=5,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:46:625: Recv timeout event received for portid=100,Id=5,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:46:625: <PPP packet sent at 03/09/2013 03:31:46:625
    [1972] 03-09 11:31:46:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x6, Port = 128
    [1972] 11:31:46:625: <C0 29 01 06 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:46:625:  
    [1972] 03-09 11:31:46:625: InsertInTimerQ called portid=100,Id=6,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:48:625: Recv timeout event received for portid=100,Id=6,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:48:625: <PPP packet sent at 03/09/2013 03:31:48:625
    [1972] 03-09 11:31:48:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x7, Port = 128
    [1972] 11:31:48:625: <C0 29 01 07 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:48:625:  
    [1972] 03-09 11:31:48:625: InsertInTimerQ called portid=100,Id=7,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:50:625: Recv timeout event received for portid=100,Id=7,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:50:625: <PPP packet sent at 03/09/2013 03:31:50:625
    [1972] 03-09 11:31:50:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x8, Port = 128
    [1972] 11:31:50:625: <C0 29 01 08 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:50:625:  
    [1972] 03-09 11:31:50:625: InsertInTimerQ called portid=100,Id=8,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:52:625: Recv timeout event received for portid=100,Id=8,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:52:625: <PPP packet sent at 03/09/2013 03:31:52:625
    [1972] 03-09 11:31:52:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x9, Port = 128
    [1972] 11:31:52:625: <C0 29 01 09 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:52:625:  
    [1972] 03-09 11:31:52:625: InsertInTimerQ called portid=100,Id=9,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:54:625: Recv timeout event received for portid=100,Id=9,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:54:625: <PPP packet sent at 03/09/2013 03:31:54:625
    [1972] 03-09 11:31:54:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0xa, Port = 128
    [1972] 11:31:54:625: <C0 29 01 0A 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:54:625:  
    [1972] 03-09 11:31:54:625: InsertInTimerQ called portid=100,Id=10,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:56:625: Recv timeout event received for portid=100,Id=10,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:56:625: <PPP packet sent at 03/09/2013 03:31:56:625
    [1972] 03-09 11:31:56:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0xb, Port = 128
    [1972] 11:31:56:625: <C0 29 01 0B 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:56:625:  
    [1972] 03-09 11:31:56:625: InsertInTimerQ called portid=100,Id=11,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:31:58:625: Recv timeout event received for portid=100,Id=11,Protocol=c029,fAuth=0
    [1972] 03-09 11:31:58:625: <PPP packet sent at 03/09/2013 03:31:58:625
    [1972] 03-09 11:31:58:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0xc, Port = 128
    [1972] 11:31:58:625: <C0 29 01 0C 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:31:58:625:  
    [1972] 03-09 11:31:58:625: InsertInTimerQ called portid=100,Id=12,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:00:625: Recv timeout event received for portid=100,Id=12,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:00:625: <PPP packet sent at 03/09/2013 03:32:00:625
    [1972] 03-09 11:32:00:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0xd, Port = 128
    [1972] 11:32:00:625: <C0 29 01 0D 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:00:625:  
    [1972] 03-09 11:32:00:625: InsertInTimerQ called portid=100,Id=13,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:02:625: Recv timeout event received for portid=100,Id=13,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:02:625: <PPP packet sent at 03/09/2013 03:32:02:625
    [1972] 03-09 11:32:02:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0xe, Port = 128
    [1972] 11:32:02:625: <C0 29 01 0E 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:02:625:  
    [1972] 03-09 11:32:02:625: InsertInTimerQ called portid=100,Id=14,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:04:625: Recv timeout event received for portid=100,Id=14,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:04:625: <PPP packet sent at 03/09/2013 03:32:04:625
    [1972] 03-09 11:32:04:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0xf, Port = 128
    [1972] 11:32:04:625: <C0 29 01 0F 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:04:625:  
    [1972] 03-09 11:32:04:625: InsertInTimerQ called portid=100,Id=15,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:06:625: Recv timeout event received for portid=100,Id=15,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:06:625: <PPP packet sent at 03/09/2013 03:32:06:625
    [1972] 03-09 11:32:06:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x10, Port = 128
    [1972] 11:32:06:625: <C0 29 01 10 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:06:625:  
    [1972] 03-09 11:32:06:625: InsertInTimerQ called portid=100,Id=16,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:08:625: Recv timeout event received for portid=100,Id=16,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:08:625: <PPP packet sent at 03/09/2013 03:32:08:625
    [1972] 03-09 11:32:08:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x11, Port = 128
    [1972] 11:32:08:625: <C0 29 01 11 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:08:625:  
    [1972] 03-09 11:32:08:625: InsertInTimerQ called portid=100,Id=17,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:10:625: Recv timeout event received for portid=100,Id=17,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:10:625: <PPP packet sent at 03/09/2013 03:32:10:625
    [1972] 03-09 11:32:10:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x12, Port = 128
    [1972] 11:32:10:625: <C0 29 01 12 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:10:625:  
    [1972] 03-09 11:32:10:625: InsertInTimerQ called portid=100,Id=18,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:12:625: Recv timeout event received for portid=100,Id=18,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:12:625: <PPP packet sent at 03/09/2013 03:32:12:625
    [1972] 03-09 11:32:12:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x13, Port = 128
    [1972] 11:32:12:625: <C0 29 01 13 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:12:625:  
    [1972] 03-09 11:32:12:625: InsertInTimerQ called portid=100,Id=19,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:14:625: Recv timeout event received for portid=100,Id=19,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:14:625: <PPP packet sent at 03/09/2013 03:32:14:625
    [1972] 03-09 11:32:14:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x14, Port = 128
    [1972] 11:32:14:625: <C0 29 01 14 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:14:625:  
    [1972] 03-09 11:32:14:625: InsertInTimerQ called portid=100,Id=20,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:16:625: Recv timeout event received for portid=100,Id=20,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:16:625: <PPP packet sent at 03/09/2013 03:32:16:625
    [1972] 03-09 11:32:16:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x15, Port = 128
    [1972] 11:32:16:625: <C0 29 01 15 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:16:625:  
    [1972] 03-09 11:32:16:625: InsertInTimerQ called portid=100,Id=21,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:18:625: Recv timeout event received for portid=100,Id=21,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:18:625: <PPP packet sent at 03/09/2013 03:32:18:625
    [1972] 03-09 11:32:18:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x16, Port = 128
    [1972] 11:32:18:625: <C0 29 01 16 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:18:625:  
    [1972] 03-09 11:32:18:625: InsertInTimerQ called portid=100,Id=22,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:20:625: Recv timeout event received for portid=100,Id=22,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:20:625: <PPP packet sent at 03/09/2013 03:32:20:625
    [1972] 03-09 11:32:20:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x17, Port = 128
    [1972] 11:32:20:625: <C0 29 01 17 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:20:625:  
    [1972] 03-09 11:32:20:625: InsertInTimerQ called portid=100,Id=23,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:22:625: Recv timeout event received for portid=100,Id=23,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:22:625: <PPP packet sent at 03/09/2013 03:32:22:625
    [1972] 03-09 11:32:22:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x18, Port = 128
    [1972] 11:32:22:625: <C0 29 01 18 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:22:625:  
    [1972] 03-09 11:32:22:625: InsertInTimerQ called portid=100,Id=24,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:24:625: Recv timeout event received for portid=100,Id=24,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:24:625: <PPP packet sent at 03/09/2013 03:32:24:625
    [1972] 03-09 11:32:24:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x19, Port = 128
    [1972] 11:32:24:625: <C0 29 01 19 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:24:625:  
    [1972] 03-09 11:32:24:625: InsertInTimerQ called portid=100,Id=25,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:26:625: Recv timeout event received for portid=100,Id=25,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:26:625: <PPP packet sent at 03/09/2013 03:32:26:625
    [1972] 03-09 11:32:26:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1a, Port = 128
    [1972] 11:32:26:625: <C0 29 01 1A 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:26:625:  
    [1972] 03-09 11:32:26:625: InsertInTimerQ called portid=100,Id=26,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:28:625: Recv timeout event received for portid=100,Id=26,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:28:625: <PPP packet sent at 03/09/2013 03:32:28:625
    [1972] 03-09 11:32:28:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1b, Port = 128
    [1972] 11:32:28:625: <C0 29 01 1B 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:28:625:  
    [1972] 03-09 11:32:28:625: InsertInTimerQ called portid=100,Id=27,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:30:625: Recv timeout event received for portid=100,Id=27,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:30:625: <PPP packet sent at 03/09/2013 03:32:30:625
    [1972] 03-09 11:32:30:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1c, Port = 128
    [1972] 11:32:30:625: <C0 29 01 1C 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:30:625:  
    [1972] 03-09 11:32:30:625: InsertInTimerQ called portid=100,Id=28,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:32:625: Recv timeout event received for portid=100,Id=28,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:32:625: <PPP packet sent at 03/09/2013 03:32:32:625
    [1972] 03-09 11:32:32:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1d, Port = 128
    [1972] 11:32:32:625: <C0 29 01 1D 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:32:625:  
    [1972] 03-09 11:32:32:625: InsertInTimerQ called portid=100,Id=29,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:34:625: Recv timeout event received for portid=100,Id=29,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:34:625: <PPP packet sent at 03/09/2013 03:32:34:625
    [1972] 03-09 11:32:34:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1e, Port = 128
    [1972] 11:32:34:625: <C0 29 01 1E 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:34:625:  
    [1972] 03-09 11:32:34:625: InsertInTimerQ called portid=100,Id=30,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:36:625: Recv timeout event received for portid=100,Id=30,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:36:625: <PPP packet sent at 03/09/2013 03:32:36:625
    [1972] 03-09 11:32:36:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x1f, Port = 128
    [1972] 11:32:36:625: <C0 29 01 1F 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:32:36:625:  
    [1972] 03-09 11:32:36:625: InsertInTimerQ called portid=100,Id=31,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:38:625: Recv timeout event received for portid=100,Id=31,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:38:625: <PPP packet sent at 03/09/2013 03:32:38:625
    [1972] 03-09 11:32:38:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x20, Port = 128
    [1972] 11:32:38:625: <C0 29 01 20 00 06 01 02 00 00 00 00 00 00 00 00 |.). ............|
    [1972] 03-09 11:32:38:625:  
    [1972] 03-09 11:32:38:625: InsertInTimerQ called portid=100,Id=32,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:40:625: Recv timeout event received for portid=100,Id=32,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:40:625: <PPP packet sent at 03/09/2013 03:32:40:625
    [1972] 03-09 11:32:40:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x21, Port = 128
    [1972] 11:32:40:625: <C0 29 01 21 00 06 01 02 00 00 00 00 00 00 00 00 |.).!............|
    [1972] 03-09 11:32:40:625:  
    [1972] 03-09 11:32:40:625: InsertInTimerQ called portid=100,Id=33,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:42:625: Recv timeout event received for portid=100,Id=33,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:42:625: <PPP packet sent at 03/09/2013 03:32:42:625
    [1972] 03-09 11:32:42:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x22, Port = 128
    [1972] 11:32:42:625: <C0 29 01 22 00 06 01 02 00 00 00 00 00 00 00 00 |.)."............|
    [1972] 03-09 11:32:42:625:  
    [1972] 03-09 11:32:42:625: InsertInTimerQ called portid=100,Id=34,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:44:625: Recv timeout event received for portid=100,Id=34,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:44:625: <PPP packet sent at 03/09/2013 03:32:44:625
    [1972] 03-09 11:32:44:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x23, Port = 128
    [1972] 11:32:44:625: <C0 29 01 23 00 06 01 02 00 00 00 00 00 00 00 00 |.).#............|
    [1972] 03-09 11:32:44:625:  
    [1972] 03-09 11:32:44:625: InsertInTimerQ called portid=100,Id=35,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:46:625: Recv timeout event received for portid=100,Id=35,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:46:625: <PPP packet sent at 03/09/2013 03:32:46:625
    [1972] 03-09 11:32:46:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x24, Port = 128
    [1972] 11:32:46:625: <C0 29 01 24 00 06 01 02 00 00 00 00 00 00 00 00 |.).$............|
    [1972] 03-09 11:32:46:625:  
    [1972] 03-09 11:32:46:625: InsertInTimerQ called portid=100,Id=36,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:48:625: Recv timeout event received for portid=100,Id=36,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:48:625: <PPP packet sent at 03/09/2013 03:32:48:625
    [1972] 03-09 11:32:48:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x25, Port = 128
    [1972] 11:32:48:625: <C0 29 01 25 00 06 01 02 00 00 00 00 00 00 00 00 |.).%............|
    [1972] 03-09 11:32:48:625:  
    [1972] 03-09 11:32:48:625: InsertInTimerQ called portid=100,Id=37,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:50:625: Recv timeout event received for portid=100,Id=37,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:50:625: <PPP packet sent at 03/09/2013 03:32:50:625
    [1972] 03-09 11:32:50:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x26, Port = 128
    [1972] 11:32:50:625: <C0 29 01 26 00 06 01 02 00 00 00 00 00 00 00 00 |.).&............|
    [1972] 03-09 11:32:50:625:  
    [1972] 03-09 11:32:50:625: InsertInTimerQ called portid=100,Id=38,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:52:625: Recv timeout event received for portid=100,Id=38,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:52:625: <PPP packet sent at 03/09/2013 03:32:52:625
    [1972] 03-09 11:32:52:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x27, Port = 128
    [1972] 11:32:52:625: <C0 29 01 27 00 06 01 02 00 00 00 00 00 00 00 00 |.).'............|
    [1972] 03-09 11:32:52:625:  
    [1972] 03-09 11:32:52:625: InsertInTimerQ called portid=100,Id=39,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:54:625: Recv timeout event received for portid=100,Id=39,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:54:625: <PPP packet sent at 03/09/2013 03:32:54:625
    [1972] 03-09 11:32:54:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x28, Port = 128
    [1972] 11:32:54:625: <C0 29 01 28 00 06 01 02 00 00 00 00 00 00 00 00 |.).(............|
    [1972] 03-09 11:32:54:625:  
    [1972] 03-09 11:32:54:625: InsertInTimerQ called portid=100,Id=40,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:56:625: Recv timeout event received for portid=100,Id=40,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:56:625: <PPP packet sent at 03/09/2013 03:32:56:625
    [1972] 03-09 11:32:56:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x29, Port = 128
    [1972] 11:32:56:625: <C0 29 01 29 00 06 01 02 00 00 00 00 00 00 00 00 |.).)............|
    [1972] 03-09 11:32:56:625:  
    [1972] 03-09 11:32:56:625: InsertInTimerQ called portid=100,Id=41,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:32:58:625: Recv timeout event received for portid=100,Id=41,Protocol=c029,fAuth=0
    [1972] 03-09 11:32:58:625: <PPP packet sent at 03/09/2013 03:32:58:625
    [1972] 03-09 11:32:58:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2a, Port = 128
    [1972] 11:32:58:625: <C0 29 01 2A 00 06 01 02 00 00 00 00 00 00 00 00 |.).*............|
    [1972] 03-09 11:32:58:625:  
    [1972] 03-09 11:32:58:625: InsertInTimerQ called portid=100,Id=42,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:00:625: Recv timeout event received for portid=100,Id=42,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:00:625: <PPP packet sent at 03/09/2013 03:33:00:625
    [1972] 03-09 11:33:00:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2b, Port = 128
    [1972] 11:33:00:625: <C0 29 01 2B 00 06 01 02 00 00 00 00 00 00 00 00 |.).+............|
    [1972] 03-09 11:33:00:625:  
    [1972] 03-09 11:33:00:625: InsertInTimerQ called portid=100,Id=43,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:02:625: Recv timeout event received for portid=100,Id=43,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:02:625: <PPP packet sent at 03/09/2013 03:33:02:625
    [1972] 03-09 11:33:02:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2c, Port = 128
    [1972] 11:33:02:625: <C0 29 01 2C 00 06 01 02 00 00 00 00 00 00 00 00 |.).,............|
    [1972] 03-09 11:33:02:625:  
    [1972] 03-09 11:33:02:625: InsertInTimerQ called portid=100,Id=44,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:04:625: Recv timeout event received for portid=100,Id=44,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:04:625: <PPP packet sent at 03/09/2013 03:33:04:625
    [1972] 03-09 11:33:04:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2d, Port = 128
    [1972] 11:33:04:625: <C0 29 01 2D 00 06 01 02 00 00 00 00 00 00 00 00 |.).-............|
    [1972] 03-09 11:33:04:625:  
    [1972] 03-09 11:33:04:625: InsertInTimerQ called portid=100,Id=45,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:06:625: Recv timeout event received for portid=100,Id=45,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:06:625: <PPP packet sent at 03/09/2013 03:33:06:625
    [1972] 03-09 11:33:06:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2e, Port = 128
    [1972] 11:33:06:625: <C0 29 01 2E 00 06 01 02 00 00 00 00 00 00 00 00 |.)..............|
    [1972] 03-09 11:33:06:625:  
    [1972] 03-09 11:33:06:625: InsertInTimerQ called portid=100,Id=46,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:08:625: Recv timeout event received for portid=100,Id=46,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:08:625: <PPP packet sent at 03/09/2013 03:33:08:625
    [1972] 03-09 11:33:08:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x2f, Port = 128
    [1972] 11:33:08:625: <C0 29 01 2F 00 06 01 02 00 00 00 00 00 00 00 00 |.)./............|
    [1972] 03-09 11:33:08:625:  
    [1972] 03-09 11:33:08:625: InsertInTimerQ called portid=100,Id=47,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:10:625: Recv timeout event received for portid=100,Id=47,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:10:625: <PPP packet sent at 03/09/2013 03:33:10:625
    [1972] 03-09 11:33:10:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x30, Port = 128
    [1972] 11:33:10:625: <C0 29 01 30 00 06 01 02 00 00 00 00 00 00 00 00 |.).0............|
    [1972] 03-09 11:33:10:625:  
    [1972] 03-09 11:33:10:625: InsertInTimerQ called portid=100,Id=48,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:12:625: Recv timeout event received for portid=100,Id=48,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:12:625: <PPP packet sent at 03/09/2013 03:33:12:625
    [1972] 03-09 11:33:12:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x31, Port = 128
    [1972] 11:33:12:625: <C0 29 01 31 00 06 01 02 00 00 00 00 00 00 00 00 |.).1............|
    [1972] 03-09 11:33:12:625:  
    [1972] 03-09 11:33:12:625: InsertInTimerQ called portid=100,Id=49,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:14:625: Recv timeout event received for portid=100,Id=49,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:14:625: <PPP packet sent at 03/09/2013 03:33:14:625
    [1972] 03-09 11:33:14:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x32, Port = 128
    [1972] 11:33:14:625: <C0 29 01 32 00 06 01 02 00 00 00 00 00 00 00 00 |.).2............|
    [1972] 03-09 11:33:14:625:  
    [1972] 03-09 11:33:14:625: InsertInTimerQ called portid=100,Id=50,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:16:625: Recv timeout event received for portid=100,Id=50,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:16:625: <PPP packet sent at 03/09/2013 03:33:16:625
    [1972] 03-09 11:33:16:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x33, Port = 128
    [1972] 11:33:16:625: <C0 29 01 33 00 06 01 02 00 00 00 00 00 00 00 00 |.).3............|
    [1972] 03-09 11:33:16:625:  
    [1972] 03-09 11:33:16:625: InsertInTimerQ called portid=100,Id=51,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:18:625: Recv timeout event received for portid=100,Id=51,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:18:625: <PPP packet sent at 03/09/2013 03:33:18:625
    [1972] 03-09 11:33:18:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x34, Port = 128
    [1972] 11:33:18:625: <C0 29 01 34 00 06 01 02 00 00 00 00 00 00 00 00 |.).4............|
    [1972] 03-09 11:33:18:625:  
    [1972] 03-09 11:33:18:625: InsertInTimerQ called portid=100,Id=52,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:20:625: Recv timeout event received for portid=100,Id=52,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:20:625: <PPP packet sent at 03/09/2013 03:33:20:625
    [1972] 03-09 11:33:20:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x35, Port = 128
    [1972] 11:33:20:625: <C0 29 01 35 00 06 01 02 00 00 00 00 00 00 00 00 |.).5............|
    [1972] 03-09 11:33:20:625:  
    [1972] 03-09 11:33:20:625: InsertInTimerQ called portid=100,Id=53,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:22:625: Recv timeout event received for portid=100,Id=53,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:22:625: <PPP packet sent at 03/09/2013 03:33:22:625
    [1972] 03-09 11:33:22:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x36, Port = 128
    [1972] 11:33:22:625: <C0 29 01 36 00 06 01 02 00 00 00 00 00 00 00 00 |.).6............|
    [1972] 03-09 11:33:22:625:  
    [1972] 03-09 11:33:22:625: InsertInTimerQ called portid=100,Id=54,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:24:625: Recv timeout event received for portid=100,Id=54,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:24:625: <PPP packet sent at 03/09/2013 03:33:24:625
    [1972] 03-09 11:33:24:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x37, Port = 128
    [1972] 11:33:24:625: <C0 29 01 37 00 06 01 02 00 00 00 00 00 00 00 00 |.).7............|
    [1972] 03-09 11:33:24:625:  
    [1972] 03-09 11:33:24:625: InsertInTimerQ called portid=100,Id=55,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:26:625: Recv timeout event received for portid=100,Id=55,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:26:625: <PPP packet sent at 03/09/2013 03:33:26:625
    [1972] 03-09 11:33:26:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x38, Port = 128
    [1972] 11:33:26:625: <C0 29 01 38 00 06 01 02 00 00 00 00 00 00 00 00 |.).8............|
    [1972] 03-09 11:33:26:625:  
    [1972] 03-09 11:33:26:625: InsertInTimerQ called portid=100,Id=56,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:28:625: Recv timeout event received for portid=100,Id=56,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:28:625: <PPP packet sent at 03/09/2013 03:33:28:625
    [1972] 03-09 11:33:28:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x39, Port = 128
    [1972] 11:33:28:625: <C0 29 01 39 00 06 01 02 00 00 00 00 00 00 00 00 |.).9............|
    [1972] 03-09 11:33:28:625:  
    [1972] 03-09 11:33:28:625: InsertInTimerQ called portid=100,Id=57,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:30:625: Recv timeout event received for portid=100,Id=57,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:30:625: <PPP packet sent at 03/09/2013 03:33:30:625
    [1972] 03-09 11:33:30:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3a, Port = 128
    [1972] 11:33:30:625: <C0 29 01 3A 00 06 01 02 00 00 00 00 00 00 00 00 |.).:............|
    [1972] 03-09 11:33:30:625:  
    [1972] 03-09 11:33:30:625: InsertInTimerQ called portid=100,Id=58,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:32:625: Recv timeout event received for portid=100,Id=58,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:32:625: <PPP packet sent at 03/09/2013 03:33:32:625
    [1972] 03-09 11:33:32:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3b, Port = 128
    [1972] 11:33:32:625: <C0 29 01 3B 00 06 01 02 00 00 00 00 00 00 00 00 |.).;............|
    [1972] 03-09 11:33:32:625:  
    [1972] 03-09 11:33:32:625: InsertInTimerQ called portid=100,Id=59,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:34:625: Recv timeout event received for portid=100,Id=59,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:34:625: <PPP packet sent at 03/09/2013 03:33:34:625
    [1972] 03-09 11:33:34:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3c, Port = 128
    [1972] 11:33:34:625: <C0 29 01 3C 00 06 01 02 00 00 00 00 00 00 00 00 |.).<............|
    [1972] 03-09 11:33:34:625:  
    [1972] 03-09 11:33:34:625: InsertInTimerQ called portid=100,Id=60,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:36:625: Recv timeout event received for portid=100,Id=60,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:36:625: <PPP packet sent at 03/09/2013 03:33:36:625
    [1972] 03-09 11:33:36:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3d, Port = 128
    [1972] 11:33:36:625: <C0 29 01 3D 00 06 01 02 00 00 00 00 00 00 00 00 |.).=............|
    [1972] 03-09 11:33:36:625:  
    [1972] 03-09 11:33:36:625: InsertInTimerQ called portid=100,Id=61,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:38:625: Recv timeout event received for portid=100,Id=61,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:38:625: <PPP packet sent at 03/09/2013 03:33:38:625
    [1972] 03-09 11:33:38:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3e, Port = 128
    [1972] 11:33:38:625: <C0 29 01 3E 00 06 01 02 00 00 00 00 00 00 00 00 |.).>............|
    [1972] 03-09 11:33:38:625:  
    [1972] 03-09 11:33:38:625: InsertInTimerQ called portid=100,Id=62,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:40:625: Recv timeout event received for portid=100,Id=62,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:40:625: <PPP packet sent at 03/09/2013 03:33:40:625
    [1972] 03-09 11:33:40:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x3f, Port = 128
    [1972] 11:33:40:625: <C0 29 01 3F 00 06 01 02 00 00 00 00 00 00 00 00 |.).?............|
    [1972] 03-09 11:33:40:625:  
    [1972] 03-09 11:33:40:625: InsertInTimerQ called portid=100,Id=63,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:42:625: Recv timeout event received for portid=100,Id=63,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:42:625: <PPP packet sent at 03/09/2013 03:33:42:625
    [1972] 03-09 11:33:42:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x40, Port = 128
    [1972] 11:33:42:625: <C0 29 01 40 00 06 01 02 00 00 00 00 00 00 00 00 |.).@............|
    [1972] 03-09 11:33:42:625:  
    [1972] 03-09 11:33:42:625: InsertInTimerQ called portid=100,Id=64,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:44:625: Recv timeout event received for portid=100,Id=64,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:44:625: <PPP packet sent at 03/09/2013 03:33:44:625
    [1972] 03-09 11:33:44:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x41, Port = 128
    [1972] 11:33:44:625: <C0 29 01 41 00 06 01 02 00 00 00 00 00 00 00 00 |.).A............|
    [1972] 03-09 11:33:44:625:  
    [1972] 03-09 11:33:44:625: InsertInTimerQ called portid=100,Id=65,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:46:625: Recv timeout event received for portid=100,Id=65,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:46:625: <PPP packet sent at 03/09/2013 03:33:46:625
    [1972] 03-09 11:33:46:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x42, Port = 128
    [1972] 11:33:46:625: <C0 29 01 42 00 06 01 02 00 00 00 00 00 00 00 00 |.).B............|
    [1972] 03-09 11:33:46:625:  
    [1972] 03-09 11:33:46:625: InsertInTimerQ called portid=100,Id=66,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:48:625: Recv timeout event received for portid=100,Id=66,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:48:625: <PPP packet sent at 03/09/2013 03:33:48:625
    [1972] 03-09 11:33:48:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x43, Port = 128
    [1972] 11:33:48:625: <C0 29 01 43 00 06 01 02 00 00 00 00 00 00 00 00 |.).C............|
    [1972] 03-09 11:33:48:625:  
    [1972] 03-09 11:33:48:625: InsertInTimerQ called portid=100,Id=67,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:50:625: Recv timeout event received for portid=100,Id=67,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:50:625: <PPP packet sent at 03/09/2013 03:33:50:625
    [1972] 03-09 11:33:50:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x44, Port = 128
    [1972] 11:33:50:625: <C0 29 01 44 00 06 01 02 00 00 00 00 00 00 00 00 |.).D............|
    [1972] 03-09 11:33:50:625:  
    [1972] 03-09 11:33:50:625: InsertInTimerQ called portid=100,Id=68,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:52:625: Recv timeout event received for portid=100,Id=68,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:52:625: <PPP packet sent at 03/09/2013 03:33:52:625
    [1972] 03-09 11:33:52:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x45, Port = 128
    [1972] 11:33:52:625: <C0 29 01 45 00 06 01 02 00 00 00 00 00 00 00 00 |.).E............|
    [1972] 03-09 11:33:52:625:  
    [1972] 03-09 11:33:52:625: InsertInTimerQ called portid=100,Id=69,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:54:625: Recv timeout event received for portid=100,Id=69,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:54:625: <PPP packet sent at 03/09/2013 03:33:54:625
    [1972] 03-09 11:33:54:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x46, Port = 128
    [1972] 11:33:54:625: <C0 29 01 46 00 06 01 02 00 00 00 00 00 00 00 00 |.).F............|
    [1972] 03-09 11:33:54:625:  
    [1972] 03-09 11:33:54:625: InsertInTimerQ called portid=100,Id=70,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:56:625: Recv timeout event received for portid=100,Id=70,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:56:625: <PPP packet sent at 03/09/2013 03:33:56:625
    [1972] 03-09 11:33:56:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x47, Port = 128
    [1972] 11:33:56:625: <C0 29 01 47 00 06 01 02 00 00 00 00 00 00 00 00 |.).G............|
    [1972] 03-09 11:33:56:625:  
    [1972] 03-09 11:33:56:625: InsertInTimerQ called portid=100,Id=71,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:33:58:625: Recv timeout event received for portid=100,Id=71,Protocol=c029,fAuth=0
    [1972] 03-09 11:33:58:625: <PPP packet sent at 03/09/2013 03:33:58:625
    [1972] 03-09 11:33:58:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x48, Port = 128
    [1972] 11:33:58:625: <C0 29 01 48 00 06 01 02 00 00 00 00 00 00 00 00 |.).H............|
    [1972] 03-09 11:33:58:625:  
    [1972] 03-09 11:33:58:625: InsertInTimerQ called portid=100,Id=72,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:34:00:625: Recv timeout event received for portid=100,Id=72,Protocol=c029,fAuth=0
    [1972] 03-09 11:34:00:625: <PPP packet sent at 03/09/2013 03:34:00:625
    [1972] 03-09 11:34:00:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x49, Port = 128
    [1972] 11:34:00:625: <C0 29 01 49 00 06 01 02 00 00 00 00 00 00 00 00 |.).I............|
    [1972] 03-09 11:34:00:625:  
    [1972] 03-09 11:34:00:625: InsertInTimerQ called portid=100,Id=73,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:34:02:625: Recv timeout event received for portid=100,Id=73,Protocol=c029,fAuth=0
    [1972] 03-09 11:34:02:625: <PPP packet sent at 03/09/2013 03:34:02:625
    [1972] 03-09 11:34:02:625: <Protocol = CBCP, Type = Protocol specific, Length = 0x8, Id = 0x4a, Port = 128
    [1972] 11:34:02:625: <C0 29 01 4A 00 06 01 02 00 00 00 00 00 00 00 00 |.).J............|
    [1972] 03-09 11:34:02:625:  
    [1972] 03-09 11:34:02:625: InsertInTimerQ called portid=100,Id=74,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:34:03:625: NotifyCaller(hPort=128, dwMsgId=14)
    [1788] 03-09 11:34:03:625: PppDdmStop

    [1788] 03-09 11:34:03:625: PPPEMSG_Stop recvd

    [1972] 03-09 11:34:03:625: FsmClose event received for protocol c021 on port 128
    [1972] 03-09 11:34:03:625: RemoveFromTimerQ called portid=100,Id=0,Protocol=c021,EventType=0,fAuth=0
    [1972] 03-09 11:34:03:625: FsmThisLayerDown called for protocol = c021, port = 128
    [1972] 03-09 11:34:03:625: RemoveFromTimerQ called portid=100,Id=74,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:34:03:625: <PPP packet sent at 03/09/2013 03:34:03:625
    [1972] 03-09 11:34:03:625: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0x2, Port = 128
    [1972] 11:34:03:625: <C0 21 05 02 00 10 0B F7 3B F7 00 3C CD 74 00 00 |.!......;..<.t..|
    [1972] 11:34:03:625: <02 CE 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
    [1972] 03-09 11:34:03:625:  
    [1972] 03-09 11:34:03:625: InsertInTimerQ called portid=100,Id=2,Protocol=c021,EventType=0,fAuth=0
    [1972] 03-09 11:34:05:625: Recv timeout event received for portid=100,Id=2,Protocol=c021,fAuth=0
    [1972] 03-09 11:34:05:625: <PPP packet sent at 03/09/2013 03:34:05:625
    [1972] 03-09 11:34:05:625: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0x3, Port = 128
    [1972] 11:34:05:625: <C0 21 05 03 00 10 0B F7 3B F7 00 3C CD 74 00 00 |.!......;..<.t..|
    [1972] 11:34:05:625: <02 CE 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
    [1972] 03-09 11:34:05:625:  
    [1972] 03-09 11:34:05:625: InsertInTimerQ called portid=100,Id=3,Protocol=c021,EventType=0,fAuth=0
    [1972] 03-09 11:34:07:625: Recv timeout event received for portid=100,Id=3,Protocol=c021,fAuth=0
    [1972] 03-09 11:34:07:625: Terminate retry exceeded
    [1972] 03-09 11:34:07:625: FsmThisLayerFinished called for protocol = c021, port = 128
    [1972] 03-09 11:34:07:625: NotifyCaller(hPort=128, dwMsgId=18)
    [1900] 03-09 11:34:07:687: PPPEMSG_LineDown recvd, hPort=128

    [1972] 03-09 11:34:07:687: Line down event occurred on port 128
    [1972] 03-09 11:34:07:687: FsmDown event received for protocol c021 on port 128
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=3,Protocol=c021,EventType=0,fAuth=0
    [1972] 03-09 11:34:07:687: FsmReset called for protocol = c021, port = 128
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=0,Protocol=0,EventType=3,fAuth=0
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=0,Protocol=0,EventType=7,fAuth=0
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=0,Protocol=0,EventType=2,fAuth=0
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=0,Protocol=0,EventType=1,fAuth=0
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=0,Protocol=0,EventType=4,fAuth=0
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=0,Protocol=0,EventType=6,fAuth=0
    [1972] 03-09 11:34:07:687: RemoveFromTimerQ called portid=100,Id=74,Protocol=c029,EventType=0,fAuth=0
    [1972] 03-09 11:34:07:687: LcpEnd
    [1360] 03-09 11:34:07:687: Stopping Accounting for port 128
    [1972] 03-09 11:34:09:687: Post line down event occurred on port 128
    [1972] 03-09 11:34:09:687: NotifyCaller(hPort=128, dwMsgId=23)


    Router.LOG

    [1788] 03-09 11:31:27:453: Rasman state change received from port 128, 1->5
    [1788] 03-09 11:31:27:453: Rasman state change received from port 128, 5->2
    [1788] 03-09 11:31:27:453: SvDevConnected: Entered, hPort=128
    [1788] 03-09 11:31:27:453: TimerQRemove called
    [1788] 03-09 11:31:32:625: SvFrameReceived: Entered, hPort: 128
    [1788] 03-09 11:31:32:625: SvFrameReceived: PPP frame on port 128
    [1972] 03-09 11:31:36:796: ServerSendMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_Authenticated
    [1788] 03-09 11:31:36:796: ServerReceiveMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_Authenticated
    [1788] 03-09 11:31:36:796: SvPppUserOK: Entered, hPort=128
    [1788] 03-09 11:31:36:796: TimerQRemove called
    [1972] 03-09 11:34:03:625: ServerSendMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_PppFailure
    [1788] 03-09 11:34:03:625: ServerReceiveMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_PppFailure
    [1788] 03-09 11:34:03:625: SvPppFailure: Entered, hPort=128, Error=718
    [1972] 03-09 11:34:07:625: ServerSendMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_Stopped
    [1788] 03-09 11:34:07:625: ServerReceiveMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_Stopped
    [1788] 03-09 11:34:07:625: DevStartClosing: Entered, hPort=128
    [1788] 03-09 11:34:07:625: RmDisconnect:Disconnect posted on port 128
    [1788] 03-09 11:34:07:625: RasPortDisconnect rc=600
    [1788] 03-09 11:34:07:625: TimerQRemove called
    [1788] 03-09 11:34:07:625: TimerQRemove called
    [1788] 03-09 11:34:07:625: TimerQRemove called
    [1788] 03-09 11:34:07:625: DevCloseComplete:hPort=128,Auth=0,Rcv=0,Conn=1 1,Sec=3 2,Ppp=0
    [1788] 03-09 11:34:07:687: Rasman state change received from port 128, 3->4
    [1788] 03-09 11:34:07:687: SvDevDisconnected:Entered, hPort=128
    [1788] 03-09 11:34:07:687: DevCloseComplete:hPort=128,Auth=0,Rcv=0,Conn=1 0,Sec=4 2,Ppp=0
    [1788] 03-09 11:34:07:687: RmListen: Listen posted on port 128
    [1788] 03-09 11:34:07:687: RasPortListen dwRetCode=600
    [1972] 03-09 11:34:09:687: ServerSendMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_PortCleanedUp
    [1788] 03-09 11:34:09:687: ServerReceiveMessage on port/connection: 80 from: PPP Message: PPPDDMMSG_PortCleanedUp

    2013年3月9日 12:00
  • Hi,

    您的问题似乎和windows azure没有什么关系. 如果这样的话我建议您转发或者重新在以下论坛发贴来寻求更有针对性的帮助,谢谢:

    http://social.technet.microsoft.com/Forums/en-US/winserverNIS/threads

    http://social.msdn.microsoft.com/Forums/en-US/category/windowsserver

    谢谢,


    QinDian Tang
    MSDN Community Support | Feedback to us
    Develop and promote your apps in Windows Store
    Please remember to mark the replies as answers if they help and unmark them if they provide no help.

    2013年3月11日 2:27
    版主