Hey Julio,
actually I am a bit clueless. Today I came back to Berlin, where I could test on Foneros MIPS devices (I suspected the endian type to be related to the problem but it also works very smoothly there. I tried with rv1146 on gw and client and with exactly the same parameter which you used. For me it looks like the handshake messages send by the client do not even end up at the bmxd running in the gw-node.
In rv1147 i fixed some more misdirected debug-messages send by the tunnel thread (but I doubt this was the reason for the problem) and I put another debug message in the tunnel thread which should log invalid tunnel messages to debug-level 0 and 3. Have a look also on what printed to debug-level 0 / logread. If this doesnt show anything useful and if you are able to compile the binaries on your own, you may uncomment lines 1179 and 1180 in posix/tunnel.c which should cause the tunnel thread to log all incoming message on its dedicated port.
If you send the output of ip a ip rule iptables -t nat -L -nv iptables -L -nv
I would also have a look on that.
Some more comments inline...
On Mittwoch 29 Oktober 2008, Julio Cesar Puigpinos wrote:
Yes, it does set up bat0.
The client should have set tun0 up after the handshake process (with -o 2000 -r 1 approximately 200 seconds after startup)
No, it didn't do it.
shit but makes sense, the client does only setit up after a succesfull handshake.
One message that I saw that sometimes appears, while using debug level 3 in both the client and the gw, was like this "ERROR: rcvd dbgl msg via fd 10, len 0, error Success".
ok thats no problem, not really an error. Have to fix that. This message always appears when "bmxd -c ..." terminates after pulling for example on-the-fly debug-messages from the main daemon.
Note that usually I would suspect a firewall issue but this shuld be ruled out as you said you are working with the same setup that worked with rv1069.
Right, it works well with rv1069. To test both revisions I just opkg remove, install and reboot each time.
Maybe the problem is related to something completely different. What about using http://downloads.open-mesh.net/batman/development/misc/batmand-exp_0.3-alpha... exchange current with rv1147/rv11 and dynamic with static to old reveisions can be found here: http://downloads.open-mesh.net/batman/development/misc/old/batmand-exp_0.3-a...
save it to /tmp/bmxd, dont forget chmod u+x /tmp/bmxd, maybe set a link from /usr/sbin/bmxd to /tmp/bmxd,... and just restart the daemon
you can use "bmxd -c --purge" to shortcut the duplicate address detection timeout which you may have noticed. With -o 2000 this can be annoying.
This are 3 lines from the output (both client and gw showed the same, except the IP order): 00:12:23.353439 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), length 35) 5.129.82.40.4306 > 5.252.88.99.4306: UDP, length
looks good. Just misses the reply :-(
Just for testing you may try with one-way-tunnel at the client side to see if the bat0 interface is set up correctly. This tunneltype is stateless and therefore needs no handshake messages between client and gw node. A simple bmxd -c --one-way-tunnel=4 assigned to the runnung client-daemon should at least show the bat0 interface (now configured with a 5.255.22.x IP instead of a 169.254.1.x ip) at the client. But for really using it you may need to modify your iptables settings.
Using that, debug level 3 shows: [ 200363] using default tunnel to GW 5.252.88.99 (gw_flags: 49, packet_count: 960, gw_product: 589824) [ 200364] add_default_route()... [ 200413] client_to_gw_tun() started... [ 200417] Tried to name tunnel to bat0 ... success [ 200421] select() indicated changed interface status! Going to check interfaces! [ 200423] researching min. MTU, so fare: 1500, current dev ath2, mtu: 1500 [ 200423] select() indicated changed interface status! Going to check interfaces!
And the bat0 shows up like this: bat0 Link encap:UNSPEC HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00 inet addr:5.129.82.40 P-t-P:5.129.82.40 Mask:255.255.255.255 UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1471 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:282 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:500 RX bytes:0 (0.0 B) TX bytes:20776 (20.2 KiB)
also looks good.
happy testing, axel
Altogether debuglevel3 should look like this (on the gw-client side): root@248:~# bmxd -cd3 [...]
In mi case, this doesn't happens. It just keeps requesting an IP, then gives up for some seconds and tries again and again.
On the gw-side it should look like: neumann@smart:/usr/src/batman-svn/trunk/batman-experimental$ sudo ./batmand -o 2000 -g 5000 -d 3 eth0:bmx [...] [ 2782] change route to 103.130.30.248 via 103.130.30.248 1 / 100 (prev. via 0.0.0.0 0) [ 181474] Gateway - assigned 169.254.1.245 to client: 103.130.30.248
In my case it didn't asign an IP to the client.
Saludos, Julio++++ Logs taked from the gw ++++ *Debug level 8 BatMan-eXp 0.3-alpha, IF ath2 5.252.88.99, LinkWindowSize 100, PathWindSize 100, OGI 2000ms, currSeqno 22461, UT 0:00:36:09, CPU 1/1000, IntTime 2169739 Neighbor outgoingIF bestNextHop brc (~rcvd knownSince lseq lvld rid nid ) [ viaIF RTQ RQ TQ].. 5.129.82.40 ath2 5.129.82.40 100 ( 99 0:00:10:00 44589 1 1 1 ) [ ath2 100 100 100]
Originator outgoingIF bestNextHop brc (~rcvd knownSince lseq lvld pws ogi cpu hop change ) alternativeNextHops brc ... 5.129.82.40 ath2 5.129.82.40 100 ( 99 0:00:10:00 44589 1 100 2014 5 1 1 ) 1 known Originator(s), averages: 100 ( 99 1 100 2014 5 1 1 )
*Debug level 3 BatMan-eXp 0.3-alpha (compatibility version 10) !
gateway class: 49 -> propagating: 4MBit/1024KBit
Startup parameters: bmxd -o 2000 -g 5000 -d 3 ath2
duplicate-address-detection timeout 100s, purge timeout 2525s, originator interval 2000ms, window size 100
searching min. MTU, so fare: 1500, current dev ath2, mtu: 1500
detected wireless interface ath2 (use ath2 /l to correct this assumption)
changing /proc/sys/net/ipv4/conf/ath2/send_redirects from 1 to 0
activated interface ath2 5.252.88.99/8 broadcast address 5.255.255.255
add rule from 0.0.0.0/0, table 64, prio 6500, if (null), type 1
add rule from 0.0.0.0/0, table 66, prio 6600, if (null), type 1
add rule from 0.0.0.0/0, table 65, prio 6699, if (null), type 1
add route to 127.0.0.0 via 0.0.0.0 src 0.0.0.0 dev lo, table 65, type 1
add route to 10.0.0.0 via 0.0.0.0 src 0.0.0.0 dev eth0, table 65, type 1
add route to 10.88.99.0 via 0.0.0.0 src 0.0.0.0 dev ath0, table 65, type 1
add route to 10.88.99.128 via 0.0.0.0 src 0.0.0.0 dev ath1, table 65, type 1
start_gw_service ()
[ 124] changing /proc/sys/net/ipv4/conf/all/send_redirects from 1 to 0
[ 132] changing /proc/sys/net/ipv4/conf/default/send_redirects from 1 to 0
[ 139] gw_listen() started...
[ 139] gw_listen(): my_tun_ip 169.254.0.0, my_tun_netmask: 255.255.252.0
[ 140] Tried to name tunnel to bat0 ... success
[ 140] select() indicated changed interface status! Going to check interfaces!
[ 141] researching min. MTU, so fare: 1500, current dev ath2, mtu: 1500
[ 142] select() indicated changed interface status! Going to check interfaces!
[ 142] researching min. MTU, so fare: 1500, current dev ath2, mtu: 1500
[ 143] select() indicated changed interface status! Going to check interfaces!
[ 144] researching min. MTU, so fare: 1500, current dev ath2, mtu: 1500
[ 165] select() indicated changed interface status! Going to check interfaces!
[ 166] researching min. MTU, so fare: 1500, current dev ath2, mtu: 1500
[ 15710] change route to 5.129.82.40 via 5.129.82.40 1 / 100 (prev. Via 0.0.0.0 0)
*bat0 shows up like this bat0 Link encap:UNSPEC HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00
inet addr:169.254.0.0 P-t-P:169.254.0.0 Mask:255.255.255.255 UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1471 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:500 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B)++++ Logs taked from the client ++++ *Debug level 8 BatMan-eXp 0.3-alpha, IF ath2 5.129.82.40, LinkWindowSize 100, PathWindSize 100, OGI 2000ms, currSeqno 7055, UT 0:00:13:13, CPU 3/1000, IntTime 793302 Neighbor outgoingIF bestNextHop brc (~rcvd knownSince lseq lvld rid nid ) [ viaIF RTQ RQ TQ].. 5.252.88.99 ath2 5.252.88.99 100 ( 99 0:00:06:56 21584 1 1 1 ) [ ath2 100 100 100]
Originator outgoingIF bestNextHop brc (~rcvd knownSince lseq lvld pws ogi cpu hop change ) alternativeNextHops brc ... 5.252.88.99 ath2 5.252.88.99 100 ( 99 0:00:06:56 21584 1 100 1998 1 1 1 ) 1 known Originator(s), averages: 100 ( 99 1 100 1998 1 1 1 )
*Debug level 3 [ 379294] change route to 5.252.88.99 via 5.252.88.99 1 / 100 (prev. via 0.0.0.0 0) [ 379299] found new gateway 5.252.88.99, announced by 5.252.88.99 -> class: 49 - 4MBit/1024KBit, new supported tunnel types TWT, OWT [ 380363] using default tunnel to GW 5.252.88.99 (gw_flags: 49, packet_count: 10, gw_product: 64) [ 380364] add_default_route()... [ 380375] client_to_gw_tun() started... [ 380375] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 ......................... 20 times the same message ......................... [ 403643] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 [ 404634] ERROR: rcvd dbgl msg via fd 15, len 0, error Success [ 404643] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 ......................... 37 times the same message ......................... [ 445253] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 [ 445454] Gateway client - disconnecting from unresponsive gateway (5.252.88.99) ! [ 445455] Gateway client - Maximum number of tunnel ip requests send ! [ 445455] Gateway client - Ignoring this GW for 10 secs [ 445456] terminating client_to_gw_tun thread: is_aborted(): NO, curr_gateway: 4799696, deleted: 0 [ 445459] Error - can't delete route to 0.0.0.0/0 via 0.0.0.0 (table 68): No such process [ 445466] Error - can't delete tun device: Inappropriate ioctl for device [ 456306] using default tunnel to GW 5.252.88.99 (gw_flags: 49, packet_count: 370, gw_product: 87616) [ 456310] add_default_route()... [ 456318] client_to_gw_tun() started... [ 456352] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 ......................... 59 times the same message ......................... [ 521513] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 [ 521714] Gateway client - disconnecting from unresponsive gateway (5.252.88.99) ! [ 521715] Gateway client - Maximum number of tunnel ip requests send ! [ 521715] Gateway client - Ignoring this GW for 40 secs [ 521716] terminating client_to_gw_tun thread: is_aborted(): NO, curr_gateway: 4799696, deleted: 0 [ 521719] Error - can't delete route to 0.0.0.0/0 via 0.0.0.0 (table 68): No such process [ 521726] Error - can't delete tun device: Inappropriate ioctl for device [ 561963] using default tunnel to GW 5.252.88.99 (gw_flags: 49, packet_count: 890, gw_product: 506944) [ 561964] add_default_route()... [ 561973] client_to_gw_tun() started... [ 561974] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 ......................... 60 times the same message ......................... [ 627583] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 [ 627784] Gateway client - disconnecting from unresponsive gateway (5.252.88.99) ! [ 627785] Gateway client - Maximum number of tunnel ip requests send ! [ 627785] Gateway client - Ignoring this GW for 90 secs [ 627786] terminating client_to_gw_tun thread: is_aborted(): NO, curr_gateway: 4799696, deleted: 0 [ 627790] Error - can't delete route to 0.0.0.0/0 via 0.0.0.0 (table 68): No such process [ 627796] Error - can't delete tun device: Inappropriate ioctl for device [ 717783] using default tunnel to GW 5.252.88.99 (gw_flags: 49, packet_count: 990, gw_product: 627264) [ 717784] add_default_route()... [ 717792] client_to_gw_tun() started... [ 717793] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 ......................... 59 times the same message ......................... [ 782623] send ip request to gateway: 5.252.88.99, preferred IP: 0.0.0.0 [ 782824] Gateway client - disconnecting from unresponsive gateway (5.252.88.99) ! [ 782825] Gateway client - Maximum number of tunnel ip requests send ! [ 782825] Gateway client - Ignoring this GW for 160 secs [ 782826] terminating client_to_gw_tun thread: is_aborted(): NO, curr_gateway: 4799696, deleted: 0 [ 782830] Error - can't delete route to 0.0.0.0/0 via 0.0.0.0 (table 68): No such process [ 782836] Error - can't delete tun device: Inappropriate ioctl for device
And it will go on like this, again and again, after the ignoring time passes.