Hello,
I'm facing the "Legacy NAT" error again... I don't understand it's
happening from time to time, without any change of configuration.
Do you think it could come from the implementation? I'm pretty sure It
can't come from a race between zebra and nsis since I wait for zebra to
be "finished" before running nsis.
I've attached the output of the nsis daemon (debug 2) on the router5. I
also give you the captures I made from another time, where the path used
router2 and router3. There are two series of captures, one with errors,
the other one when everything was ok.
I hope you'll be able to help me,
Best regards,
Julien.
(err) Setting Debug Level to 2
(err) Reading IP Routing Table (overwrites nsis.conf IP addresses)
(err) Read Route from IP Routing Table: To:100.0.1.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.0.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.3.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.2.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.5.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.4.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.7.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.6.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.9.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.8.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.11.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.10.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
NSIS daemon v0.5.0 starting ...
My PID id = 506
FD is -1
TEST: no
(warn) *** Connection over Unix Socket ***
(warn) register_unix_sd : new connection sd[12] added to Event Loop.
(warn) *** Received API Call ***
(err) Registered NSLP with NSLPID 3
(warn) Debug client connected
(warn) Debug Client send 3
(warn) *** Connection over Unix Socket ***
(warn) register_unix_sd : new connection sd[14] added to Event Loop.
(warn) *** Received API Call ***
(err) Registered NSLP with NSLPID 5
(warn) *** Connection over Unix Socket ***
(warn) register_unix_sd : new connection sd[15] added to Event Loop.
(warn) *** Received API Call ***
(err) Registered NSLP with NSLPID 1
(warn) *** Received RAO packet (value = 00 00) ***
(warn) *** Dmode message from : 100.0.5.1:4> ***
(info) GIST message:
Version = 1
Hop Count = 49
NSLP ID = 1
Message length = 140
(info) Type = QUERY
(info) S flag = 1
(info) R flag = 1
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.5.1
(info) RouteStateValidityTime = 100000
(info) GistQueryCookie: length = 36
(info) GistStackProposal: length = 12; profileCount = 1
(info) Profile 1: (info) TCP (info)
(info) GistStackConfigurationData: length = 16
(warn) *** This is a UNknown flow ***
(info) FSM: Change state to 0
(info) FSM: Event 5 while state is 0
(warn) Creating new MA
(warn) register_server_sd : new connection sd[16] added to Event Loop.
(info) FSM: Change state to 1
(warn) Found the pending MA
(crit) void GistMAEntry::setSock(Socket * sock)
(warn) *** Cmode connect via IPv4 from : 100.0.5.1:34013 ***
(warn) register_sd : new connection sd[17] added to Event Loop.
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 1
Message length = 136
(info) Type = CONFIRM
(info) S flag = 1
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.5.1
(info) RouteStateValidityTime = 100000
(info) GistResponderCookie: length = 36
(info) GistStackProposal: length = 12; profileCount = 1
(info) Profile 1: (info) TCP (info)
(info) GistStackConfigurationData: length = 12
(warn) *** This is a known flow ***
(info) FSM: Event 9 while state is 1
(warn) Checking Stack Proposal in CONFIRM ...
(err) MA Keep Alive Timer started ...
(info) FSM: Change state to 4
(info) GIST messa(err) Setting Debug Level to 2
(err) Reading IP Routing Table (overwrites nsis.conf IP addresses)
(err) Read Route from IP Routing Table: To:100.0.1.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.0.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.3.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.2.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.5.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.4.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.7.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.6.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.9.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.8.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.11.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.10.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
NSIS daemon v0.5.0 starting ...
My PID id = 506
Starting qosServer (pid: 508) ...
ClsRMF::device = eth0
overall_rate 5mbit, default_rate 100kbit, default_max_rate 5mbit, float max_bw 5242880.000000
(TC) Running "tc qdisc delete dev eth0 root handle 1:"
(IpTables) Running "iptables -t mangle -F PREROUTING"
(TC) Running "tc qdisc add dev eth0 root handle 1: htb default 2"
(TC) Running "tc class add dev eth0 parent 1: classid 1:1 htb rate 5mbit burst 15k"
(TC) Running "tc class add dev eth0 parent 1:1 classid 1:2 htb rate 100kbit ceil 5mbit"
(TC) Running "tc qdisc add dev eth0 parent 1:2 handle 2: sfq perturb 10"
ClsRMF::device = eth1
overall_rate 5mbit, default_rate 100kbit, default_max_rate 5mbit, float max_bw 5242880.000000
(TC) Running "tc qdisc delete dev eth1 root handle 1:"
(IpTables) Running "iptables -t mangle -F PREROUTING"
(TC) Running "tc qdisc add dev eth1 root handle 1: htb default 2"
(TC) Running "tc class add dev eth1 parent 1: classid 1:1 htb rate 5mbit burst 15k"
(TC) Running "tc class add dev eth1 parent 1:1 classid 1:2 htb rate 100kbit ceil 5mbit"
(TC) Running "tc qdisc add dev eth1 parent 1:2 handle 2: sfq perturb 10"
QoSServer: connected to GIST server
SID: ffffffff ffffffff ffffffff ffffffff
fsm : (nil)
(info) FSM: Change state to 0
We are Entity (QNE)
QNE: --> incoming msg
+ SII_Handle : 100.0.5.1
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : DOWNSTREAM
+ msg length : 120 bytes
+ QoS Message Type : RESERVE
+ Object Type : RII
++ RII : 0x71dc98c2
+ Object Type : RSN
++ RSN : 2
++ EpochID : 0x481ef28c
+ Object Type : PacketClassifier
++ PacketClassifier : 0x00
++ PC-Flags : 0xc000
+ Object Type : Qspec
++ V:Id:M_Seq:O_Cmb : 0:0:0:3
+++ QSPEC object : QoS Desired
+++ Object type : Tmod1, length:4
+++ QSPEC object : QoS Available
+++ Object type : Tmod1, length:4
+++ QSPEC object : Minimum QoS
+++ Object type : Tmod1, length:4
+++ Object type : ExcessTreatment, length:1
+ Q:T:R:S-Flag : 1:0:0:0
triggerEvent : ST_IDLE::EV_RX_RESERVE
(info) FSM: Event 1 while state is 0
QoSFsm::idle__rx_reserve()
Reserving ...
(TC) Running "tc class add dev eth0 parent 1:1 classid 1:6 htb rate 10000bps ceil 10000bps"
(TC) Running "tc qdisc add dev eth0 parent 1:6 handle 6: sfq perturb 10"
(IpTables) Running "iptables -t mangle -A PREROUTING --src 100.0.0.1/32 --dst 100.0.9.2/32 -j MARK --set-mark 0x0006"
(TC) Running "tc filter add dev eth0 protocol ip parent 1:0 prio 1 handle 6 fw classid 1:6"
(TC) Running "tc class add dev eth1 parent 1:1 classid 1:6 htb rate 10000bps ceil 10000bps"
(TC) Running "tc qdisc age:
Version = 1
Hop Count = 49
NSLP ID = 1
Message length = 172
(info) Type = DATA
(info) S flag = 1
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNslpData: length = 124; payloadLength = 120
(warn) *** This is a known flow ***
(info) FSM: Event 11 while state is 4
(info)
*** API-Call RecvMessage() ***
(info) nslp_data :
(info) nd_size : 120
(info) nslp_id : 0x0001
(info) sid : (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliable : 1
(info) security : 0
(info) explicitlyRouted : 0
(info) noGistState : 0
(info) ip_ttl : 0
(info) ip_distance : 0
(warn) *** Received API Call ***
(info)
*** API-Call SendMessage() ***
(info) nslp_data :
(info) nd_size : 120
(info) nslp_id : 0x0001
(info) sid : (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliab. : 1
(info) security : 0
(info) local : 0
(info) installRoutingState : 1
(info) timeout : 2000
(info) ip_ttl : 10
(info) gist_hop_count : 50
(warn) *** Starting new Downstream FSM ***
(info) FSM: Change state to 0
(info) FSM: Event 1 while state is 0
(info) *** Dmode message > query (with rao) ***
(info) FSM: Change state to 2
(warn) *** Received API Call ***
(info)
*** API-Call SendMessage() ***
(info) nslp_data :
(info) nd_size : 12
(info) nslp_id : 0x0001
(info) sid : (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 1
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliab. : 1
(info) security : 0
(info) local dd dev eth1 parent 1:6 handle 6: sfq perturb 10"
(IpTables) Running "iptables -t mangle -A PREROUTING --src 100.0.0.1/32 --dst 100.0.9.2/32 -j MARK --set-mark 0x0006"
(TC) Running "tc filter add dev eth1 protocol ip parent 1:0 prio 1 handle 6 fw classid 1:6"
++ V:Id:M_Seq:O_Cmb : 0:0:0:3
+++ QSPEC object : QoS Desired
+++ Object type : Tmod1, length:4
+++ QSPEC object : QoS Available
+++ Object type : Tmod1, length:4
+++ QSPEC object : Minimum QoS
+++ Object type : Tmod1, length:4
+++ Object type : ExcessTreatment, length:1
QNE: outgoing msg -->
+ SII_Handle :
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : DOWNSTREAM
+ msg length : 120 bytes
+ QoS Message Type : RESERVE
+ Object Type : RII
++ RII : 0x71dc98c2
+ Object Type : RSN
++ RSN : 2
++ EpochID : 0x481ef28c
+ Object Type : PacketClassifier
++ PacketClassifier : 0x00
++ PC-Flags : 0xc000
+ Object Type : Qspec
++ V:Id:M_Seq:O_Cmb : 0:0:0:3
+++ QSPEC object : QoS Desired
+++ Object type : Tmod1, length:4
+++ QSPEC object : QoS Available
+++ Object type : Tmod1, length:4
+++ QSPEC object : Minimum QoS
+++ Object type : Tmod1, length:4
+++ Object type : ExcessTreatment, length:1
+ Q:T:R:S-Flag : 1:0:0:0
QNE: outgoing msg -->
+ SII_Handle :
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : UPSTREAM
+ msg length : 12 bytes
+ QoS Message Type : NOTIFY
+ Object Type : InfoSpec
++ InfoSpec : 0x03 0x01 0x00 0x00
+ Q:T:R:S-Flag : 0:0:0:0
StateTimer started : 100s
RefrTimer started : 30s
transition to : ST_INST
(info) FSM: Change state to 2
----------------------------------------
handleMessageStatus (GIST was unable to establish routing state with the peer. This is caused, when GIST receives no RESPONSE to an initial QUERY (after a configured number of retransmissions). All pending messages could not be transmitted and have been dropped.)
SID: ffffffff ffffffff ffffffff ffffffff
fsm : 0x80bf8f0
QNE: --> incoming msg
+ SII_Handle : 100.0.5.1
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : DOWNSTREAM
+ msg length : 120 bytes
+ QoS Message Type : RESERVE
+ Object Type : RII
++ RII : 0x71dc98c2
+ Object Type : RSN
++ RSN : 2
++ EpochID : 0x481ef28c
+ Object Type : PacketClassifier
++ PacketClassifier : 0x00
++ PC-Flags : 0xc000
+ Object Type : Qspec
++ V:Id:M_Seq:O_Cmb : 0:0:0:3
+++ QSPEC object : QoS Desired
+++ Object type : Tmod1, length:4
+++ QSPEC object : QoS Available
+++ Object type : Tmod1, length:4
+++ QSPEC object : Minimum QoS
+++ Object type : Tmod1, length:4
+++ Object type : ExcessTreatment, length:1
+ Q:T:R:S-Flag : 1:0:0:0
triggerEvent : ST_INST::EV_RX_RESERVE
(info) FSM: Event 1 while state is 2
QoSFsm::inst__rx_reserve()
++ V:Id:M_Seq:O_Cmb : 0:0:0:3
+++ QSPEC object : QoS Desired
+++ Object type : Tmod1, length:4
+++ QSPEC object : QoS Available
+++ Object type : Tmod1, length:4
+++ QSPEC object : Minimum QoS
+++ Object type : Tmod1, length:4
+++ Object type : ExcessTreatment, length:1
QNE: outgoing msg -->
+ SII_Handle :
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : DOWNSTREAM
+ msg length : 120 bytes
+ QoS Message Type : RESERVE
+ Object Type : RII
++ RII : 0x71dc98c2
+ Object Type : RSN
++ RSN : 2
++ EpochID : 0x481ef28c
+ Object Type : PacketClassifier
++ PacketClassifier : 0x00
++ PC-Flags : 0xc000
+ Object Type : Qspec
++ V:Id:M_Seq:O_Cmb : 0:0:0:3
+++ QSPEC object : QoS Desired
+++ Object type : Tmod1, length:4
+++ QSPEC object : QoS Available
+++ Object type : Tmod1, length:4
+++ QSPEC object : Minimum QoS
+++ Object type : Tmod1, length:4
+++ Object type : 0
(info) installRoutingState : 1
(info) timeout : 2000
(info) ip_ttl : 10
(info) gist_hop_count : 50
(info) FSM: Event 1 while state is 4
(warn) *** Dmode message from : 100.0.7.2:4> ***
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 1
Message length = 180
(info) Type = RESPONSE
(info) S flag = 0
(info) R flag = 1
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Upstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.6.2
(info) RouteStateValidityTime = 100000
(info) GistQueryCookie: length = 36
(info) GistResponderCookie: length = 36
(info) GistStackProposal: length = 12; profileCount = 1
(info) Profile 1: (info) TCP (info)
(info) GistStackConfigurationData: length = 20
(warn) *** This is a known flow ***
(info) FSM: Event 6 while state is 2
(info)
*** API-Call MessageStatus() ***
(info) nslpid : 1
(info) nslp_message_handle :
(info) reliab. : 0
(info) security : 0
(info) error_type : 0x02
(crit)
*** GistException: Legacy NAT detected ***
(info) FSM: Change state to 0
(info) FSM: Event 4 while state is 0
(err) Event (4) not specified for current state (0)
(info) GIST message:
Version = 1
Hop Count = 49
NSLP ID = 1
Message length = 172
(info) Type = DATA
(info) S flag = 1
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNslpData: length = 124; payloadLength = 120
(warn) *** This is a known flow ***
(info) FSM: Event 11 while state is 4
(info)
*** API-Call RecvMessage() ***
(info) nslp_data :
(info) nd_size : 120
(info) nslp_id : 0x0001
(info) sid : (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliable : 1
(info) security : 0
(info) explicitlyRouted : 0
(info) noGistState : 0
(info) ip_ttl : 0
(info) ip_distance : 0
(warn) *** Received API Call ***
(info)
*** API-Call SendMessage() ***
(info) nslp_data :
(info) nd_size : 120
(info) nslp_id : 0x0001
(info) sid : (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliab. : 1
(info) security : 0
(info) local : 0
(info) installRoutingState : 1
(info) timeout : 2000
(info) ip_ttl : 10
(info) gist_hop_count : 50
(info) FSM: Event 1 while state is 0
(info) *** Dmode message > query (with rao) ***
(info) FSM: Change state to 2
(warn) *** Dmode message from : 100.0.7.2:4> ***
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 1
Message length = 180
(info) Type = RESPONSE
(info) S flag = 0
(info) R flag = 1
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Upstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.6.2
(info) RouteStateValidityTime = 100000
(info) GistQueryCookie: length = 36
(info) GistResponderCookie: length = 36
(info) GistStackProposal: length = 12; profileCount = 1
(info) Profile 1: (info) TCP (info)
(info) GistStackConfigurationData: length = 20
(warn) *** This is a known flow ***
(info) FSM: Event 6 while state is 2
(info)
*** API-Call MessageStatus() ***
(info) nslpid : 1
(info) nslp_message_handle :
(info) reliab. : 0
(info) security : 0
(info) error_type : 0x02
(crit)
*** GistException: Legacy NAT detected ***
(info) FSM: Change state to 0
(info) FSM: Event 4 while state is 0
(err) Event (4) not specified for current state (0)
(info) GIST message:
Version = 1
Hop Count = 49
NSLP ID = 1
Message length = 68
(info) Type = DATA
(info) S flag = 1
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) ffffffff ffffffff ffffffff ffffffff
(info) GistNslpData: length = 20; payloadLength = 16
(warn) *** This is a known flow ***
(info) FSM: Event 11 while state is 4
(info)
*** API-Call RecvMessage() ***
(info) nslp_data :
(info) nd_size : 16
(info) nslp_id : 0x0001
(info) sid : (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info) 0xff (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliable : 1
(info) security : 0
(info) explicitlyRouted : 0
(info) noGistState : 0
(info) ip_ttl : 0
(info) ip_distance : 0
(warn) *** Received RAO packet (value = 00 00) ***
(warn) *** Dmode message from : 100.0.5.1:4> ***
(info) GIST message:
Version = 1
Hop Count = 49
NSLP ID = 3
Message length = 112
(info) Type = QUERY
(info) S flag = 1
(info) R flag = 1
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) 00000000 00000000 00000000 00000000
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.5.1
(info) RouteStateValidityTime = 100000
(info) GistQueryCookie: length = 36
(warn) *** This is a UNknown flow ***
(info) FSM: Change state to 0
(info) FSM: Event 5 while state is 0
(info) FSM: Change state to 1
(warn) *** Dmode message from : 100.0.5.1:4> ***
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 3
Message length = 112
(info) Type = CONFIRM
(info) S flag = 1
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) 00000000 00000000 00000000 00000000
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.5.1
(info) RouteStateValidityTime = 100000
(info) GistResponderCookie: length = 36
(warn) *** This is a known flow ***
(info) FSM: Event 8 while state is 1
(info) FSM: Change state to 4
(warn) *** Dmode message from : 100.0.5.1:4> ***
(info) GIST message:
Version = 1
Hop Count = 49
NSLP ID = 3
Message length = 160
(info) Type = DATA
(info) S flag = 0
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) 00000000 00000000 00000000 00000000
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.5.1
(info) RouteStateValidityTime = 100000
(info) GistNslpData: length = 84; payloadLength = 80
(warn) *** This is a known flow ***
(info) FSM: Event 10 while state is 4
(info)
*** API-Call RecvMessage() ***
(info) nslp_data : P
(info) nd_size : 80
(info) nslp_id : 0x0003
(info) sid : (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliable : 0
(info) security : 0
(info) explicitlyRouted : 0
(info) noGistState : 0
(info) ip_ttl : 0
(info) ip_distance : 0
(warn) *** Received API Call ***
(info)
*** API-Call SendMessage() ***
(info) nslp_data : h
(info) nd_size : 104
(info) nslp_id : 0x0003
(info) sid : (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliab. : 0
(info) security : 0
(info) local : 0
(info) installRoutingState : 1
(info) timeout : 2000
(info) ip_ttl : 10
(info) gist_hop_count : 50
(warn) *** Starting new Downstream FSM ***
(info) FSM: Change state to 0
(info) FSM: Event 0 while state is 0
(info) *** Dmode message > query (with rao) ***
(info) FSM: Change state to 3
(warn) *** Dmode message from : 100.0.7.2:4> ***
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 3
Message length = 148
(info) Type = RESPONSE
(info) S flag = 0
(info) R flag = 1
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Upstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) 00000000 00000000 00000000 00000000
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.6.2
(info) RouteStateValidityTime = 100000
(info) GistQueryCookie: length = 36
(info) GistResponderCookie: length = 36
(warn) *** This is a known flow ***
(info) FSM: Event 6 while state is 3
(info)
*** API-Call MessageStatus() ***
(info) nslpid : 3
(info) nslp_message_handle :
(info) reliab. : 0
(info) security : 0
(info) error_type : 0x02
(crit)
*** GistException: Legacy NAT detected ***
(info) FSM: Change state to 0
(info) FSM: Event 4 while state is 0
(err) Event (4) not specified for current state (0)
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 0
Message length = 16
(info) Type = MAHELLO
(info) S flag = 1
(info) R flag = 1
(info) E flag = 0
(info) GistHelloId: id = 5
(warn) MA-Hello received, sending MA-Hello back
(warn) *** Dmode message from : 100.0.5.1:4> ***
(info) GIST message:
Version = 1
Hop Count = 49
NSLP ID = 3
Message length = 160
(info) Type = DATA
(info) S flag = 0
(info) R flag = 0
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Downstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) 00000000 00000000 00000000 00000000
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.5.1
(info) RouteStateValidityTime = 100000
(info) GistNslpData: length = 84; payloadLength = 80
(warn) *** This is a known flow ***
(info) FSM: Event 10 while state is 4
(info)
*** API-Call RecvMessage() ***
(info) nslp_data : P
(info) nd_size : 80
(info) nslp_id : 0x0003
(info) sid : (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliable : 0
(info) security : 0
(info) explicitlyRouted : 0
(info) noGistState : 0
(info) ip_ttl : 0
(info) ip_distance : 0
(warn) *** Received API Call ***
(info)
*** API-Call SendMessage() ***
(info) nslp_data : h
(info) nd_size : 104
(info) nslp_id : 0x0003
(info) sid : (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info) 0x00 (info)
(info) mri->msgRoutingMethod : 0x0000
(info) mri->s : 0
(info) mri->f : 0
(info) mri->t : 0
(info) mri->p : 0
(info) mri->ipVersion : 0x04
(info) mri->reserved : 0x00
(info) mri->d : 0
(info) mri->b : 0
(info) mri->a : 0
(info) mri->srcAddr : 100.0.0.1
(info) mri->destAddr : 100.0.9.2
(info) mri->srcPrefix : 0x20
(info) mri->destPrefix : 0x20
(info) mri->protocol : 0x00
(info) mri->diffserv_field : 0x00
(info) reliab. : 0
(info) security : 0
(info) local : 0
(info) installRoutingState : 1
(info) timeout : 2000
(info) ip_ttl : 10
(info) gist_hop_count : 50
(info) FSM: Event 0 while state is 0
(info) *** Dmode message > query (with rao) ***
(info) FSM: Change state to 3
(warn) *** Dmode message from : 100.0.7.2:4> ***
(info) GIST message:
Version = 1
Hop Count = 4
NSLP ID = 3
Message length = 148
(info) Type = RESPONSE
(inf(err) Setting Debug Level to 2
(err) Reading IP Routing Table (overwrites nsis.conf IP addresses)
(err) Read Route from IP Routing Table: To:100.0.1.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.0.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.3.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.2.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.5.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.4.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.7.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.6.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.9.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.8.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.11.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.10.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
NSIS daemon v0.5.0 starting ...
My PID id = 506
Starting pingServer (pid: 507) ...
**** Ping: handleMessageStatus was called ****
Handle: 0 0 30 45 50 64 44 170 167 191 12 171 167 191 168 169
**** Ping: handleMessageStatus was called ****
Handle: 0 0 30 45 50 64 44 170 167 191 12 171 167 191 168 169
[49;39m** Process 507 got signal 2: SIGINT
: ExcessTreatment, length:1
+ Q:T:R:S-Flag : 1:0:0:0
StateTimer restarted: 100s
transition to : ST_INST
(info) FSM: Change state to 2
----------------------------------------
handleMessageStatus (GIST was unable to establish routing state with the peer. This is caused, when GIST receives no RESPONSE to an initial QUERY (after a configured number of retransmissions). All pending messages could not be transmitted and have been dropped.)
SID: ffffffff ffffffff ffffffff ffffffff
fsm : 0x80bf8f0
QNE: --> incoming msg
+ SII_Handle : 100.0.5.1
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : DOWNSTREAM
+ msg length : 16 bytes
+ QoS Message Type : RESERVE
+ Object Type : RSN
++ RSN : 0
++ EpochID : 0x481ef2d0
+ Q:T:R:S-Flag : 0:0:0:0
triggerEvent : ST_INST::EV_RX_RESERVE
(info) FSM: Event 1 while state is 2
QoSFsm::inst__rx_reserve()
StateTimer restarted: 100s
transition to : ST_INST
(info) FSM: Change state to 2
----------------------------------------
[49;39m** Process 508 got signal 2: QoSFsm: refresh_timeout()
triggerEvent : ST_INST::EV_TIMEOUT_REFRESH
(info) FSM: Event 7 while state is 2
QoSFsm::inst__timeout_refresh()
QNE: outgoing msg -->
+ SII_Handle :
+ srcAddr : 100.0.0.1
+ destAddr : 100.0.9.2
+ direction : DOWNSTREAM
+ msg length : 16 bytes
+ QoS Message Type : RESERVE
+ Object Type : RSN
++ RSN : 0
++ EpochID : 0x481ef40f
+ Q:T:R:S-Flag : 0:0:0:0
RefrTimer restarted : 30s
transition to : ST_INST
(info) FSM: Change state to 2
----------------------------------------
SIGINT
(err) Setting Debug Level to 2
(err) Reading IP Routing Table (overwrites nsis.conf IP addresses)
(err) Read Route from IP Routing Table: To:100.0.1.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.0.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.3.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.2.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.5.0/255.255.255.0 with local IP: 100.0.5.2
(err) Read Route from IP Routing Table: To:100.0.4.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.7.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.6.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.9.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.8.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.11.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IP Routing Table: To:100.0.10.0/255.255.255.0 with local IP: 100.0.7.1
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
(err) Read Route from IPv6 Routing Table: To:::/:: with local IP: (null)
NSIS daemon v0.5.0 starting ...
My PID id = 506
Starting diagServer (pid: 509) ...
(err) Diag: connected to GIST server
[49;39m** Process 509 got signal 2: SIGINT
o) S flag = 0
(info) R flag = 1
(info) E flag = 0
(info) GistMriPathCoupled: length = 56
(info) Direction = Upstream
(info) Source IP = 100.0.0.1
(info) Dest IP = 100.0.9.2
(info) GistSessionId: length = 20
(info) 00000000 00000000 00000000 00000000
(info) GistNetworkLayerInfo: length = 28
(info) IntAddr = 100.0.6.2
(info) RouteStateValidityTime = 100000
(info) GistQueryCookie: length = 36
(info) GistResponderCookie: length = 36
(warn) *** This is a known flow ***
(info) FSM: Event 6 while state is 3
(info)
*** API-Call MessageStatus() ***
(info) nslpid : 3
(info) nslp_message_handle :
(info) reliab. : 0
(info) security : 0
(info) error_type : 0x02
(crit)
*** GistException: Legacy NAT detected ***
(info) FSM: Change state to 0
(info) FSM: Event 4 while state is 0
(err) Event (4) not specified for current state (0)
[49;39m** Process 506 got signal 2: SIGINT