Спойлер
03.10.2019 22:08:24.084295 VERBOSE LWP10300 [src/radius_base.cpp:56] ============== Authorization packet #1 received from 192.168.200.200, size: 174 ==============
03.10.2019 22:08:24.084517 VERBOSE LWP10300 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 22:08:24.084554 VERBOSE LWP10300 [src/radius_record.cpp:108] Authenticator: 7be23c2bfe01c5142e46e31ca2e189be
03.10.2019 22:08:24.084573 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:08:24.084586 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:08:24.084595 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:08:24.084608 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:08:24.084619 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:08:24.084627 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:08:24.084635 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:08:24.084642 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:08:24.084651 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:08:24.084669 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:08:24.084679 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:08:24.084693 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Password", value: "248a3c51bc80c06a205033b951cdd3344bf754771639de2edf059b3f2c93efb5"
03.10.2019 22:08:24.084711 VERBOSE LWP10300 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 22:08:24.084723 VERBOSE LWP10300 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:08:24.085307 VERBOSE LWP10300 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 22:08:24.086459 INFO LWP10300 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac4a057d
03.10.2019 22:08:24.089863 VERBOSE LWP10300 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 22:08:24.090363 VERBOSE LWP10300 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (900)
03.10.2019 22:08:24.094785 WARNING LWP10300 [src/radius.cpp:2062] ANI '18:a9:05:cb:c3:0a' is bound to 10.11.0.8/255.255.255.255 for user '18:a9:05:cb:c3:0a'
03.10.2019 22:08:24.095048 VERBOSE LWP10300 [src/radius.cpp:2144] Client IP/Netmask: 10.11.0.8/255.255.255.255, ANI: "18:a9:05:cb:c3:0a"
03.10.2019 22:08:24.095069 WARNING LWP10300 [src/radius.cpp:536] Unable to allocate delegated prefix
03.10.2019 22:08:24.095075 WARNING LWP10300 [src/radius.cpp:538] Unable to allocate framed prefix
03.10.2019 22:08:24.095086 INFO LWP10300 [src/radius.cpp:609] Access-Accept, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac4a057d
03.10.2019 22:08:24.095091 VERBOSE LWP10300 [src/radius.cpp:612] =============== Output attributes dump: ===============
03.10.2019 22:08:24.095106 VERBOSE LWP10300 [src/radius.cpp:706] Attribute "Session-Timeout", value: "900"
03.10.2019 22:08:24.095114 VERBOSE LWP10300 [src/radius.cpp:711] Attribute "Service-Type", value: "2"
03.10.2019 22:08:24.095121 VERBOSE LWP10300 [src/radius.cpp:715] Attribute "Framed-Protocol", value: "1"
03.10.2019 22:08:24.095131 VERBOSE LWP10300 [src/radius.cpp:726] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:08:24.095139 VERBOSE LWP10300 [src/radius.cpp:746] Attribute "Framed-IP-Netmask", value: "255.255.255.255"
03.10.2019 22:08:24.096280 VERBOSE LWP10300 [src/radius.cpp:756] Attribute "Class", tag: "125", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:08:24.096340 VERBOSE LWP10300 [src/radius.cpp:799] Attribute "Acct-Interim-Interval", value: "150"
03.10.2019 22:08:24.096355 VERBOSE LWP10300 [src/radius.cpp:820] INSERT INTO CACHE NAS_IP_ADDRESS: 192.168.200.200; UUID(session_id): 774dcff2ac4a057d
03.10.2019 22:08:24.101185 WARNING LWP10300 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 22:08:24.101223 VERBOSE LWP10300 [src/radius_record.cpp:90] Access-Accept (2), id: 0x1, length: 96
03.10.2019 22:08:24.101267 VERBOSE LWP10300 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 16 <=<=<=<=<=<=<=<
03.10.2019 22:08:24.107614 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #2 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:08:24.107652 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x1, length: 240
03.10.2019 22:08:24.107668 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: 887b6e5682b631760b2d6cd2092d1226
03.10.2019 22:08:24.107678 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:08:24.107686 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:08:24.107694 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:08:24.107701 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:08:24.107707 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:08:24.107713 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:08:24.107720 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:08:24.107727 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:08:24.107733 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:08:24.107741 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:08:24.107750 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:08:24.107757 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "1"
03.10.2019 22:08:24.107770 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:08:24.107776 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:08:24.107782 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "0"
03.10.2019 22:08:24.107788 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "0"
03.10.2019 22:08:24.107794 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "0"
03.10.2019 22:08:24.107799 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "0"
03.10.2019 22:08:24.107805 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "0"
03.10.2019 22:08:24.107811 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:08:24.107817 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:08:24.107824 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:08:24.107834 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:08:24.107879 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = START
03.10.2019 22:08:24.107895 INFO LWP10301 [src/radius.cpp:2661] Acct START, Session-Id 774dcff2ac4a057d
03.10.2019 22:08:24.107925 VERBOSE LWP10301 [src/radius.cpp:2680] No record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:08:24.112128 VERBOSE LWP10301 [src/radius.cpp:2696] Looking for auth session: NAS_IP_ADDRESS: 192.168.200.200; UUID(session_id): 774dcff2ac4a057d;
03.10.2019 22:08:24.112151 VERBOSE LWP10301 [src/radius.cpp:2699] FOUND IN AUTH CACHE!
03.10.2019 22:08:24.112160 INFO LWP10301 [src/radius.cpp:2745] Starting Handler:ScriptStart for 18:a9:05:cb:c3:0a
03.10.2019 22:08:24.112439 VERBOSE LWP10301 [src/radius.cpp:2774] End DRadius::RunAcctRequestInst
03.10.2019 22:08:24.112458 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x1, length: 20
03.10.2019 22:08:24.112500 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 4 <=<=<=<=<=<=<=<
03.10.2019 22:08:59.190435 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:08:59.229842 VERBOSE LWP10299 [src/radius.cpp:2939] Start billing sessions
03.10.2019 22:08:59.229871 VERBOSE LWP10299 [src/radius_session.cpp:753] Put data to DB for sessions <774dcff2ac4a057d>
03.10.2019 22:08:59.254918 VERBOSE LWP10297 [src/radius.cpp:1762] Flush is done
03.10.2019 22:09:58.138244 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms
03.10.2019 22:10:00.260274 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:10:54.113392 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #3 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:10:54.113505 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x2, length: 240
03.10.2019 22:10:54.113525 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: c045bd9335d9cf53d5d3db2bd9ede4b4
03.10.2019 22:10:54.113566 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:10:54.113576 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:10:54.113593 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:10:54.113601 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:10:54.113609 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:10:54.113616 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:10:54.113623 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:10:54.113631 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:10:54.113639 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:10:54.113648 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:10:54.113660 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:10:54.113667 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "3"
03.10.2019 22:10:54.113674 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:10:54.113682 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:10:54.113689 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "150"
03.10.2019 22:10:54.113695 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "68184"
03.10.2019 22:10:54.113702 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "16238"
03.10.2019 22:10:54.113709 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "583"
03.10.2019 22:10:54.113716 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "155"
03.10.2019 22:10:54.113722 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:10:54.113729 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:10:54.113737 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:10:54.113749 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:10:54.113814 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = UPDATE
03.10.2019 22:10:54.113827 VERBOSE LWP10301 [src/radius.cpp:2685] Record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:10:54.113839 VERBOSE LWP10301 [src/radius.cpp:2790] End DRadius::RunAcctRequestInst
03.10.2019 22:10:54.113847 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x2, length: 20
03.10.2019 22:10:54.113889 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 0 <=<=<=<=<=<=<=<
03.10.2019 22:11:01.265609 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:11:01.304890 VERBOSE LWP10299 [src/radius.cpp:2939] Start billing sessions
03.10.2019 22:11:01.304913 VERBOSE LWP10299 [src/radius_session.cpp:753] Put data to DB for sessions <774dcff2ac4a057d>
03.10.2019 22:11:01.305522 VERBOSE LWP10299 [src/radius_session.cpp:773] Put data to DB for session <774dcff2ac4a057d>
03.10.2019 22:11:01.307527 VERBOSE LWP10299 [src/radius_session.cpp:881] Vg_id: 1, Source tariff 1, category 0: units included = 0, above price = 0
03.10.2019 22:11:01.307567 VERBOSE LWP10299 [src/radius_session.cpp:916] Billed traffic = 16238 bytes
03.10.2019 22:11:01.309640 INFO LWP10299 [src/charge_vgroup.cpp:181] Charging: vg_id = 1, tar_id = 1, cat_idx = 0, consumed = 0.0154858, climit = 0.0808086, price = unlim, amount = 0
03.10.2019 22:11:01.338173 VERBOSE LWP10297 [src/radius.cpp:1762] Flush is done
03.10.2019 22:11:01.413458 VERBOSE LWP10299 [src/radius_session.cpp:1095] Total charge amount for '18:a9:05:cb:c3:0a'(vg_id = 1): 0
03.10.2019 22:11:58.153365 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms
03.10.2019 22:12:02.343155 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:13:03.348418 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:13:24.113384 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #4 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:13:24.113490 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x3, length: 240
03.10.2019 22:13:24.113509 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: 4befad6c502c300e252d836b30eb8671
03.10.2019 22:13:24.113521 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:13:24.113548 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:13:24.113559 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:13:24.113567 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:13:24.113575 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:13:24.113591 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:13:24.113598 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:13:24.113605 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:13:24.113612 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:13:24.113630 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:13:24.113641 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:13:24.113649 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "3"
03.10.2019 22:13:24.113656 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:13:24.113663 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:13:24.113670 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "300"
03.10.2019 22:13:24.113677 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "86077"
03.10.2019 22:13:24.113684 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "34741"
03.10.2019 22:13:24.113690 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "766"
03.10.2019 22:13:24.113697 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "301"
03.10.2019 22:13:24.113703 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:13:24.113710 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:13:24.113718 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:13:24.113730 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:13:24.113775 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = UPDATE
03.10.2019 22:13:24.113787 VERBOSE LWP10301 [src/radius.cpp:2685] Record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:13:24.113799 VERBOSE LWP10301 [src/radius.cpp:2790] End DRadius::RunAcctRequestInst
03.10.2019 22:13:24.113808 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x3, length: 20
03.10.2019 22:13:24.113849 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 0 <=<=<=<=<=<=<=<
03.10.2019 22:13:58.168430 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms
03.10.2019 22:14:04.353744 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:14:04.390093 VERBOSE LWP10299 [src/radius.cpp:2939] Start billing sessions
03.10.2019 22:14:04.390117 VERBOSE LWP10299 [src/radius_session.cpp:753] Put data to DB for sessions <774dcff2ac4a057d>
03.10.2019 22:14:04.390127 VERBOSE LWP10299 [src/radius_session.cpp:773] Put data to DB for session <774dcff2ac4a057d>
03.10.2019 22:14:04.391646 VERBOSE LWP10299 [src/radius_session.cpp:881] Vg_id: 1, Source tariff 1, category 0: units included = 0, above price = 0
03.10.2019 22:14:04.391672 VERBOSE LWP10299 [src/radius_session.cpp:916] Billed traffic = 18503 bytes
03.10.2019 22:14:04.393275 INFO LWP10299 [src/charge_vgroup.cpp:181] Charging: vg_id = 1, tar_id = 1, cat_idx = 0, consumed = 0.0176458, climit = 0.0962944, price = unlim, amount = 0
03.10.2019 22:14:04.415098 VERBOSE LWP10297 [src/radius.cpp:1762] Flush is done
03.10.2019 22:14:04.523729 VERBOSE LWP10299 [src/radius_session.cpp:1095] Total charge amount for '18:a9:05:cb:c3:0a'(vg_id = 1): 0
03.10.2019 22:15:05.420418 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:15:54.113391 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #5 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:15:54.113494 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x4, length: 240
03.10.2019 22:15:54.113511 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: 6a300ff089b739d35c1c92b59f0946e9
03.10.2019 22:15:54.113522 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:15:54.113578 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:15:54.113590 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:15:54.113599 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:15:54.113606 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:15:54.113614 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:15:54.113622 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:15:54.113629 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:15:54.113637 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:15:54.113646 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:15:54.113658 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:15:54.113665 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "3"
03.10.2019 22:15:54.113673 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:15:54.113680 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:15:54.113687 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "450"
03.10.2019 22:15:54.113694 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "147712"
03.10.2019 22:15:54.113700 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "49836"
03.10.2019 22:15:54.113716 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "1381"
03.10.2019 22:15:54.113723 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "451"
03.10.2019 22:15:54.113730 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:15:54.113737 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:15:54.113745 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:15:54.113757 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:15:54.113801 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = UPDATE
03.10.2019 22:15:54.113813 VERBOSE LWP10301 [src/radius.cpp:2685] Record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:15:54.113826 VERBOSE LWP10301 [src/radius.cpp:2790] End DRadius::RunAcctRequestInst
03.10.2019 22:15:54.113834 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x4, length: 20
03.10.2019 22:15:54.113872 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 0 <=<=<=<=<=<=<=<
03.10.2019 22:15:58.183166 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms
03.10.2019 22:16:06.425732 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:16:06.456818 VERBOSE LWP10299 [src/radius.cpp:2939] Start billing sessions
03.10.2019 22:16:06.456842 VERBOSE LWP10299 [src/radius_session.cpp:753] Put data to DB for sessions <774dcff2ac4a057d>
03.10.2019 22:16:06.456852 VERBOSE LWP10299 [src/radius_session.cpp:773] Put data to DB for session <774dcff2ac4a057d>
03.10.2019 22:16:06.458286 VERBOSE LWP10299 [src/radius_session.cpp:881] Vg_id: 1, Source tariff 1, category 0: units included = 0, above price = 0
03.10.2019 22:16:06.458311 VERBOSE LWP10299 [src/radius_session.cpp:916] Billed traffic = 15095 bytes
03.10.2019 22:16:06.459831 INFO LWP10299 [src/charge_vgroup.cpp:181] Charging: vg_id = 1, tar_id = 1, cat_idx = 0, consumed = 0.0143957, climit = 0.11394, price = unlim, amount = 0
03.10.2019 22:16:06.481776 VERBOSE LWP10297 [src/radius.cpp:1762] Flush is done
03.10.2019 22:16:06.532031 VERBOSE LWP10299 [src/radius_session.cpp:1095] Total charge amount for '18:a9:05:cb:c3:0a'(vg_id = 1): 0
03.10.2019 22:17:07.486874 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:17:58.198073 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms
03.10.2019 22:18:08.492089 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:18:24.113349 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #6 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:18:24.113455 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x5, length: 240
03.10.2019 22:18:24.113472 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: 8471380c716f29381e353e2c5707e4df
03.10.2019 22:18:24.113483 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:18:24.113492 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:18:24.113500 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:18:24.113507 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:18:24.113514 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:18:24.113521 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:18:24.113528 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:18:24.113556 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:18:24.113563 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:18:24.113572 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:18:24.113583 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:18:24.113591 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "3"
03.10.2019 22:18:24.113598 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:18:24.113605 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:18:24.113611 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "600"
03.10.2019 22:18:24.113617 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "180801"
03.10.2019 22:18:24.113624 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "64183"
03.10.2019 22:18:24.113630 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "1857"
03.10.2019 22:18:24.113636 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "601"
03.10.2019 22:18:24.113643 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:18:24.113649 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:18:24.113656 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:18:24.113668 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:18:24.113711 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = UPDATE
03.10.2019 22:18:24.113723 VERBOSE LWP10301 [src/radius.cpp:2685] Record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:18:24.113734 VERBOSE LWP10301 [src/radius.cpp:2790] End DRadius::RunAcctRequestInst
03.10.2019 22:18:24.113743 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x5, length: 20
03.10.2019 22:18:24.113781 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 0 <=<=<=<=<=<=<=<
03.10.2019 22:19:09.497351 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:19:09.531135 VERBOSE LWP10299 [src/radius.cpp:2939] Start billing sessions
03.10.2019 22:19:09.531160 VERBOSE LWP10299 [src/radius_session.cpp:753] Put data to DB for sessions <774dcff2ac4a057d>
03.10.2019 22:19:09.531169 VERBOSE LWP10299 [src/radius_session.cpp:773] Put data to DB for session <774dcff2ac4a057d>
03.10.2019 22:19:09.532637 VERBOSE LWP10299 [src/radius_session.cpp:881] Vg_id: 1, Source tariff 1, category 0: units included = 0, above price = 0
03.10.2019 22:19:09.532661 VERBOSE LWP10299 [src/radius_session.cpp:916] Billed traffic = 14347 bytes
03.10.2019 22:19:09.534152 INFO LWP10299 [src/charge_vgroup.cpp:181] Charging: vg_id = 1, tar_id = 1, cat_idx = 0, consumed = 0.0136824, climit = 0.128336, price = unlim, amount = 0
03.10.2019 22:19:09.572827 VERBOSE LWP10297 [src/radius.cpp:1762] Flush is done
03.10.2019 22:19:09.681448 VERBOSE LWP10299 [src/radius_session.cpp:1095] Total charge amount for '18:a9:05:cb:c3:0a'(vg_id = 1): 0
03.10.2019 22:19:58.213257 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms
03.10.2019 22:20:10.578151 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:20:54.113368 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #7 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:20:54.113469 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x6, length: 240
03.10.2019 22:20:54.113486 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: da9141088979d02e325b73f08ab84c57
03.10.2019 22:20:54.113497 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:20:54.113506 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:20:54.113514 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:20:54.113522 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:20:54.113528 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:20:54.113553 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:20:54.113561 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:20:54.113568 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:20:54.113576 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:20:54.113585 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:20:54.113596 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:20:54.113603 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "3"
03.10.2019 22:20:54.113610 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:20:54.113617 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:20:54.113623 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "750"
03.10.2019 22:20:54.113630 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "256295"
03.10.2019 22:20:54.113636 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "80166"
03.10.2019 22:20:54.113642 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "2648"
03.10.2019 22:20:54.113649 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "751"
03.10.2019 22:20:54.113655 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:20:54.113661 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:20:54.113669 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:20:54.113681 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:20:54.113723 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = UPDATE
03.10.2019 22:20:54.113735 VERBOSE LWP10301 [src/radius.cpp:2685] Record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:20:54.113746 VERBOSE LWP10301 [src/radius.cpp:2790] End DRadius::RunAcctRequestInst
03.10.2019 22:20:54.113755 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x6, length: 20
03.10.2019 22:20:54.113793 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 0 <=<=<=<=<=<=<=<
03.10.2019 22:21:11.583398 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:21:11.615554 VERBOSE LWP10299 [src/radius.cpp:2939] Start billing sessions
03.10.2019 22:21:11.615576 VERBOSE LWP10299 [src/radius_session.cpp:753] Put data to DB for sessions <774dcff2ac4a057d>
03.10.2019 22:21:11.615585 VERBOSE LWP10299 [src/radius_session.cpp:773] Put data to DB for session <774dcff2ac4a057d>
03.10.2019 22:21:11.617077 VERBOSE LWP10299 [src/radius_session.cpp:881] Vg_id: 1, Source tariff 1, category 0: units included = 0, above price = 0
03.10.2019 22:21:11.617102 VERBOSE LWP10299 [src/radius_session.cpp:916] Billed traffic = 15983 bytes
03.10.2019 22:21:11.618753 INFO LWP10299 [src/charge_vgroup.cpp:181] Charging: vg_id = 1, tar_id = 1, cat_idx = 0, consumed = 0.0152426, climit = 0.142018, price = unlim, amount = 0
03.10.2019 22:21:11.632265 VERBOSE LWP10297 [src/radius.cpp:1762] Flush is done
03.10.2019 22:21:11.708935 VERBOSE LWP10299 [src/radius_session.cpp:1095] Total charge amount for '18:a9:05:cb:c3:0a'(vg_id = 1): 0
03.10.2019 22:21:58.228725 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 5 ms
03.10.2019 22:22:12.637351 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:23:13.642749 VERBOSE LWP10297 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 22:23:24.110025 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #8 received from 192.168.200.200, size: 246 ==============
03.10.2019 22:23:24.110126 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x6, length: 246
03.10.2019 22:23:24.110144 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: f8a13e3d65df24106e0cb92d9034968b
03.10.2019 22:23:24.110155 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:24.110165 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:23:24.110173 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:23:24.110181 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "573"
03.10.2019 22:23:24.110188 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:23:24.110195 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:23:24.110202 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:24.110209 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:23:24.110216 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "026404d4-2481-4f7d-87bc-f40ce75830e3/1"
03.10.2019 22:23:24.110225 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:23:24.110235 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:23:24.110242 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "2"
03.10.2019 22:23:24.110249 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:23:24.110256 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057d"
03.10.2019 22:23:24.110263 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "900"
03.10.2019 22:23:24.110269 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "297110"
03.10.2019 22:23:24.110275 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "94393"
03.10.2019 22:23:24.110282 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "3064"
03.10.2019 22:23:24.110288 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "902"
03.10.2019 22:23:24.110294 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:23:24.110301 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:23:24.110308 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:23:24.110317 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Terminate-Cause", value: "5"
03.10.2019 22:23:24.110326 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:23:24.110368 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = STOP
03.10.2019 22:23:24.110384 INFO LWP10301 [src/radius.cpp:2661] Acct STOP (Session-Timeout), Session-Id 774dcff2ac4a057d, vg_id 1, login 1234
03.10.2019 22:23:24.110390 VERBOSE LWP10301 [src/radius.cpp:2685] Record with SessionID = '774dcff2ac4a057d' found in cache
03.10.2019 22:23:24.111194 VERBOSE LWP10301 [src/radius.cpp:2826] Delegated prefix /0 released for session 774dcff2ac4a057d (Acct-STOP)
03.10.2019 22:23:24.111215 VERBOSE LWP10301 [src/radius.cpp:2829] Framed prefix /0 released for session 774dcff2ac4a057d (Acct-STOP)
03.10.2019 22:23:24.111222 VERBOSE LWP10301 [src/radius.cpp:2836] End DRadius::RunAcctRequestInst
03.10.2019 22:23:24.111334 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x6, length: 20
03.10.2019 22:23:24.111420 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 1 <=<=<=<=<=<=<=<
03.10.2019 22:23:24.819765 INFO LWP10296 [src/radius.cpp:2433] Running ScriptStop for '1234' session '774dcff2ac4a057d', stopped [99]
03.10.2019 22:23:24.819816 VERBOSE LWP10296 [src/radius.cpp:2321] Session '774dcff2ac4a057d' is marked for removed from memory cache
03.10.2019 22:23:32.302423 VERBOSE LWP10300 [src/radius_base.cpp:56] ============== Authorization packet #9 received from 192.168.200.200, size: 174 ==============
03.10.2019 22:23:32.302471 VERBOSE LWP10300 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 22:23:32.302487 VERBOSE LWP10300 [src/radius_record.cpp:108] Authenticator: 11fb72d992f674ab1469eca3665ea84a
03.10.2019 22:23:32.302507 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:32.302516 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:23:32.302525 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:23:32.302553 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "574"
03.10.2019 22:23:32.302561 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:23:32.302569 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:23:32.302585 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:32.302592 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:23:32.302602 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:23:32.302613 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:23:32.302622 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057e"
03.10.2019 22:23:32.302640 VERBOSE LWP10300 [src/radius_record.cpp:113] Attribute "Password", value: "bc7c851febd1370a9bd1727269732da4191ede6faa841d592d84fc938a9a8588"
03.10.2019 22:23:32.302649 VERBOSE LWP10300 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 22:23:32.302659 VERBOSE LWP10300 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:23:32.302752 VERBOSE LWP10300 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 22:23:32.303400 INFO LWP10300 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac4a057e
03.10.2019 22:23:32.304791 VERBOSE LWP10300 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 22:23:32.305071 VERBOSE LWP10300 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (900)
03.10.2019 22:23:32.307174 WARNING LWP10300 [src/radius.cpp:2062] ANI '18:a9:05:cb:c3:0a' is bound to 10.11.0.8/255.255.255.255 for user '18:a9:05:cb:c3:0a'
03.10.2019 22:23:32.307439 VERBOSE LWP10300 [src/radius.cpp:2144] Client IP/Netmask: 10.11.0.8/255.255.255.255, ANI: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:32.307456 WARNING LWP10300 [src/radius.cpp:536] Unable to allocate delegated prefix
03.10.2019 22:23:32.307461 WARNING LWP10300 [src/radius.cpp:538] Unable to allocate framed prefix
03.10.2019 22:23:32.307478 INFO LWP10300 [src/radius.cpp:609] Access-Accept, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac4a057e
03.10.2019 22:23:32.307484 VERBOSE LWP10300 [src/radius.cpp:612] =============== Output attributes dump: ===============
03.10.2019 22:23:32.307497 VERBOSE LWP10300 [src/radius.cpp:706] Attribute "Session-Timeout", value: "900"
03.10.2019 22:23:32.307506 VERBOSE LWP10300 [src/radius.cpp:711] Attribute "Service-Type", value: "2"
03.10.2019 22:23:32.307513 VERBOSE LWP10300 [src/radius.cpp:715] Attribute "Framed-Protocol", value: "1"
03.10.2019 22:23:32.307524 VERBOSE LWP10300 [src/radius.cpp:726] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:23:32.307549 VERBOSE LWP10300 [src/radius.cpp:746] Attribute "Framed-IP-Netmask", value: "255.255.255.255"
03.10.2019 22:23:32.308691 VERBOSE LWP10300 [src/radius.cpp:756] Attribute "Class", tag: "113", value: "46ca1230-3348-4271-abc0-34178d06213c/1"
03.10.2019 22:23:32.308752 VERBOSE LWP10300 [src/radius.cpp:799] Attribute "Acct-Interim-Interval", value: "150"
03.10.2019 22:23:32.308770 VERBOSE LWP10300 [src/radius.cpp:820] INSERT INTO CACHE NAS_IP_ADDRESS: 192.168.200.200; UUID(session_id): 774dcff2ac4a057e
03.10.2019 22:23:32.312398 WARNING LWP10300 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 22:23:32.312432 VERBOSE LWP10300 [src/radius_record.cpp:90] Access-Accept (2), id: 0x1, length: 96
03.10.2019 22:23:32.312778 VERBOSE LWP10300 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 10 <=<=<=<=<=<=<=<
03.10.2019 22:23:32.321052 VERBOSE LWP10301 [src/radius_base.cpp:56] ============== Accounting packet #10 received from 192.168.200.200, size: 240 ==============
03.10.2019 22:23:32.321089 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Request (4), id: 0x1, length: 240
03.10.2019 22:23:32.321104 VERBOSE LWP10301 [src/radius_record.cpp:108] Authenticator: 0b443752daadc4f15f3c562db5f9af2a
03.10.2019 22:23:32.321117 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:32.321128 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 22:23:32.321136 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 22:23:32.321145 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "574"
03.10.2019 22:23:32.321152 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 22:23:32.321159 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 22:23:32.321166 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 22:23:32.321174 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 22:23:32.321206 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Class", value: "46ca1230-3348-4271-abc0-34178d06213c/1"
03.10.2019 22:23:32.321217 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 22:23:32.321230 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 22:23:32.321237 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Status-Type", value: "1"
03.10.2019 22:23:32.321244 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Authentic", value: "1"
03.10.2019 22:23:32.321251 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac4a057e"
03.10.2019 22:23:32.321263 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Session-Time", value: "0"
03.10.2019 22:23:32.321269 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Octets", value: "0"
03.10.2019 22:23:32.321275 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Octets", value: "0"
03.10.2019 22:23:32.321281 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Packets", value: "0"
03.10.2019 22:23:32.321287 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Packets", value: "0"
03.10.2019 22:23:32.321293 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Input-Gigawords", value: "0"
03.10.2019 22:23:32.321299 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Acct-Output-Gigawords", value: "0"
03.10.2019 22:23:32.321306 VERBOSE LWP10301 [src/radius_record.cpp:113] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 22:23:32.321319 VERBOSE LWP10301 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 22:23:32.321374 INFO LWP10301 [src/radius.cpp:2624] Acct-Status-Type = START
03.10.2019 22:23:32.321389 INFO LWP10301 [src/radius.cpp:2661] Acct START, Session-Id 774dcff2ac4a057e
03.10.2019 22:23:32.321424 VERBOSE LWP10301 [src/radius.cpp:2680] No record with SessionID = '774dcff2ac4a057e' found in cache
03.10.2019 22:23:32.322614 VERBOSE LWP10301 [src/radius.cpp:2696] Looking for auth session: NAS_IP_ADDRESS: 192.168.200.200; UUID(session_id): 774dcff2ac4a057e;
03.10.2019 22:23:32.322625 VERBOSE LWP10301 [src/radius.cpp:2699] FOUND IN AUTH CACHE!
03.10.2019 22:23:32.322631 INFO LWP10301 [src/radius.cpp:2745] Starting Handler:ScriptStart for 18:a9:05:cb:c3:0a
03.10.2019 22:23:32.322850 VERBOSE LWP10301 [src/radius.cpp:2774] End DRadius::RunAcctRequestInst
03.10.2019 22:23:32.322866 VERBOSE LWP10301 [src/radius_record.cpp:90] Accounting-Response (5), id: 0x1, length: 20
03.10.2019 22:23:32.322906 VERBOSE LWP10301 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Accounting answer sent successfully, time: 1 <=<=<=<=<=<=<=<
03.10.2019 22:23:58.243824 VERBOSE LWP10293 [src/shaper.cpp:59] Calculating shape is finished. Elapsed 4 ms