2008-04-09 01:19:00 Local7.Notice 192.168.0.1 55504: Apr 9 01:18:56.619: %SYS-5-CONFIG_I: Configured from console by vty0 (192.168.0.107) 2008-04-09 01:19:00 Local7.Notice 192.168.0.1 55505: Apr 9 01:18:57.283: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55506: Apr 9 01:19:31.343: ATM0 dslsar_1a_reset: PLIM type is 18, Rate is 800Mbps 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55507: Apr 9 01:19:31.343: ATM0 dslsar_1a_shutdown: state=4 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55508: Apr 9 01:19:31.343: dslsar disable ATM0 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55509: 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55510: Apr 9 01:19:31.347: DSL: SET: [DMTDSL_STOP -> DMTDSL_INIT] 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55511: Apr 9 01:19:31.347: Resetting ATM0 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55512: Apr 9 01:19:31.347: dslsar_1a_config(ATM0) 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55513: Apr 9 01:19:31.347: dslsar_1a_enable(ATM0) 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55514: Apr 9 01:19:31.347: ATM0: dslsar_init(81C07ED8,FALSE) 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55515: Apr 9 01:19:31.347: dslsar disable ATM0 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55516: 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55517: Apr 9 01:19:31.691: ATM0 dslsar_init: DSLSAR TXRX disabled 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55518: 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55519: Apr 9 01:19:32.186: ATM0 dslsar_1a_enable: restarting VCs: 0 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55520: Apr 9 01:19:32.186: (ATM0)1a_enable,calling atm_activate_pvc, vcd = 1, vc = 0x82047F10adb->flags = 0x4800C 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55521: Apr 9 01:19:32.190: DSL: SM: [DMTDSL_STOP -> DMTDSL_INIT] 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55522: Apr 9 01:19:32.198: DSL(ATM0): Downloading ASW_init_3_8_131.bin 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55523: Apr 9 01:19:32.198: DSL(ATM0): Received response: 0x26 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55524: Apr 9 01:19:32.198: DSL: Unexpected command(0x65) 0x26 received 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55525: Apr 9 01:19:32.198: DSL(ATM0): Could not download ASW_init_3_8_131.bin 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55526: Apr 9 01:19:32.198: DSL(ATM0): Unexpected response 0x65 to ASW_init_3_8_131.bin 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55527: Apr 9 01:19:32.202: DSL(ATM0): Downloading ASW_init_3_8_131.bin 2008-04-09 01:19:35 Local7.Debug 192.168.0.1 55528: Apr 9 01:19:32.226: DSL:(ATM0) Downloaded 5 blocks... Finished! 2008-04-09 01:19:36 Local7.Debug 192.168.0.1 55529: Apr 9 01:19:32.230: DSL(ATM0): Sent command 0x14 2008-04-09 01:19:36 Local7.Error 192.168.0.1 55530: Apr 9 01:19:33.342: %LINK-3-UPDOWN: Interface ATM0, changed state to down 2008-04-09 01:19:37 Local7.Debug 192.168.0.1 55531: Apr 9 01:19:33.342: dslsar_atm_lineaction(ATM0): state=0 2008-04-09 01:19:37 Local7.Debug 192.168.0.1 55532: Apr 9 01:19:33.342: dslsar_1a_teardown_vc(ATM0): vc:1 vpi:0 vci:35 2008-04-09 01:19:37 Local7.Debug 192.168.0.1 55533: Apr 9 01:19:33.342: dslsar_vc_teardown: Trying to unconfigure a currently unused VC 2008-04-09 01:19:37 Local7.Debug 192.168.0.1 55534: Apr 9 01:19:33.342: ATM0.1 VC 0/35, num_vc is already 0 2008-04-09 01:19:37 Local7.Debug 192.168.0.1 55535: Apr 9 01:19:33.342: dslsar_atm_lineaction: REG_INVOKE: line down 2008-04-09 01:19:37 Local7.Debug 192.168.0.1 55536: 2008-04-09 01:19:38 Local7.Notice 192.168.0.1 55537: Apr 9 01:19:34.434: %SYS-5-CONFIG_I: Configured from console by vty0 (192.168.0.107) 2008-04-09 01:19:40 Local7.Debug 192.168.0.1 55538: Apr 9 01:19:36.138: DSL(ATM0): Received response: 0x80 2008-04-09 01:19:40 Local7.Debug 192.168.0.1 55539: Apr 9 01:19:36.530: DSL(ATM0): Downloading ASW_R3_8_131.bin 2008-04-09 01:19:40 Local7.Debug 192.168.0.1 55540: Apr 9 01:19:36.762: DSL:(ATM0) Downloaded 100 blocks 2008-04-09 01:19:40 Local7.Debug 192.168.0.1 55541: Apr 9 01:19:36.990: DSL:(ATM0) Downloaded 200 blocks 2008-04-09 01:19:41 Local7.Debug 192.168.0.1 55542: Apr 9 01:19:37.226: DSL:(ATM0) Downloaded 300 blocks 2008-04-09 01:19:41 Local7.Debug 192.168.0.1 55543: Apr 9 01:19:37.454: DSL:(ATM0) Downloaded 400 blocks 2008-04-09 01:19:41 Local7.Debug 192.168.0.1 55544: Apr 9 01:19:37.686: DSL:(ATM0) Downloaded 500 blocks 2008-04-09 01:19:41 Local7.Debug 192.168.0.1 55545: Apr 9 01:19:37.914: DSL:(ATM0) Downloaded 600 blocks 2008-04-09 01:19:41 Local7.Debug 192.168.0.1 55546: Apr 9 01:19:38.142: DSL:(ATM0) Downloaded 700 blocks 2008-04-09 01:19:42 Local7.Debug 192.168.0.1 55547: Apr 9 01:19:38.378: DSL:(ATM0) Downloaded 800 blocks 2008-04-09 01:19:42 Local7.Debug 192.168.0.1 55548: Apr 9 01:19:38.610: DSL:(ATM0) Downloaded 900 blocks 2008-04-09 01:19:42 Local7.Debug 192.168.0.1 55549: Apr 9 01:19:38.842: DSL:(ATM0) Downloaded 1000 blocks 2008-04-09 01:19:42 Local7.Debug 192.168.0.1 55550: Apr 9 01:19:39.070: DSL:(ATM0) Downloaded 1100 blocks 2008-04-09 01:19:42 Local7.Debug 192.168.0.1 55551: Apr 9 01:19:39.298: DSL:(ATM0) Downloaded 1200 blocks 2008-04-09 01:19:43 Local7.Debug 192.168.0.1 55552: Apr 9 01:19:39.534: DSL:(ATM0) Downloaded 1300 blocks 2008-04-09 01:19:43 Local7.Debug 192.168.0.1 55553: Apr 9 01:19:39.574: DSL:(ATM0) Downloaded 1317 blocks... Finished! 2008-04-09 01:19:43 Local7.Debug 192.168.0.1 55554: Apr 9 01:19:39.574: DSL(ATM0): Sent command 0x14 2008-04-09 01:19:45 Local7.Debug 192.168.0.1 55555: Apr 9 01:19:41.582: DSL(ATM0):changed current state to do open!! 2008-04-09 01:19:45 Local7.Debug 192.168.0.1 55556: Apr 9 01:19:41.582: DSL(ATM0): Send ADSL_OPEN command. 2008-04-09 01:19:45 Local7.Debug 192.168.0.1 55557: Apr 9 01:19:41.582: DSL(ATM0): Using subfunction 0x15 2008-04-09 01:19:45 Local7.Debug 192.168.0.1 55558: Apr 9 01:19:41.582: DSL(ATM0): Sent command 0x3 2008-04-09 01:19:47 Local7.Debug 192.168.0.1 55559: Apr 9 01:19:44.082: DSL(ATM0): 1: Modem state = 0x8 2008-04-09 01:19:50 Local7.Debug 192.168.0.1 55560: Apr 9 01:19:46.582: DSL(ATM0): 2: Modem state = 0x8 2008-04-09 01:19:52 Local7.Debug 192.168.0.1 55561: Apr 9 01:19:49.082: DSL(ATM0): 3: Modem state = 0x10 2008-04-09 01:19:52 Local7.Debug 192.168.0.1 55562: Apr 9 01:19:49.206: PPPoE : Shutting down client session 2008-04-09 01:19:52 Local7.Info 192.168.0.1 55563: Apr 9 01:19:49.206: %DIALER-6-UNBIND: Interface Vi1 unbound from profile Di1 2008-04-09 01:19:53 Local7.Error 192.168.0.1 55564: Apr 9 01:19:49.246: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to down 2008-04-09 01:19:54 Local7.Notice 192.168.0.1 55565: Apr 9 01:19:50.206: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to down 2008-04-09 01:19:55 Local7.Debug 192.168.0.1 55566: Apr 9 01:19:51.582: DSL(ATM0): 4: Modem state = 0x10 2008-04-09 01:19:57 Local7.Debug 192.168.0.1 55567: Apr 9 01:19:54.082: DSL(ATM0): 5: Modem state = 0x10 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55568: Apr 9 01:19:55.710: DSL(ATM0): Received response: 0x24 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55569: Apr 9 01:19:55.710: DSL(ATM0): Showtime! 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55570: Apr 9 01:19:55.710: DSL(ATM0): Sent command 0x11 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55571: Apr 9 01:19:55.714: DSL(ATM0): Received response: 0x61 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55572: Apr 9 01:19:55.714: DSL(ATM0): Read firmware revision 0x1A04 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55573: Apr 9 01:19:55.714: DSL(ATM0): Sent command 0x31 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55574: Apr 9 01:19:55.718: DSL(ATM0): Received response: 0x12 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55575: Apr 9 01:19:55.718: DSL(ATM0): operation mode 0x0002 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55576: Apr 9 01:19:55.722: DSL(ATM0): Sent command 0x33 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55577: Apr 9 01:19:55.726: DSL(ATM0): Received response: 0x16 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55578: Apr 9 01:19:55.726: DSL(ATM0): Far End ITU Country Code 0x0F 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55579: Apr 9 01:19:55.726: DSL(ATM0): Far End ITU Vendor ID ALCB 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55580: Apr 9 01:19:55.726: DSL(ATM0): Far End ITU Vendor ID Specific 0x0000 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55581: Apr 9 01:19:55.726: DSL(ATM0): Far End ITU Vendor STD Number 0x0001 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55582: Apr 9 01:19:55.726: DSL(ATM0): Sent command 0x32 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55583: Apr 9 01:19:55.738: DSL(ATM0): Received response: 0x14 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55584: Apr 9 01:19:55.738: DSL(ATM0): Near End ITU Country Code 0x00 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55585: Apr 9 01:19:55.738: DSL(ATM0): Near End ITU Vendor ID ALCB 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55586: Apr 9 01:19:55.738: DSL(ATM0): Near End ITU Vendor ID Specific 0x0000 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55587: Apr 9 01:19:55.738: DSL(ATM0): Near End ITU Vendor STD Number 0x0001 2008-04-09 01:19:58 Local7.Debug 192.168.0.1 55588: Apr 9 01:19:55.738: ATM0 dslsar_MatchSARTxToLineSpeed(): usbw 800, clkPerCell 18724 prev_clkPerCell 9702 2008-04-09 01:19:59 Local7.Debug 192.168.0.1 55589: Apr 9 01:19:55.742: ATM0 dslsar_MatchSARTxToLineSpeed(): Changing line speed from fast to slow 2008-04-09 01:19:59 Local7.Debug 192.168.0.1 55590: Apr 9 01:19:55.854: ATM0 dslsar_update_us_bandwidth(): upstream bw =800 Kbps 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55591: Apr 9 01:20:03.494: (ATM0) dslsar_periodic: ENABLING DSLSAR 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55592: 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55593: Apr 9 01:20:03.502: dslsar enable ATM0 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55594: 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55595: Apr 9 01:20:03.506: dslsar_1a_setup_vc(ATM0): vc:1 vpi:0 vci:35 state 2 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55596: Apr 9 01:20:03.506: ATM0: vcd = 1, txring_limit=2 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55597: Apr 9 01:20:03.506: ATM0 dslsar_vc_setup: vcd 1, vpi 0, vci 35, avgrate 0 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55598: Apr 9 01:20:03.506: CONFIGURING(ATM0) VC 1 (0/35) IN TX SCHEDULE TABLE SET 0 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55599: Apr 9 01:20:03.506: (ATM0)Forcing Peakrate and Avgrate to: 800 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55600: Apr 9 01:20:03.506: (ATM0)Requested QoS: Peakrate = 800, Avgrate = 800, Burstsize =0 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55601: Apr 9 01:20:03.506: (ATM0)Configuring VC 1: slot 0 in TST 5 2008-04-09 01:20:06 Local7.Debug 192.168.0.1 55602: Apr 9 01:20:03.506: (ATM0) SUCCESSFUL CONFIGURATION OF VC 1 (0/35), QOS Type 4 2008-04-09 01:20:07 Local7.Debug 192.168.0.1 55603: Apr 9 01:20:03.506: dslsar_setup_cos(ATM0): vc:1 wred_name:­ max_q:0 2008-04-09 01:20:07 Local7.Debug 192.168.0.1 55604: Apr 9 01:20:03.510: CONFIGURING(ATM0) VC 1 (0/35) IN TX SCHEDULE TABLE SET 0 2008-04-09 01:20:07 Local7.Debug 192.168.0.1 55605: Apr 9 01:20:03.510: (ATM0)Forcing Peakrate and Avgrate to: 800 2008-04-09 01:20:07 Local7.Debug 192.168.0.1 55606: Apr 9 01:20:03.510: (ATM0)Requested QoS: Peakrate = 800, Avgrate = 800, Burstsize =0 2008-04-09 01:20:07 Local7.Debug 192.168.0.1 55607: Apr 9 01:20:03.510: (ATM0)Configuring VC 1: slot 0 in TST 5 2008-04-09 01:20:07 Local7.Debug 192.168.0.1 55608: Apr 9 01:20:03.510: (ATM0) SUCCESSFUL CONFIGURATION OF VC 1 (0/35), QOS Type 4 2008-04-09 01:20:08 Local7.Error 192.168.0.1 55609: Apr 9 01:20:05.410: %LINK-3-UPDOWN: Interface ATM0, changed state to up 2008-04-09 01:20:09 Local7.Debug 192.168.0.1 55610: Apr 9 01:20:05.410: dslsar_atm_lineaction(ATM0): state=4 2008-04-09 01:20:09 Local7.Debug 192.168.0.1 55611: Apr 9 01:20:05.410: dslsar_atm_lineaction: REG_INVOKE: line up 2008-04-09 01:20:09 Local7.Debug 192.168.0.1 55612: 2008-04-09 01:20:09 Local7.Notice 192.168.0.1 55613: Apr 9 01:20:06.410: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up 2008-04-09 01:20:12 Auth.Notice 192.168.0.114 port 4 user Port_4 login ok 2008-04-09 01:20:13 Local7.Debug 192.168.0.1 55614: Apr 9 01:20:09.334: Sending PADI: vc=0/35 2008-04-09 01:20:13 Local7.Debug 192.168.0.1 55615: Apr 9 01:20:09.342: PPPoE 0: I PADO R:0030.194c.a382 L:0015.62e5.1651 0/35 ATM0.1 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55616: Apr 9 01:20:11.350: PPPOE: we've got our pado and the pado timer went off 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55617: Apr 9 01:20:11.350: OUT PADR from PPPoE Session 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55618: Apr 9 01:20:11.358: PPPoE 13994: I PADS R:0030.194c.a382 L:0015.62e5.1651 0/35 ATM0.1 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55619: Apr 9 01:20:11.362: IN PADS from PPPoE Session 2008-04-09 01:20:14 Local7.Info 192.168.0.1 55620: Apr 9 01:20:11.366: %DIALER-6-BIND: Interface Vi1 bound to profile Di1 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55621: Apr 9 01:20:11.366: PPPoE: Virtual Access interface obtained. 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55622: Apr 9 01:20:11.366: PPPoE : encap string prepared 2008-04-09 01:20:14 Local7.Debug 192.168.0.1 55623: Apr 9 01:20:11.366: [0]PPPoE 13994: data path set to Virtual Acess 2008-04-09 01:20:15 Local7.Error 192.168.0.1 55624: Apr 9 01:20:11.374: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to up 2008-04-09 01:20:18 Local7.Notice 192.168.0.1 55625: Apr 9 01:20:14.446: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to up 2008-04-09 01:21:29 Local7.Debug 192.168.0.1 55626: Apr 9 01:21:25.976: PPPoE 48501: I PADT R:0030.194c.a382 L:0015.62e5.1651 0/35 ATM0.1