Q: Why does it take 9000ms (9s) to establish an PPPoE connection? (Many *dial-up* ISDN connections are made in half that time)
(Note: Connection is UP as of Frame #38, total elapsed time = 8.445s)
A: Here is a decomposition of the major contributors to connection delay:
| Frame(s) | Delay (s) | Description | Possible Culprit |
|---|---|---|---|
| 8.40 | Current total connect time | ||
| 8 | 2.90 | Client as completed LCP, but now we wait for Redback to start LCP | Redback? |
| 14 | 2.10 | Client responds to authentication challenge with no delay, server takes 2s to authenticate! seems excessive. | ISP Authentication Server |
| 28-32 | 0.75 | Why, why, why is NTS doing anything with DHCP! | NTS |
| 33-34 | 2.00 | SLP (Server Location Protocol) requests that have to timeout | Microsoft |
| 7.25 | Total suspected unnecessary delays (add .500s for authentication) | (see Above) | |
| 1.15 | Current connect time - unnecesary delays = What is SHOULD be. |
Note: Intel 22B210 is client PC, 001067008D8A is Redback PPPoE aggregation router.
Test configuration:
Windows NT Workstation 4.0 (SP5)
NTS v1.40
\"Flags \",\"Frame \",\"Delta Time \",\"Destination \",\"Source \",\"Bytes\",\"Protocol \",\"Summary\" " M "," 1","0.000.000 ","Broadcast ","Intel 22B210 "," 60 ","PPPoE"," : Discovery Stage " " "," 2","0.023.213 ","Intel 22B210 ","001067008D8A "," 74 ","PPPoE"," : Discovery Stage " " "," 3","0.000.203 ","001067008D8A ","Intel 22B210 "," 60 ","PPPoE"," : Discovery Stage " " "," 4","0.040.604 ","Intel 22B210 ","001067008D8A "," 61 ","PPPoE"," : Discovery Stage " " "," 5","0.042.403 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Configure Request" " "," 6","0.018.605 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," LCP Configure Request" " "," 7","0.014.671 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Configure Ack" " "," 8","2.966.882 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Configure Request" " "," 9","0.018.945 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," LCP Configure Reject" " "," 10","0.014.274 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Configure Request" " "," 11","0.049.957 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," LCP Configure Ack" " "," 12","0.017.789 ","Intel 22B210 ","001067008D8A "," 60 ","CHAP"," MESSAGE TYPE = Challenge Packet IDENTIFIER = 1 LENGTH = 29" " "," 13","0.042.379 ","001067008D8A ","Intel 22B210 "," 60 ","CHAP"," MESSAGE TYPE = Response Packet IDENTIFIER = 1 LENGTH = 28" " "," 14","2.108.101 ","Intel 22B210 ","001067008D8A "," 63 ","CHAP"," MESSAGE TYPE = Success Packet IDENTIFIER = 1 LENGTH = 41" " "," 15","0.000.771 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Echo Request" " "," 16","0.000.780 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," IPCP Configure Request" " "," 17","0.015.939 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," LCP Echo Reply" " "," 18","0.000.066 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," IPCP Configure Request" " "," 19","0.000.035 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," IPCP Configure Ack" " "," 20","0.014.325 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," IPCP Configure Reject" " "," 21","0.047.594 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," IPCP Configure Request" " "," 22","0.018.442 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," IPCP Configure Nak" " "," 23","0.044.002 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," IPCP Configure Request" " "," 24","0.023.661 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," IPCP Configure Ack" " "," 25","0.000.777 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Echo Request" " "," 26","0.038.145 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," LCP Echo Reply" " "," 27","0.045.161 ","[224.0.1.22] ","[208.61.64.253] "," 60 ","IGMP"," Type 6, Ver2 Membership Report" " "," 28","0.002.243 ","[255.255.255.255] ","[208.61.64.253] "," 590 ","DHCP"," Request, Message type: DHCP Inform" " "," 29","0.024.965 ","[208.61.64.253] ","[205.152.56.129] "," 78 ","ICMP"," Destination unreachable (invalid code)" " "," 30","0.226.045 ","[255.255.255.255] ","[208.61.64.253] "," 590 ","DHCP"," Request, Message type: DHCP Inform" " "," 31","0.498.599 ","[255.255.255.255] ","[208.61.64.253] "," 590 ","DHCP"," Request, Message type: DHCP Inform" " "," 32","0.029.970 ","[208.61.64.253] ","[205.152.56.129] "," 78 ","ICMP"," Destination unreachable (invalid code)" " "," 33","0.970.284 ","[224.0.1.35] ","[208.61.64.253] "," 84 ","SLP"," VER=1 FUNC= Service Request" " "," 34","0.999.897 ","[224.0.1.35] ","[208.61.64.253] "," 84 ","SLP"," VER=1 FUNC= Service Request" " "," 35","0.055.470 ","[205.152.0.20] ","[208.61.64.253] "," 60 ","ICMP"," Echo" " "," 36","0.012.334 ","[208.61.64.253] ","[205.152.0.20] "," 60 ","ICMP"," Echo reply" " "," 37","0.010.410 ","[205.152.0.5] ","[208.61.64.253] "," 60 ","ICMP"," Echo" " "," 38","0.011.250 ","[208.61.64.253] ","[205.152.0.5] "," 60 ","ICMP"," Echo reply" " "," 39","0.926.558 ","[224.0.1.35] ","[208.61.64.253] "," 84 ","SLP"," VER=1 FUNC= Service Request" " "," 40","0.687.989 ","001067008D8A ","Intel 22B210 "," 60 ","PPP"," LCP Echo Request" " "," 41","0.010.296 ","Intel 22B210 ","001067008D8A "," 60 ","PPP"," LCP Echo Reply" " "," 42","1.332.724 ","[224.0.1.35] ","[208.61.64.253] "," 84 ","SLP"," VER=1 FUNC= Service Request" " "," 43","1.973.337 ","[224.0.1.22] ","[208.61.64.253] "," 60 ","IGMP"," Type 6, Ver2 Membership Report"