freeswan/klips/test/west-pass-02/spi1-console.txt

   1 Program invoked with PATH/start.sh spi1.sh
   2
   3 Starting UML PATH/start.sh
   4 spawn PATH single
   5 tracing thread pid = XXXXX
   6 Linux version XXXX
   7 On node 0 totalpages: 8192
   8 zone(0): 0 pages.
   9 zone(1): 8192 pages.
  10 zone(2): 0 pages.
  11 Kernel command line:
  12 Calibrating delay loop... XXXX bogomips
  13 Memory: 32244k available
  14 Dentry-cache hash table entries: 4096 (order: 3, 32768 bytes)
  15 Inode-cache hash table entries: 2048 (order: 2, 16384 bytes)
  16 Mount-cache hash table entries: 512 (order: 0, 4096 bytes)
  17 Buffer-cache hash table entries: 1024 (order: 0, 4096 bytes)
  18 Page-cache hash table entries: 8192 (order: 3, 32768 bytes)
  19 POSIX conformance testing by UNIFIX
  20 Linux NET4.0 for Linux 2.4
  21 Based upon Swansea University Computer Society NET3.039
  22 Initializing RT netlink socket
  23 Starting kswapd
  24 VFS: Diskquotas version dquot_6.4.0 initialized
  25 devfs: VERSION Richard Gooch (rgooch@atnf.csiro.au)
  26 devfs: boot_options Q
  27 pty: 256 Unix98 ptys configured
  28 block: slots and queues
  29 RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
  30 Netdevice 0 : daemon backend- ethernet address = 10:0:0:ab:cd:ff
  31
  32 Netdevice 1 : daemon backend- ethernet address = 10:0:0:64:64:45
  33
  34 SLIP: version 0.8.4-NET3.019-NEWTTY (dynamic channels, max=256).
  35 loop: loaded (max 8 devices)
  36 PPP generic driver version 2.4.1
  37 Universal TUN/TAP device driver 1.4 (C)1999-2001 Maxim Krasnyansky
  38 NET4: Linux TCP/IP 1.0 for NET4.0
  39 IP Protocols: ICMP, UDP, TCP
  40 IP: routing cache hash table of 512 buckets, 4Kbytes
  41 TCP: Hash tables configured (established 2048 bind 2048)
  42 IPv4 over IPv4 tunneling driver
  43 GRE over IPv4 tunneling driver
  44 klips_info:ipsec_init: KLIPS startup, FreeS/WAN IPSec version: XXXX
  45 NET4: Unix domain sockets 1.0/SMP for Linux NET4.0.
  46 Initializing stdio console driver
  47 Initializing software serial port version 1
  48 mconsole initialized on PATH
  49 Mounted devfs on /dev
  50 INIT: version 2.78 booting
  51 Activating swap...
  52 Calculating module dependancies
  53 done.
  54 Loading modules: LIST
  55
  56 Checking all file systems...
  57 Parallelizing fsck version 1.18 (11-Nov-1999)
  58 Setting kernel variables.
  59 Mounting local filesystems...
  60 /dev/ubd/1 on /usr/share type hostfs (ro,/usr/share)
  61 /dev/shm on /tmp type tmpfs (rw)
  62 /dev/shm on /var/run type tmpfs (rw)
  63 devpts on /dev/pts type devpts (rw,mode=0622)
  64 Enabling packet forwarding: done.
  65 Configuring network interfaces: done.
  66 Cleaning: /tmp /var/lock /var/run.
  67 Initializing random number generator... done.
  68 Recovering nvi editor sessions... done.
  69 Give root password for maintenance
  70 (or type Control-D for normal startup):
  71 west:~# TZ=GMT export TZ
  72 west:~# ipsec spi --clear
  73 west:~# ipsec eroute --clear
  74 west:~# ipsec klipsdebug --set pfkey
  75 klips_debug:pfkey_x_debug_process: set
  76 klips_debug:pfkey_msg_interp: parsing message type 16 with msg_parser ABCDABCD
  77 klips_debug:pfkey_x_msg_debug_parse: .
  78 klips_debug:pfkey_release: sock=ABCDABCD sk=ABCDABCD
  79 klips_debug:pfkey_destroy_socket: STUFF
  80 klips_debug:pfkey_remove_socket: .
  81 klips_debug:pfkey_remove_socket: succeeded.
  82 klips_debug:pfkey_destroy_socket: STUFF
  83 klips_debug:pfkey_destroy_socket: STUFF
  84 klips_debug:pfkey_destroy_socket: STUFF
  85 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  86 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  87 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  88 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  89 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  90 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  91 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  92 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  93 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  94 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  95 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  96 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  97 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
  98 klips_debug:pfkey_release: succeeded.
  99 west:~# ipsec klipsdebug --set verbose
 100 klips_debug:pfkey_create: sock=ABCDABCD type:3 state:1 flags:0 protocol:2
 101 klips_debug:pfkey_create: sock->fasync_list=00000000 sk->sleep=ABCDABCD
 102 klips_debug:pfkey_insert_socket: sk=ABCDABCD
 103 klips_debug:pfkey_list_insert_socket: socketp=ABCDABCD
 104 klips_debug:pfkey_create: Socket sock=ABCDABCD sk=ABCDABCD initialised.
 105 klips_debug:pfkey_sendmsg: .
 106 klips_debug:pfkey_sendmsg: msg sent for parsing.
 107 klips_debug:pfkey_msg_interp: parsing message ver=2, type=16, errno=0, satype=0(UNKNOWN), len=9, res=0, seq=1, pid=987.
 108 klips_debug:pfkey_alloc_ipsec_sa: allocated tdb struct=ABDCABCD
 109 klips_debug:pfkey_msg_interp: allocated extr->tdb=ABCDABCD
 110 klips_debug:pfkey_msg_interp: processing ext 25 ABCDABCD with processor ABCDABCD
 111 klips_debug:pfkey_x_debug_process: .
 112 klips_debug:pfkey_x_debug_process: set
 113 klips_debug:pfkey_msg_interp: parsing message type 16 with msg_parser ABCDABCD
 114 klips_debug:pfkey_x_msg_debug_parse: .
 115 klips_debug:pfkey_release: sock=ABCDABCD sk=ABCDABCD
 116 klips_debug:pfkey_destroy_socket: STUFF
 117 klips_debug:pfkey_remove_socket: .
 118 klips_debug:pfkey_remove_socket: succeeded.
 119 klips_debug:pfkey_destroy_socket: STUFF
 120 klips_debug:pfkey_destroy_socket: STUFF
 121 klips_debug:pfkey_destroy_socket: STUFF
 122 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 123 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 124 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 125 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 126 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 127 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 128 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 129 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 130 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 131 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 132 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 133 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 134 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 135 klips_debug:pfkey_release: succeeded.
 136 west:~# ipsec eroute --add --eraf inet --src 192.0.1.0/24 --dst 192.0.2.0/24 --s aid %pass
 137 klips_debug:pfkey_create: sock=ABCDABCD type:3 state:1 flags:0 protocol:2
 138 klips_debug:pfkey_create: sock->fasync_list=00000000 sk->sleep=ABCDABCD
 139 klips_debug:pfkey_insert_socket: sk=ABCDABCD
 140 klips_debug:pfkey_list_insert_socket: socketp=ABCDABCD
 141 klips_debug:pfkey_create: Socket sock=ABCDABCD sk=ABCDABCD initialised.
 142 klips_debug:pfkey_sendmsg: .
 143 klips_debug:pfkey_sendmsg: msg sent for parsing.
 144 klips_debug:pfkey_msg_interp: parsing message ver=2, type=14, errno=0, satype=11(INT), len=22, res=0, seq=1, pid=987.
 145 klips_debug:pfkey_alloc_ipsec_sa: allocated tdb struct=ABDCABCD
 146 klips_debug:pfkey_msg_interp: allocated extr->tdb=ABCDABCD
 147 klips_debug:pfkey_msg_interp: satype 11 lookups to proto=61.
 148 klips_debug:pfkey_msg_interp: processing ext 1 ABCDABCD with processor ABCDABCD
 149 klips_debug:pfkey_sa_process: .
 150 klips_debug:pfkey_msg_interp: processing ext 5 ABCDABCD with processor ABCDABCD
 151 klips_debug:pfkey_address_process:
 152 klips_debug:pfkey_address_process: found address family=2, AF_INET, 0.0.0.0.
 153 klips_debug:pfkey_address_process: found src address.
 154 klips_debug:pfkey_address_process: successful.
 155 klips_debug:pfkey_msg_interp: processing ext 6 ABCDABCD with processor ABCDABCD
 156 klips_debug:pfkey_address_process:
 157 klips_debug:pfkey_address_process: found address family=2, AF_INET, 0.0.0.0.
 158 klips_debug:pfkey_address_process: found dst address.
 159 klips_debug:pfkey_address_process: tdb_said.dst set to 0.0.0.0.
 160 klips_debug:pfkey_address_process: successful.
 161 klips_debug:pfkey_msg_interp: processing ext 21 ABCDABCD with processor ABCDABCD
 162 klips_debug:pfkey_address_process:
 163 klips_debug:pfkey_address_process: found address family=2, AF_INET, 192.0.1.0.
 164 klips_debug:pfkey_address_process: found src flow address.
 165 klips_debug:pfkey_alloc_eroute: allocated eroute struct=ABCDABCD
 166 klips_debug:pfkey_address_parse: extr->eroute set to 192.0.1.0/0->0.0.0.0/0
 167 klips_debug:pfkey_address_process: successful.
 168 klips_debug:pfkey_msg_interp: processing ext 22 ABCDABCD with processor ABCDABCD
 169 klips_debug:pfkey_address_process:
 170 klips_debug:pfkey_address_process: found address family=2, AF_INET, 192.0.2.0.
 171 klips_debug:pfkey_address_process: found dst flow address.
 172 klips_debug:pfkey_alloc_eroute: eroute struct already allocated
 173 klips_debug:pfkey_address_parse: extr->eroute set to 192.0.1.0/0->192.0.2.0/0
 174 klips_debug:pfkey_address_process: successful.
 175 klips_debug:pfkey_msg_interp: processing ext 23 ABCDABCD with processor ABCDABCD
 176 klips_debug:pfkey_address_process:
 177 klips_debug:pfkey_address_process: found address family=2, AF_INET, 255.255.255.0.
 178 klips_debug:pfkey_address_process: found src mask address.
 179 klips_debug:pfkey_alloc_eroute: eroute struct already allocated
 180 klips_debug:pfkey_address_parse: extr->eroute set to 192.0.1.0/24->192.0.2.0/0
 181 klips_debug:pfkey_address_process: successful.
 182 klips_debug:pfkey_msg_interp: processing ext 24 ABCDABCD with processor ABCDABCD
 183 klips_debug:pfkey_address_process:
 184 klips_debug:pfkey_address_process: found address family=2, AF_INET, 255.255.255.0.
 185 klips_debug:pfkey_address_process: found dst mask address.
 186 klips_debug:pfkey_alloc_eroute: eroute struct already allocated
 187 klips_debug:pfkey_address_parse: extr->eroute set to 192.0.1.0/24->192.0.2.0/24
 188 klips_debug:pfkey_address_process: successful.
 189 klips_debug:pfkey_msg_interp: parsing message type 14 with msg_parser ABCDABCD
 190 klips_debug:pfkey_x_addflow_parse: .
 191 klips_debug:pfkey_x_addflow_parse: calling breakeroute and/or makeroute for 192.0.1.0/24->192.0.2.0/24
 192 klips_debug:pfkey_x_addflow_parse: calling makeroute.
 193 klips_debug:pfkey_x_addflow_parse: makeroute call successful.
 194 klips_debug:pfkey_msg_hdr_build:
 195 klips_debug:pfkey_msg_hdr_build: on_entry &pfkey_ext=ABCDABCD pfkey_ext=ABCDABCD *pfkey_ext=ABCDABCD
 196 klips_debug:pfkey_msg_hdr_build: on_exit &pfkey_ext=ABCDABCD pfkey_ext=ABCDABCD *pfkey_ext=ABCDABCD
 197 klips_debug:pfkey_safe_build: error=0
 198 klips_debug:pfkey_safe_build:success.
 199 klips_debug:pfkey_sa_build: spi=00000100 replay=0 sa_state=0 auth=0 encrypt=0 flags=0
 200 klips_debug:pfkey_safe_build: error=0
 201 klips_debug:pfkey_safe_build:success.
 202 klips_debug:pfkey_address_build: exttype=5 proto=0 prefixlen=0
 203 klips_debug:pfkey_address_build: found address family AF_INET.
 204 klips_debug:pfkey_address_build: found address=0.0.0.0:0.
 205 klips_debug:pfkey_address_build: successful.
 206 klips_debug:pfkey_safe_build: error=0
 207 klips_debug:pfkey_safe_build:success.
 208 klips_debug:pfkey_address_build: exttype=6 proto=0 prefixlen=0
 209 klips_debug:pfkey_address_build: found address family AF_INET.
 210 klips_debug:pfkey_address_build: found address=0.0.0.0:0.
 211 klips_debug:pfkey_address_build: successful.
 212 klips_debug:pfkey_safe_build: error=0
 213 klips_debug:pfkey_safe_build:success.
 214 klips_debug:pfkey_address_build: exttype=21 proto=0 prefixlen=0
 215 klips_debug:pfkey_address_build: found address family AF_INET.
 216 klips_debug:pfkey_address_build: found address=192.0.1.0:0.
 217 klips_debug:pfkey_address_build: successful.
 218 klips_debug:pfkey_safe_build: error=0
 219 klips_debug:pfkey_safe_build:success.
 220 klips_debug:pfkey_address_build: exttype=22 proto=0 prefixlen=0
 221 klips_debug:pfkey_address_build: found address family AF_INET.
 222 klips_debug:pfkey_address_build: found address=192.0.2.0:0.
 223 klips_debug:pfkey_address_build: successful.
 224 klips_debug:pfkey_safe_build: error=0
 225 klips_debug:pfkey_safe_build:success.
 226 klips_debug:pfkey_address_build: exttype=23 proto=0 prefixlen=0
 227 klips_debug:pfkey_address_build: found address family AF_INET.
 228 klips_debug:pfkey_address_build: found address=255.255.255.0:0.
 229 klips_debug:pfkey_address_build: successful.
 230 klips_debug:pfkey_safe_build: error=0
 231 klips_debug:pfkey_safe_build:success.
 232 klips_debug:pfkey_address_build: exttype=24 proto=0 prefixlen=0
 233 klips_debug:pfkey_address_build: found address family AF_INET.
 234 klips_debug:pfkey_address_build: found address=255.255.255.0:0.
 235 klips_debug:pfkey_address_build: successful.
 236 klips_debug:pfkey_safe_build: error=0
 237 klips_debug:pfkey_safe_build:success.
 238 klips_debug:pfkey_msg_build: pfkey_msg=ABCDABCD allocated 176 bytes, &(extensions[0])=ABCDABCD
 239 klips_debug:pfkey_msg_build: copying 16 bytes from extensions[1]=ABCDABCD to=ABCDABCD
 240 klips_debug:pfkey_msg_build: copying 24 bytes from extensions[5]=ABCDABCD to=ABCDABCD
 241 klips_debug:pfkey_msg_build: copying 24 bytes from extensions[6]=ABCDABCD to=ABCDABCD
 242 klips_debug:pfkey_msg_build: copying 24 bytes from extensions[21]=ABCDABCD to=ABCDABCD
 243 klips_debug:pfkey_msg_build: copying 24 bytes from extensions[22]=ABCDABCD to=ABCDABCD
 244 klips_debug:pfkey_msg_build: copying 24 bytes from extensions[23]=ABCDABCD to=ABCDABCD
 245 klips_debug:pfkey_msg_build: copying 24 bytes from extensions[24]=ABCDABCD to=ABCDABCD
 246 klips_debug:pfkey_msg_build: extensions permitted=01e00063, seen=01e00063, required=01e00043.
 247 klips_debug:pfkey_upmsg: allocating 176 bytes...
 248 klips_debug:pfkey_upmsg: ...allocated at ABCDABCD
 249 klips_debug:pfkey_x_addflow_parse: sending up x_addflow reply message for satype=11(INT) (proto=61) to socket=ABCDABCD succeeded.
 250 klips_debug:pfkey_x_addflow_parse: extr->tdb cleaned up and freed.
 251 klips_debug:pfkey_release: sock=ABCDABCD sk=ABCDABCD
 252 klips_debug:pfkey_destroy_socket: STUFF
 253 klips_debug:pfkey_remove_socket: .
 254 klips_debug:pfkey_remove_socket: succeeded.
 255 klips_debug:pfkey_destroy_socket: STUFF
 256 klips_debug:pfkey_destroy_socket: STUFF
 257 klips_debug:pfkey_destroy_socket: STUFF
 258 klips_debug:pfkey_destroy_socket: STUFF
 259 klips_debug:pfkey_destroy_socket: STUFF
 260 klips_debug:pfkey_destroy_socket: STUFF
 261 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 262 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 263 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 264 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 265 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 266 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 267 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 268 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 269 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 270 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 271 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 272 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 273 klips_debug:pfkey_list_remove_socket: removing sock=ABCDABCD
 274 klips_debug:pfkey_release: succeeded.
 275 west:~# ipsec tncfg --attach --virtual ipsec0 --physical eth1
 276 west:~# ifconfig ipsec0 inet 192.1.2.45 netmask 0xffffff00 broadcast 192.1.2.255  up
 277 west:~# arp -s 192.1.2.23 10:00:00:64:64:45
 278 west:~# arp -s 192.1.2.254 10:00:00:64:64:45
 279 west:~# ipsec look
 280 west NOW
 281 192.0.1.0/24       -> 192.0.2.0/24       => %pass (0)
 282 ipsec0->eth1 mtu=16260(1500)->1500
 283 Destination     Gateway         Genmask         Flags   MSS Window  irtt Iface
 284 west:~# route add -host 192.0.2.1 gw 192.1.2.23 dev ipsec0
 285 west:~# nbd: module cleaned up.
 286