RSPlib Discussions

Text archives Help


Re: [RSPlib-Discusssion] fail-over problems


Chronological Thread 
  • From: "Marjan Bozinovski" <marjanb AT cpk.auc.dk>
  • To: "Thomas Dreibholz" <dreibh AT exp-math.uni-essen.de>, <rsplib-discussion AT sctp.de>
  • Subject: Re: [RSPlib-Discusssion] fail-over problems
  • Date: Fri Sep 13 11:02:01 2002
  • List-id: RSPlib Discussions <rsplib-discussion.sctp.de>

Hi,

In addition to my last mail in which I gave a piece of the code, which seems
problematic, here I am sending the corresponding logfile as Thomas
suggested.
Just as reminder, I will repeat the code:


----------------------------------------------------------------------------
----

1. First I start with:

beginLogging();
initAll();
connectToPoolElement();
//as a result, a global DataSocket is created which is nonzero. This is
the same as in examplepu.

2. I send some data 'buffer'.

sendToPoolElement(buffer);
//this is successfully transmitted as I can see with Ethereal. Also, the
SCTP receiver responds back with SACK + echoed 'buffer'.

3. I start listening to incoming messages:

FD_ZERO(&readfdset);
FD_SET(DataSocket, &readfdset);

timeout.tv_sec = 2;
timeout.tv_usec = 0;

result = rspSelect(DataSocket + 1, &readfdset, NULL, NULL, &timeout);
//exactly as in the examplepu;

After this I always get result=0, there are no incoming messages at
DataSocket and the program exits.

----------------------------------------------------------------------------
----


Hope this helps.

/Marjan

14-Sep-2002 09:56:59.7037: loglevel.c:122 beginLogging() - Logging started,
log level is 9.
14-Sep-2002 09:56:59.7153: asapinstance.c:246 asapIO() - Request trial #1 -
sending request...
14-Sep-2002 09:56:59.7154: serverhunter.c:438 serverHunterRun() - Starting
Server Hunt...
14-Sep-2002 09:56:59.7154: serverhunter.c:458 serverHunterRun() - Server Hunt
Trial #1 with timeout 2000000 [µs]
14-Sep-2002 09:56:59.7156: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <# Unicast nameserver
addresses>
14-Sep-2002 09:56:59.7156: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <# nameserver 127.0.0.1:9999
tcp>
14-Sep-2002 09:56:59.7156: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <# nameserver 127.0.0.1:9999
sctp>
14-Sep-2002 09:56:59.7156: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <# nameserver 10.0.0.1:9999
sctp>
14-Sep-2002 09:56:59.7157: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <# Multicast nameserver
addresses>
14-Sep-2002 09:56:59.7157: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <nameserver 239.0.0.1:9999
multicast permanent>
14-Sep-2002 09:56:59.7158: serverinfolist.c:271
serverInfoListGetStaticEntries() - nameserver <239.0.0.1:9999> <multicast>
<permanent>
14-Sep-2002 09:56:59.7159: serverinfolist.c:182 serverInfoListAddServerInfo()
- Added Multicast ServerInfo already in list:
ServerID $00000000
Flags = Permanent Static Multicast
Address = { 239.0.0.1 } 9999/UDP
14-Sep-2002 09:56:59.7160: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <# Unicast nameserver
addresses>
14-Sep-2002 09:56:59.7160: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <nameserver 192.168.2.1:9999
sctp>
14-Sep-2002 09:56:59.7160: serverinfolist.c:276
serverInfoListGetStaticEntries() - nameserver <192.168.2.1:9999> <sctp>
14-Sep-2002 09:56:59.7161: serverinfolist.c:202 serverInfoListAddServerInfo()
- Added Unicast ServerInfo already to list:
ServerID $00000000
Flags = Static Unicast Unknown
Address = { 192.168.2.1 } 9999/SCTP
14-Sep-2002 09:56:59.7161: serverinfolist.c:262
serverInfoListGetStaticEntries() - Read string <nameserver 192.168.1.1:9999
sctp>
14-Sep-2002 09:56:59.7162: serverinfolist.c:276
serverInfoListGetStaticEntries() - nameserver <192.168.1.1:9999> <sctp>
14-Sep-2002 09:56:59.7162: netutilities.c:422 addresscmp() - Comparing
192.168.2.1:9999 and 192.168.1.1:9999
14-Sep-2002 09:56:59.7163: netutilities.c:481 addresscmp() - Result:
greater-than
14-Sep-2002 09:56:59.7163: serverinfolist.c:202 serverInfoListAddServerInfo()
- Added Unicast ServerInfo already to list:
ServerID $00000000
Flags = Static Unicast Unknown
Address = { 192.168.1.1 } 9999/SCTP
14-Sep-2002 09:56:59.7164: serverinfolist.c:367
serverInfoListGetStaticEntries() - ServerInfoList initialized from
configuration file /usr/local/rsplib-0.1.0/rsplib/asap.conf:
Unicast Servers:
ServerID $00000000
Flags = Static Unicast Unknown
Address = { 192.168.2.1 } 9999/SCTP
ServerID $00000000
Flags = Static Unicast Unknown
Address = { 192.168.1.1 } 9999/SCTP
Multicast Servers:
ServerID $00000000
Flags = Permanent Static Multicast
Address = { 239.0.0.1 } 9999/UDP
14-Sep-2002 09:56:59.7164: serverhunter.c:114
serverHunterServerHuntMulticast() - Sending ServerHunt via multicast to {
239.0.0.1 } 9999/UDP...
14-Sep-2002 09:56:59.7165: serverhunter.c:132
serverHunterServerHuntMulticast() - Sending via IPv4 multicast socket 1023
14-Sep-2002 09:56:59.7168: serverhunter.c:140
serverHunterServerHuntMulticast() - Error: Sending ServerHunt via IPv4
multicast failed: No route to host
14-Sep-2002 09:56:59.7169: serverhunter.c:181
serverHunterServerHuntUnicastConnect() - Connecting socket 1021 for
ServerHunt via unicast to { 192.168.2.1 } 9999/SCTP...
14-Sep-2002 09:56:59.7176: serverhunter.c:181
serverHunterServerHuntUnicastConnect() - Connecting socket 1020 for
ServerHunt via unicast to { 192.168.1.1 } 9999/SCTP...
14-Sep-2002 09:56:59.7180: serverhunter.c:513 serverHunterRun() - select()
with timeout 1997455
14-Sep-2002 09:56:59.7195: serverhunter.c:519 serverHunterRun() - select()
result=1
14-Sep-2002 09:56:59.7196: serverhunter.c:224
serverHunterServerHuntUnicastWrite() - Sending ServerHunt via unicast to {
192.168.1.1 } 9999/SCTP, socket 1020...
14-Sep-2002 09:56:59.7196: netutilities.c:800 sendtoplus() - sendmsg(4
bytes)...
14-Sep-2002 09:56:59.7197: netutilities.c:834 sendtoplus() - sendmsg(1020)
result=4; Undefined error: 0
14-Sep-2002 09:56:59.7197: asapmessage.c:182 asapMessageSend() - Successfully
sent ASAP message
PPID=$faeeb5d1, ASAP Type = $0b, AssocID=0
14-Sep-2002 09:56:59.7198: serverhunter.c:513 serverHunterRun() - select()
with timeout 1995647
14-Sep-2002 09:56:59.7395: serverhunter.c:519 serverHunterRun() - select()
result=1
14-Sep-2002 09:56:59.7396: serverhunter.c:606 serverHunterRun() - Read event
for unicast socket 1020
14-Sep-2002 09:56:59.7396: serverhunter.c:263
serverHunterServerHuntHandleResponse() - Reading response from socket 1020...
14-Sep-2002 09:56:59.7397: asapparser.c:1159 asapPacket2Message() - Scanning
message, size=4...
14-Sep-2002 09:56:59.7397: asapparser.c:1127 scanMessage() - Scanning
ServerHuntResponse message...
14-Sep-2002 09:56:59.7398: asapparser.c:1165 asapPacket2Message() - Message
successfully scanned!
14-Sep-2002 09:56:59.7399: serverhunter.c:287
serverHunterServerHuntHandleResponse() - ServerHuntResponse from
192.168.1.1:9999 (answering unicast request to { 192.168.1.1 } 9999/SCTP)
received
14-Sep-2002 09:56:59.7399: serverhunter.c:353
serverHunterServerHuntHandleResponse() - Validated ServerInfo:
ServerID $00000000
Flags = Static Unicast
Address = { 192.168.1.1 } 9999/SCTP
14-Sep-2002 09:56:59.7400: serverhunter.c:614 serverHunterRun() - Valid
server found on socket 1020
ServerID $00000000
Flags = Static Unicast
Address = { 192.168.1.1 } 9999/SCTP
14-Sep-2002 09:56:59.7402: asapinstance.c:148 asapConnectToNameServer() -
Connection to name server server successfully established
14-Sep-2002 09:56:59.7402: netutilities.c:800 sendtoplus() - sendmsg(16
bytes)...
14-Sep-2002 09:56:59.7403: netutilities.c:834 sendtoplus() - sendmsg(1020)
result=16; Undefined error: 0
14-Sep-2002 09:56:59.7404: asapmessage.c:182 asapMessageSend() - Successfully
sent ASAP message
PPID=$faeeb5d1, ASAP Type = $05, AssocID=0
14-Sep-2002 09:56:59.7404: asapinstance.c:252 asapIO() - Request trial #1 -
waiting for response...
14-Sep-2002 09:56:59.7405: netutilities.c:880 recvfromplus() - recvmsg(1020,
4 bytes)...
14-Sep-2002 09:56:59.7405: netutilities.c:888 recvfromplus() - recvmsg(1020)
would block, waiting with timeout 4444444 [µs]...
14-Sep-2002 09:56:59.7693: netutilities.c:900 recvfromplus() - retrying
recvmsg(1020, 4 bytes)...
14-Sep-2002 09:56:59.7693: netutilities.c:915 recvfromplus() - recvmsg(1020)
result=4 data=4/4 control=0; Undefined error: 0
14-Sep-2002 09:56:59.7694: asapmessage.c:154 asapMessagePeekSize() - Incoming
ASAP message: type=$06 pad=$00 length=168
14-Sep-2002 09:56:59.7694: netutilities.c:880 recvfromplus() - recvmsg(1020,
168 bytes)...
14-Sep-2002 09:56:59.7695: netutilities.c:915 recvfromplus() - recvmsg(1020)
result=168 data=168/168 control=0; Undefined error: 0
14-Sep-2002 09:56:59.7695: asapparser.c:1159 asapPacket2Message() - Scanning
message, size=168...
14-Sep-2002 09:56:59.7696: asapparser.c:1055 scanMessage() - Scanning
NameResolutionResponse message...
14-Sep-2002 09:56:59.7696: asapparser.c:65 getNextTLV() - TLV: Type $0007,
length 9 at position 8
14-Sep-2002 09:56:59.7696: asapparser.c:769 scanPoolHandleParameter() -
Scanned pool handle "tulip", length=5.
14-Sep-2002 09:56:59.7697: asapparser.c:263 checkFinishTLV() - Skipping data:
p=13 -> p=16.
14-Sep-2002 09:56:59.7697: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 20
14-Sep-2002 09:56:59.7698: asapparser.c:571 scanPolicyParameter() - Scanned
policy RR.
14-Sep-2002 09:56:59.7698: asapparser.c:65 getNextTLV() - TLV: Type $0008,
length 48 at position 28
14-Sep-2002 09:56:59.7698: asapparser.c:65 getNextTLV() - TLV: Type $0003,
length 24 at position 44
14-Sep-2002 09:56:59.7699: asapparser.c:65 getNextTLV() - TLV: Type $0001,
length 8 at position 52
14-Sep-2002 09:56:59.7700: asapparser.c:449 scanUserTransportParameter() -
Scanned address 1.
14-Sep-2002 09:56:59.7700: asapparser.c:65 getNextTLV() - TLV: Type $0001,
length 8 at position 60
14-Sep-2002 09:56:59.7700: asapparser.c:449 scanUserTransportParameter() -
Scanned address 2.
14-Sep-2002 09:56:59.7701: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 68
14-Sep-2002 09:56:59.7701: asapparser.c:129 handleUnknownTLV() - Silently
stop processing for type $06 at position 68
14-Sep-2002 09:56:59.7701: netutilities.c:422 addresscmp() - Comparing
192.168.1.2:49452 and 192.168.2.2:49452
14-Sep-2002 09:56:59.7702: netutilities.c:481 addresscmp() - Result: less-than
14-Sep-2002 09:56:59.7702: poolelement.c:208 poolElementAddTransportAddress()
- Adding address { 192.168.1.2, 192.168.2.2 } 49452/SCTP to pool element #0.
14-Sep-2002 09:56:59.7702: asapparser.c:473 scanUserTransportParameter() -
New TransportAddress: { 192.168.1.2, 192.168.2.2 } 49452/SCTP.
14-Sep-2002 09:56:59.7703: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 68
14-Sep-2002 09:56:59.7704: asapparser.c:129 handleUnknownTLV() - Silently
stop processing for type $06 at position 68
14-Sep-2002 09:56:59.7704: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 68
14-Sep-2002 09:56:59.7704: asapparser.c:571 scanPolicyParameter() - Scanned
policy RR.
14-Sep-2002 09:56:59.7705: asapparser.c:65 getNextTLV() - TLV: Type $0008,
length 48 at position 76
14-Sep-2002 09:56:59.7705: asapparser.c:65 getNextTLV() - TLV: Type $0003,
length 24 at position 92
14-Sep-2002 09:56:59.7705: asapparser.c:65 getNextTLV() - TLV: Type $0001,
length 8 at position 100
14-Sep-2002 09:56:59.7706: asapparser.c:449 scanUserTransportParameter() -
Scanned address 1.
14-Sep-2002 09:56:59.7706: asapparser.c:65 getNextTLV() - TLV: Type $0001,
length 8 at position 108
14-Sep-2002 09:56:59.7706: asapparser.c:449 scanUserTransportParameter() -
Scanned address 2.
14-Sep-2002 09:56:59.7707: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 116
14-Sep-2002 09:56:59.7707: asapparser.c:129 handleUnknownTLV() - Silently
stop processing for type $06 at position 116
14-Sep-2002 09:56:59.7707: netutilities.c:422 addresscmp() - Comparing
192.168.1.4:17884 and 192.168.2.4:17884
14-Sep-2002 09:56:59.7708: netutilities.c:481 addresscmp() - Result: less-than
14-Sep-2002 09:56:59.7708: poolelement.c:208 poolElementAddTransportAddress()
- Adding address { 192.168.1.4, 192.168.2.4 } 17884/SCTP to pool element #0.
14-Sep-2002 09:56:59.7709: asapparser.c:473 scanUserTransportParameter() -
New TransportAddress: { 192.168.1.4, 192.168.2.4 } 17884/SCTP.
14-Sep-2002 09:56:59.7709: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 116
14-Sep-2002 09:56:59.7709: asapparser.c:129 handleUnknownTLV() - Silently
stop processing for type $06 at position 116
14-Sep-2002 09:56:59.7710: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 116
14-Sep-2002 09:56:59.7710: asapparser.c:571 scanPolicyParameter() - Scanned
policy RR.
14-Sep-2002 09:56:59.7710: asapparser.c:65 getNextTLV() - TLV: Type $0008,
length 48 at position 124
14-Sep-2002 09:56:59.7711: asapparser.c:65 getNextTLV() - TLV: Type $0003,
length 24 at position 140
14-Sep-2002 09:56:59.7711: asapparser.c:65 getNextTLV() - TLV: Type $0001,
length 8 at position 148
14-Sep-2002 09:56:59.7711: asapparser.c:449 scanUserTransportParameter() -
Scanned address 1.
14-Sep-2002 09:56:59.7712: asapparser.c:65 getNextTLV() - TLV: Type $0001,
length 8 at position 156
14-Sep-2002 09:56:59.7712: asapparser.c:449 scanUserTransportParameter() -
Scanned address 2.
14-Sep-2002 09:56:59.7712: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 164
14-Sep-2002 09:56:59.7713: asapparser.c:129 handleUnknownTLV() - Silently
stop processing for type $06 at position 164
14-Sep-2002 09:56:59.7713: netutilities.c:422 addresscmp() - Comparing
192.168.1.2:32426 and 192.168.2.2:32426
14-Sep-2002 09:56:59.7714: netutilities.c:481 addresscmp() - Result: less-than
14-Sep-2002 09:56:59.7714: poolelement.c:208 poolElementAddTransportAddress()
- Adding address { 192.168.1.2, 192.168.2.2 } 32426/SCTP to pool element #0.
14-Sep-2002 09:56:59.7714: asapparser.c:473 scanUserTransportParameter() -
New TransportAddress: { 192.168.1.2, 192.168.2.2 } 32426/SCTP.
14-Sep-2002 09:56:59.7715: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 164
14-Sep-2002 09:56:59.7715: asapparser.c:129 handleUnknownTLV() - Silently
stop processing for type $06 at position 164
14-Sep-2002 09:56:59.7716: asapparser.c:65 getNextTLV() - TLV: Type $0006,
length 8 at position 164
14-Sep-2002 09:56:59.7716: asapparser.c:571 scanPolicyParameter() - Scanned
policy RR.
14-Sep-2002 09:56:59.7716: asapparser.c:1165 asapPacket2Message() - Message
successfully scanned!
14-Sep-2002 09:56:59.7717: asapmessage.c:249 asapMessageReceive() -
Successfully received ASAP message
PPID=$00000000 AssocID=0 StreamID=0, ASAP Type = $06
14-Sep-2002 09:56:59.7717: asapcache.c:59 asapCacheUpdatePoolElement() -
Cache Update for Pool Element #0 of pool "tulip":
Identifier = $89560dc2
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.2, 192.168.2.2 } 49452/SCTP
14-Sep-2002 09:56:59.7718: asapcache.c:76 asapCacheUpdatePoolElement() - New
pool, to be created first.
14-Sep-2002 09:56:59.7718: poolnamespace.c:138 poolNamespaceAddPool() -
Adding new pool "tulip".
14-Sep-2002 09:56:59.7719: asapcache.c:87 asapCacheUpdatePoolElement() -
Adding pool element.
14-Sep-2002 09:56:59.7719: poolnamespace.c:190 poolNamespaceAddPoolElement()
- Adding new pool element #1.
14-Sep-2002 09:56:59.7719: asapcache.c:104 asapCacheUpdatePoolElement() -
*************** Name Cache Dump ***************

Pool "tulip":
Policy = Round-Robin
UserCounter = 0
Pool Element #1 of pool "tulip":
Identifier = $89560dc2
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.2, 192.168.2.2 } 49452/SCTP
***********************************************
14-Sep-2002 09:56:59.7720: asapcache.c:59 asapCacheUpdatePoolElement() -
Cache Update for Pool Element #0 of pool "tulip":
Identifier = $b4b7d1ce
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.4, 192.168.2.4 } 17884/SCTP
14-Sep-2002 09:56:59.7721: asapcache.c:87 asapCacheUpdatePoolElement() -
Adding pool element.
14-Sep-2002 09:56:59.7721: poolnamespace.c:190 poolNamespaceAddPoolElement()
- Adding new pool element #2.
14-Sep-2002 09:56:59.7722: asapcache.c:104 asapCacheUpdatePoolElement() -
*************** Name Cache Dump ***************

Pool "tulip":
Policy = Round-Robin
UserCounter = 0
Pool Element #1 of pool "tulip":
Identifier = $89560dc2
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.2, 192.168.2.2 } 49452/SCTP
Pool Element #2 of pool "tulip":
Identifier = $b4b7d1ce
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.4, 192.168.2.4 } 17884/SCTP
***********************************************
14-Sep-2002 09:56:59.7723: asapcache.c:59 asapCacheUpdatePoolElement() -
Cache Update for Pool Element #0 of pool "tulip":
Identifier = $0cd5f312
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.2, 192.168.2.2 } 32426/SCTP
14-Sep-2002 09:56:59.7724: asapcache.c:87 asapCacheUpdatePoolElement() -
Adding pool element.
14-Sep-2002 09:56:59.7724: poolnamespace.c:190 poolNamespaceAddPoolElement()
- Adding new pool element #3.
14-Sep-2002 09:56:59.7724: asapcache.c:104 asapCacheUpdatePoolElement() -
*************** Name Cache Dump ***************

Pool "tulip":
Policy = Round-Robin
UserCounter = 0
Pool Element #1 of pool "tulip":
Identifier = $89560dc2
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.2, 192.168.2.2 } 49452/SCTP
Pool Element #2 of pool "tulip":
Identifier = $b4b7d1ce
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.4, 192.168.2.4 } 17884/SCTP
Pool Element #3 of pool "tulip":
Identifier = $0cd5f312
Home ENRP ID = $fcbd10b4
Reg. Lifetime = -1 [s]
Flags = none
UserCounter = 0
Policy = Round-Robin
{ 192.168.1.2, 192.168.2.2 } 32426/SCTP
***********************************************



Archive powered by MHonArc 2.6.19+.

Top of page