[support] mipv6 error
qhtf126
qhtf126 at 126.com
Fri May 15 17:36:04 JST 2009
Hi,
this is log in mn and ha .I want to know how can i solve the prombles .Thank you again.
ike log in mn ( running mip6d and then swithed the mn to the visit )
**************************************************************
2009-05-15 15:15:19 [DEBUG]: ike_pfkey.c:178:sadb_init(): pfkey_socket: 3
2009-05-15 15:15:19 [DEBUG]: ike_conf.c:4170:ike_conf_check_consistency(): checking configuration
2009-05-15 15:15:19 [INTERNAL_WARN]: ike_conf.c:3812:ike_conf_check_ikev2(): remote (default) ikev2 ipsec_sa_nego_time_limit configuration field support is unimplemented, ignored
2009-05-15 15:15:19 [INTERNAL_WARN]: ike_conf.c:4262:ike_conf_check_consistency(): configuration errors: 0, warnings: 1
2009-05-15 15:15:19 [DEBUG]: netlink.c:58:rtsock_init(): rtnetlink_socket: 4
2009-05-15 15:15:19 [DEBUG]: if_spmd.c:359: spmd I/F connection ok: 220 2B85969F3386813B5AB8A36372A69C5111EAC994
2009-05-15 15:15:19 [DEBUG]: cfsetup.c:3881: read 16 bytes
2009-05-15 15:15:19 [DEBUG]: if_spmd.c:421: spmd LOGIN ok: 250 OK
2009-05-15 15:15:19 [DEBUG]: ike_spmif.c:69:ike_spmif_init(): spmif_socket: 5
2009-05-15 15:15:19 [DEBUG]: isakmp.c:530:isakmp_open_address(): socket 6 bind 127.0.0.1[500]
2009-05-15 15:15:19 [DEBUG]: isakmp.c:530:isakmp_open_address(): socket 7 bind ::1[500]
2009-05-15 15:15:19 [DEBUG]: isakmp.c:530:isakmp_open_address(): socket 8 bind 3ffe::2:215:ff:fe4d:7046[500]
2009-05-15 15:15:19 [DEBUG]: isakmp.c:530:isakmp_open_address(): socket 9 bind 3ffe:0:0:2::4[500]
2009-05-15 15:15:19 [DEBUG]: isakmp.c:530:isakmp_open_address(): socket 10 bind fe80::215:ff:fe4d:7046%eth1[500]
2009-05-15 15:15:19 [INFO]: main.c:433:main(): starting iked for racoon2 20090327c
2009-05-15 15:15:21 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:15:21 [DEBUG]: netlink.c:89:rtsock_process(): len 64
2009-05-15 15:15:21 [DEBUG]: netlink.c:111:rtsock_process(): type 20
2009-05-15 15:15:23 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
...........................................................................
2009-05-15 15:18:00 [DEBUG]: netlink.c:89:rtsock_process(): len 64
2009-05-15 15:18:00 [DEBUG]: netlink.c:111:rtsock_process(): type 21
2009-05-15 15:18:00 [INTERNAL_ERR]: isakmp.c:522:isakmp_open_address(): bind(3ffe::3:215:ff:fe4d:7046[500]): Cannot assign requested address
2009-05-15 15:18:00 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:18:00 [DEBUG]: netlink.c:89:rtsock_process(): len 640
2009-05-15 15:18:00 [DEBUG]: netlink.c:111:rtsock_process(): type 16
2009-05-15 15:18:00 [INTERNAL_ERR]: isakmp.c:522:isakmp_open_address(): bind(3ffe::3:215:ff:fe4d:7046[500]): Cannot assign requested address
2009-05-15 15:18:00 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:18:00 [DEBUG]: netlink.c:89:rtsock_process(): len 640
2009-05-15 15:18:00 [DEBUG]: netlink.c:111:rtsock_process(): type 16
2009-05-15 15:18:00 [INTERNAL_ERR]: isakmp.c:522:isakmp_open_address(): bind(3ffe::3:215:ff:fe4d:7046[500]): Cannot assign requested address
2009-05-15 15:18:01 [INTERNAL_ERR]: isakmp.c:522:isakmp_open_address(): bind(3ffe::3:215:ff:fe4d:7046[500]): Cannot assign requested address
2009-05-15 15:18:01 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:18:01 [DEBUG]: netlink.c:89:rtsock_process(): len 64
2009-05-15 15:18:01 [DEBUG]: netlink.c:111:rtsock_process(): type 20
2009-05-15 15:18:01 [DEBUG]: isakmp.c:530:isakmp_open_address(): socket 8 bind 3ffe::3:215:ff:fe4d:7046[500]
2009-05-15 15:18:01 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:18:01 [DEBUG]: netlink.c:89:rtsock_process(): len 64
2009-05-15 15:18:01 [DEBUG]: netlink.c:111:rtsock_process(): type 20
2009-05-15 15:18:01 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:18:01 [DEBUG]: netlink.c:89:rtsock_process(): len 64
2009-05-15 15:18:01 [DEBUG]: netlink.c:111:rtsock_process(): type 20
2009-05-15 15:18:01 [DEBUG]: netlink.c:87:rtsock_process(): reading netlink socket
2009-05-15 15:18:01 [DEBUG]: netlink.c:89:rtsock_process(): len 64
2009-05-15 15:18:01 [DEBUG]: netlink.c:111:rtsock_process(): type 21
2009-05-15 15:18:01 [DEBUG]: ike_pfkey.c:621:sadb_acquire_callback(): sadb_acquire_callback: seq=1 satype=96 sa_src=3ffe:0:0:2::2[0] sa_dst=3ffe:0:0:2::1[0] samode=146 selid=129
2009-05-15 15:18:01 [DEBUG]: if_spmd.c:829: SLID ok: 250 HomeRegBinding_out
2009-05-15 15:18:01 [DEBUG]: ikev2.c:757:ikev2_initiate(): creating new ike_sa
2009-05-15 15:18:01 [DEBUG]: ike_sa.c:412:ikev2_allocate_sa(): ikev2_create_sa((nil), 3ffe::3:215:ff:fe4d:7046[500], 3ffe:0:0:2::1[500], 0x80ef698)
2009-05-15 15:18:01 [DEBUG]: ike_sa.c:415:ikev2_allocate_sa(): sa: 0x80f1bc8
2009-05-15 15:18:01 [DEBUG]: ikev2.c:799:ikev2_initiate(): child_sa: 0x80f20e8
2009-05-15 15:18:01 [DEBUG]: ikev2_child.c:139:ikev2_child_state_set(): child_sa 0x80f20e8 state IDLING -> GETSPI
2009-05-15 15:18:01 [DEBUG]: ike_pfkey.c:269:sadb_getspi(): sadb_getspi: seq=1, satype=96
2009-05-15 15:18:01 [DEBUG]: ike_pfkey.c:459:sadb_getspi_callback(): sadb_getspi_callback: seq=1, spi=0x05ef8139, satype=96, sa_src=3ffe:0:0:2::1[0], sa_dst=3ffe:0:0:2::2[0]
2009-05-15 15:18:01 [DEBUG]: ikev2_child.c:139:ikev2_child_state_set(): child_sa 0x80f20e8 state GETSPI -> GETSPI_DONE
2009-05-15 15:18:01 [DEBUG]: ikev2_proposal.c:564:ikev2_pack_proposal_sub(): ikev2_pack_proposal_sub:
2009-05-15 15:18:01 [DEBUG]: ikev2_proposal.c:572:ikev2_pack_proposal_sub(): proposal #1:
2009-05-15 15:18:01 [DEBUG]: ikev2_proposal.c:564:ikev2_pack_proposal_sub(): ikev2_pack_proposal_sub:
2009-05-15 15:18:01 [DEBUG]: ikev2_proposal.c:572:ikev2_pack_proposal_sub(): proposal #1:
2009-05-15 15:18:01 [DEBUG]: ikev2_proposal.c:587:ikev2_pack_proposal_sub(): protocol 1 spi_size 0
2009-05-15 15:18:01 [DEBUG]: dh.c:227:oakley_dh_generate(): compute DH's private.
2009-05-15 15:18:01 [DEBUG]: dh.c:227:oakley_dh_generate():
47339d81 78d6cf9e 4786771e e2687488 12f58e2c 39afe7c1 1af36dd9 458cd6b2
540f0dff 58736e75 403cc34e 99ae9e0e a3a54f7c 316a4e78 38352b6d a7ae332b
f3db7ddf 1c01ded6 3d16f36e d84c3ccd f5133173 d884f010 65b1e1b1 fe133bdc
149bb731 67e3adef a29cac33 3ee7d4a1 3af89199 fb1c20db 12a011bc 3d72f9f5
2009-05-15 15:18:01 [DEBUG]: dh.c:227:oakley_dh_generate(): compute DH's public.
2009-05-15 15:18:01 [DEBUG]: dh.c:227:oakley_dh_generate():
4d6cfc2a 28981740 02513b72 5beb1083 c2ce7a1e 408b27b0 39f256ad ef190c49
ddf41cbd 0cfd22fb 2360c959 25ee3f39 73368362 6af861ff df5fd16e f31bf1bc
7b81a472 7655b6db 5e280b0d 373b8135 116cf542 fcf04282 37144fcc 91318f45
af7cd0d7 eed1aad2 ba09800f 0e8d9666 b0af4002 1a144659 0fc28cb4 dde2b771
2009-05-15 15:18:01 [DEBUG]: ikev2_packet.c:162:ikev2_packet_construct(): ikev2_packet_construct(34, 0x8, 0x0, 0x80f1bc8, [0x80f2488, 3])
2009-05-15 15:18:01 [DEBUG]: ikev2_packet.c:170:ikev2_packet_construct(): payload 0 type 33 (SA) data 0x80f2400 len 112
2009-05-15 15:18:01 [DEBUG]: ikev2_packet.c:170:ikev2_packet_construct(): payload 1 type 34 (KE) data 0x80ef3d8 len 132
2009-05-15 15:18:01 [DEBUG]: ikev2_packet.c:170:ikev2_packet_construct(): payload 2 type 40 (NONCE) data 0x80f1b88 len 32
2009-05-15 15:18:01 [DEBUG]: ikev2_packet.c:295:ikev2_packet_construct(): result 0x80eb1b0
2009-05-15 15:18:01 [DEBUG]: ikev2.c:563:ikev2_transmit(): ikev2_transmit(0x80f1bc8, 0x80eb1b0) len 316
2009-05-15 15:18:01 [DEBUG]: isakmp.c:1678:isakmp_transmit_noretry(): transmit 0x80f1c88
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:324:sendfromto(): sockname 3ffe::3:215:ff:fe4d:7046[500]
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:326:sendfromto(): send packet from 3ffe::3:215:ff:fe4d:7046[500]
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:328:sendfromto(): send packet to 3ffe:0:0:2::1[500]
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:384:sendfromto(): src6 3ffe::3:215:ff:fe4d:7046[500] 0
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:388:sendfromto(): dst6 3ffe:0:0:2::1[500] 0
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:400:sendfromto(): 1 times of 316 bytes message will be sent to 3ffe:0:0:2::1[500]
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:404:sendfromto():
4195f908 b71cffae 00000000 00000000 21202208 00000000 0000013c 22000074
00000070 0101000c 0300000c 0100000c 800e00c0 0300000c 0100000c 800e0080
03000008 01000003 03000008 02000001 03000008 02000002 03000008 02000004
03000008 03000002 03000008 03000001 03000008 04000002 03000008 04000005
03000008 0400000e 00000008 0400000f 28000088 00020000 4d6cfc2a 28981740
02513b72 5beb1083 c2ce7a1e 408b27b0 39f256ad ef190c49 ddf41cbd 0cfd22fb
2360c959 25ee3f39 73368362 6af861ff df5fd16e f31bf1bc 7b81a472 7655b6db
5e280b0d 373b8135 116cf542 fcf04282 37144fcc 91318f45 af7cd0d7 eed1aad2
ba09800f 0e8d9666 b0af4002 1a144659 0fc28cb4 dde2b771 00000024 38e7d4ff
02c6a974 f3355a72 7397bd43 b6281bab eee3d395 e0661993 d7d51f37
2009-05-15 15:18:01 [DEBUG]: isakmp.c:1656:isakmp_transmit(): sched 0x80f1ba0
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:221:recvfromto(): cmsg 41 50
2009-05-15 15:18:01 [DEBUG]: sockmisc.c:221:recvfromto(): cmsg 41 50
2009-05-15 15:18:01 [DEBUG]: isakmp.c:767:isakmp_handler(): ===
2009-05-15 15:18:01 [DEBUG]: isakmp.c:768:isakmp_handler(): 248 bytes message received from 3ffe:0:0:2::1[500]
...............................................................
spmd log in mn
***********************************************************
2009-05-15 15:15:13 [INFO]: main.c:171: Racoon Spmd - Security Policy Management Daemon - Started
2009-05-15 15:15:13 [INFO]: main.c:172: Spmd Version: 20090327c
2009-05-15 15:15:13 [INFO]: main.c:450: 'files' found in nsswitch.conf hosts line, we will read hosts file
2009-05-15 15:15:13 [INFO]: main.c:459: 'dns' found in nsswitch.conf hosts line, we will start dns proxy service
2009-05-15 15:15:14 [DEBUG]: spmd_pfkey.c:347: No install for (selector=HomeRegBinding_out)
2009-05-15 15:15:14 [DEBUG]: spmd_pfkey.c:347: No install for (selector=MobPfxDisc_out)
2009-05-15 15:15:14 [DEBUG]: spmd_pfkey.c:347: No install for (selector=TunnelPayload_out)
2009-05-15 15:15:14 [DEBUG]: task.c:86: === ALLOC TASK: 0x808ff30
2009-05-15 15:15:14 [DEBUG]: task.c:122: === ADD TASK: 0x808ff30
2009-05-15 15:15:14 [DEBUG]: cfsetup.c:3881: read 16 bytes
2009-05-15 15:15:14 [DEBUG]: shell.c:765: Password=13233818F4C7D5D83438E77311145047
2009-05-15 15:15:14 [DEBUG]: task.c:86: === ALLOC TASK: 0x8089720
2009-05-15 15:15:14 [DEBUG]: task.c:122: === ADD TASK: 0x8089720
2009-05-15 15:15:14 [DEBUG]: task.c:347: [READ Queue][00]:0x808ff30:0x8058afd
2009-05-15 15:15:14 [DEBUG]: task.c:347: [READ Queue][01]:0x8089720:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:161: === DEL TASK: 0x808ff30
2009-05-15 15:15:19 [DEBUG]: task.c:385: [RUN Queue][00]:0x808ff30:0x8058afd
2009-05-15 15:15:19 [DEBUG]: if_pfkeyv2.c:276: REGISTER message is not interesting because pid 6144 is not mine
2009-05-15 15:15:19 [DEBUG]: task.c:86: === ALLOC TASK: 0x8092788
2009-05-15 15:15:19 [DEBUG]: task.c:122: === ADD TASK: 0x8092788
2009-05-15 15:15:19 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][00]:0x8089720:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][01]:0x8092788:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:97: === FREE TASK: 0x808ff30
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][00]:0x8089720:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][01]:0x8092788:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:161: === DEL TASK: 0x8092788
2009-05-15 15:15:19 [DEBUG]: task.c:385: [RUN Queue][00]:0x8092788:0x8058afd
2009-05-15 15:15:19 [DEBUG]: if_pfkeyv2.c:276: REGISTER message is not interesting because pid 6144 is not mine
2009-05-15 15:15:19 [DEBUG]: task.c:86: === ALLOC TASK: 0x808ff30
2009-05-15 15:15:19 [DEBUG]: task.c:122: === ADD TASK: 0x808ff30
2009-05-15 15:15:19 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][00]:0x8089720:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][01]:0x808ff30:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:97: === FREE TASK: 0x8092788
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][00]:0x8089720:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][01]:0x808ff30:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:161: === DEL TASK: 0x8089720
2009-05-15 15:15:19 [DEBUG]: task.c:161: === DEL TASK: 0x808ff30
2009-05-15 15:15:19 [DEBUG]: task.c:385: [RUN Queue][00]:0x8089720:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:385: [RUN Queue][01]:0x808ff30:0x8058afd
2009-05-15 15:15:19 [DEBUG]: shell.c:691: Seed=E26F21B561BF60B15D6E3005896D4E1EF0C250412D60CF1105730B277A7272F94D4A2ACCA4F9BFAF4580B9F85D8E5E52DC765A69AA1B6A922EC2994025BD138609AE2009-05-15 15:15:19 [DEBUG]: task.c:86: === ALLOC TASK: 0x808e320
2009-05-15 15:15:19 [DEBUG]: task.c:122: === ADD TASK: 0x808e320
2009-05-15 15:15:19 [DEBUG]: task.c:86: === ALLOC TASK: 0x808e358
2009-05-15 15:15:19 [DEBUG]: task.c:122: === ADD TASK: 0x808e358
2009-05-15 15:15:19 [DEBUG]: if_pfkeyv2.c:276: REGISTER message is not interesting because pid 6144 is not mine
2009-05-15 15:15:19 [DEBUG]: task.c:86: === ALLOC TASK: 0x808e390
2009-05-15 15:15:19 [DEBUG]: task.c:122: === ADD TASK: 0x808e390
2009-05-15 15:15:19 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e320:0x805126c
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][01]:0x808e358:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][02]:0x808e390:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:97: === FREE TASK: 0x8089720
2009-05-15 15:15:19 [DEBUG]: task.c:97: === FREE TASK: 0x808ff30
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][00]:0x808e320:0x805126c
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][01]:0x808e358:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][02]:0x808e390:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:161: === DEL TASK: 0x808e320
2009-05-15 15:15:19 [DEBUG]: task.c:385: [RUN Queue][00]:0x808e320:0x805126c
2009-05-15 15:15:19 [DEBUG]: shell.c:615: SPMD Interface Command=>LOGIN
2009-05-15 15:15:19 [DEBUG]: cfsetup.c:3881: read 16 bytes
2009-05-15 15:15:19 [DEBUG]: shell.c:765: Password=13233818F4C7D5D83438E77311145047
2009-05-15 15:15:19 [DEBUG]: shell.c:807: Spmd interface Login Password=>13233818F4C7D5D83438E77311145047
2009-05-15 15:15:19 [DEBUG]: shell.c:808: Spmd interface Login Challenge=>2B85969F3386813B5AB8A36372A69C5111EAC994
2009-05-15 15:15:19 [DEBUG]: shell.c:809: Spmd interface Login Hash=>092126B64CCE467FE539ED1E30717324F151091E
2009-05-15 15:15:19 [DEBUG]: shell.c:852: Spmd interface connected(fd=6)
2009-05-15 15:15:19 [DEBUG]: task.c:86: === ALLOC TASK: 0x8089720
2009-05-15 15:15:19 [DEBUG]: task.c:122: === ADD TASK: 0x8089720
2009-05-15 15:15:19 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][01]:0x808e390:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:403: [=READ Queue][02]:0x8089720:0x805126c
2009-05-15 15:15:19 [DEBUG]: task.c:97: === FREE TASK: 0x808e320
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][01]:0x808e390:0x8058afd
2009-05-15 15:15:19 [DEBUG]: task.c:347: [READ Queue][02]:0x8089720:0x805126c
................................................................................
2009-05-15 15:17:15 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2191: Found selector(=HomeRegBinding_out) suitable for the external policy.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2133: spid data: dumping spid<->selector_index entries...
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=120, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2137: spid data: done
2009-05-15 15:17:15 [DEBUG]: task.c:86: === ALLOC TASK: 0x808e3c8
2009-05-15 15:17:15 [DEBUG]: task.c:122: === ADD TASK: 0x808e3c8
2009-05-15 15:17:15 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][02]:0x808e3c8:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:97: === FREE TASK: 0x808e390
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][02]:0x808e3c8:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:161: === DEL TASK: 0x808e3c8
2009-05-15 15:17:15 [DEBUG]: task.c:385: [RUN Queue][00]:0x808e3c8:0x8058afd
2009-05-15 15:17:15 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2191: Found selector(=HomeRegBinding_out) suitable for the external policy.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2133: spid data: dumping spid<->selector_index entries...
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=120, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=129, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2137: spid data: done
2009-05-15 15:17:15 [DEBUG]: task.c:86: === ALLOC TASK: 0x808ec00
2009-05-15 15:17:15 [DEBUG]: task.c:122: === ADD TASK: 0x808ec00
2009-05-15 15:17:15 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][02]:0x808ec00:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:97: === FREE TASK: 0x808e3c8
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][02]:0x808ec00:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:161: === DEL TASK: 0x808ec00
2009-05-15 15:17:15 [DEBUG]: task.c:385: [RUN Queue][00]:0x808ec00:0x8058afd
2009-05-15 15:17:15 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2191: Found selector(=MobPfxDisc_out) suitable for the external policy.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2133: spid data: dumping spid<->selector_index entries...
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=120, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=129, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=136, selector_index=MobPfxDisc_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2137: spid data: done
2009-05-15 15:17:15 [DEBUG]: task.c:86: === ALLOC TASK: 0x8090e58
2009-05-15 15:17:15 [DEBUG]: task.c:122: === ADD TASK: 0x8090e58
2009-05-15 15:17:15 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][02]:0x8090e58:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:97: === FREE TASK: 0x808ec00
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][02]:0x8090e58:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:161: === DEL TASK: 0x8090e58
2009-05-15 15:17:15 [DEBUG]: task.c:385: [RUN Queue][00]:0x8090e58:0x8058afd
2009-05-15 15:17:15 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2191: Found selector(=MobPfxDisc_out) suitable for the external policy.
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2133: spid data: dumping spid<->selector_index entries...
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=120, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=129, selector_index=HomeRegBinding_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=136, selector_index=MobPfxDisc_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2135: {spid=145, selector_index=MobPfxDisc_out}
2009-05-15 15:17:15 [DEBUG]: spmd_pfkey.c:2137: spid data: done
2009-05-15 15:17:15 [DEBUG]: task.c:86: === ALLOC TASK: 0x8091628
2009-05-15 15:17:15 [DEBUG]: task.c:122: === ADD TASK: 0x8091628
2009-05-15 15:17:15 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:403: [=READ Queue][02]:0x8091628:0x8058afd
2009-05-15 15:17:15 [DEBUG]: task.c:97: === FREE TASK: 0x8090e58
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][01]:0x8089720:0x805126c
2009-05-15 15:17:15 [DEBUG]: task.c:347: [READ Queue][02]:0x8091628:0x8058afd
2009-05-15 15:18:01 [DEBUG]: task.c:161: === DEL TASK: 0x8089720
2009-05-15 15:18:01 [DEBUG]: task.c:161: === DEL TASK: 0x8091628
2009-05-15 15:18:01 [DEBUG]: task.c:385: [RUN Queue][00]:0x8089720:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:385: [RUN Queue][01]:0x8091628:0x8058afd
2009-05-15 15:18:01 [DEBUG]: shell.c:615: SPMD Interface Command=>SLID
2009-05-15 15:18:01 [DEBUG]: task.c:86: === ALLOC TASK: 0x8090e58
2009-05-15 15:18:01 [DEBUG]: task.c:122: === ADD TASK: 0x8090e58
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:1821: Already the same SP exists - It's not necessary to update the internal spid<->slid list
2009-05-15 15:18:01 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:972: Updated: slid=HomeRegBinding_out, spid=120
2009-05-15 15:18:01 [DEBUG]: task.c:86: === ALLOC TASK: 0x8091660
2009-05-15 15:18:01 [DEBUG]: task.c:122: === ADD TASK: 0x8091660
2009-05-15 15:18:01 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][01]:0x8090e58:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][02]:0x8091660:0x8058afd
2009-05-15 15:18:01 [DEBUG]: task.c:97: === FREE TASK: 0x8089720
2009-05-15 15:18:01 [DEBUG]: task.c:97: === FREE TASK: 0x8091628
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][01]:0x8090e58:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][02]:0x8091660:0x8058afd
2009-05-15 15:18:01 [DEBUG]: task.c:161: === DEL TASK: 0x8091660
2009-05-15 15:18:01 [DEBUG]: task.c:385: [RUN Queue][00]:0x8091660:0x8058afd
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:1821: Already the same SP exists - It's not necessary to update the internal spid<->slid list
2009-05-15 15:18:01 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:972: Updated: slid=HomeRegBinding_out, spid=129
2009-05-15 15:18:01 [DEBUG]: task.c:86: === ALLOC TASK: 0x8089720
2009-05-15 15:18:01 [DEBUG]: task.c:122: === ADD TASK: 0x8089720
2009-05-15 15:18:01 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][01]:0x8090e58:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][02]:0x8089720:0x8058afd
2009-05-15 15:18:01 [DEBUG]: task.c:97: === FREE TASK: 0x8091660
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][01]:0x8090e58:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][02]:0x8089720:0x8058afd
2009-05-15 15:18:01 [DEBUG]: task.c:161: === DEL TASK: 0x8089720
2009-05-15 15:18:01 [DEBUG]: task.c:385: [RUN Queue][00]:0x8089720:0x8058afd
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:1821: Already the same SP exists - It's not necessary to update the internal spid<->slid list
2009-05-15 15:18:01 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:972: Updated: slid=MobPfxDisc_out, spid=136
2009-05-15 15:18:01 [DEBUG]: task.c:86: === ALLOC TASK: 0x8091628
2009-05-15 15:18:01 [DEBUG]: task.c:122: === ADD TASK: 0x8091628
2009-05-15 15:18:01 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][01]:0x8090e58:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:403: [=READ Queue][02]:0x8091628:0x8058afd
2009-05-15 15:18:01 [DEBUG]: task.c:97: === FREE TASK: 0x8089720
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][00]:0x808e358:0x8050ecf
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][01]:0x8090e58:0x805126c
2009-05-15 15:18:01 [DEBUG]: task.c:347: [READ Queue][02]:0x8091628:0x8058afd
2009-05-15 15:18Fri May 15 15:17:15 conf_show: vt_hostname = localhost
mip6d log in mn
**********************
Fri May 15 15:17:15 conf_show: vt_service = 7777
Fri May 15 15:17:15 conf_show: mip6_entity = 1
Fri May 15 15:17:15 conf_show: debug_level = 10
Fri May 15 15:17:15 conf_show: debug_log_file = stderr
Fri May 15 15:17:15 conf_show: PolicyModulePath = [internal]
Fri May 15 15:17:15 conf_show: DefaultBindingAclPolicy = 0
Fri May 15 15:17:15 conf_show: NonVolatileBindingCache = disabled
Fri May 15 15:17:15 conf_show: KeyMngMobCapability = enabled
Fri May 15 15:17:15 conf_show: UseMnHaIPsec = enabled
Fri May 15 15:17:15 conf_show: MnMaxHaBindingLife = 262140
Fri May 15 15:17:15 conf_show: MnMaxCnBindingLife = 420
Fri May 15 15:17:15 conf_show: MnRouterProbes = 1
Fri May 15 15:17:15 conf_show: MnRouterProbeTimeout = 0.000000
Fri May 15 15:17:15 conf_show: InitialBindackTimeoutFirstReg = 1.500000
Fri May 15 15:17:15 conf_show: InitialBindackTimeoutReReg = 1.000000
Fri May 15 15:17:15 conf_show: UseCnBuAck = enabled
Fri May 15 15:17:15 conf_show: DoRouteOptimizationMN = disabled
Fri May 15 15:17:15 conf_show: MnUseAllInterfaces = disabled
Fri May 15 15:17:15 conf_show: MnDiscardHaParamProb = enabled
Fri May 15 15:17:15 conf_show: SendMobPfxSols = enabled
Fri May 15 15:17:15 conf_show: OptimisticHandoff = disabled
Fri May 15 15:17:15 conf_show: SendMobPfxAdvs = enabled
Fri May 15 15:17:15 conf_show: SendUnsolMobPfxAdvs = enabled
Fri May 15 15:17:15 conf_show: MaxMobPfxAdvInterval = 86400
Fri May 15 15:17:15 conf_show: MinMobPfxAdvInterval = 600
Fri May 15 15:17:15 conf_show: HaMaxBindingLife = 262140
Fri May 15 15:17:15 conf_show: DoRouteOptimizationCN = enabled
Fri May 15 15:17:15 xfrm_cn_init: Adding policies and states for CN
Fri May 15 15:17:15 xfrm_mn_init: Adding policies and states for MN
Fri May 15 15:17:15 conf_home_addr_info: HoA address 3ffe:0:0:2:0:0:0:2
Fri May 15 15:17:15 conf_home_addr_info: HA address 3ffe:0:0:2:0:0:0:1
Fri May 15 15:17:15 __tunnel_add: created tunnel ip6tnl1 (5) from 3ffe:0:0:2:0:0:0:2 to 3ffe:0:0:2:0:0:0:1 user count 1
Fri May 15 15:17:15 conf_home_addr_info: Home address 3ffe:0:0:2:0:0:0:2
Fri May 15 15:17:15 flag_hoa: set HoA 3ffe:0:0:2:0:0:0:2/128 iif 5 flags 12 preferred_time 4294967295 valid_time 4294967295
Fri May 15 15:17:15 conf_home_addr_info: Added new home_addr_info successfully
Fri May 15 15:17:15 __md_discover_router: discover link on iface eth1 (4)
Fri May 15 15:17:15 md_change_default_router: have entered change default router
Fri May 15 15:17:15 md_change_default_router: if = 0
Fri May 15 15:17:15 md_change_default_router: add new router fe80:0:0:0:211:5bff:fe2f:5cb7 on interface eth1 (4)
Fri May 15 15:17:15 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:16 mn_addr_do_dad: DAD succeeded!
Fri May 15 15:17:16 mn_addr_do_dad: address = 3ffe:0:0:2:0:0:0:2
Fri May 15 15:17:16 mn_move: 1578
Fri May 15 15:17:16 mn_move: in home net
Fri May 15 15:17:16 mv_hoa: move HoA 3ffe:0:0:2:0:0:0:2/64 from iface 5 to 4
Fri May 15 15:17:18 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:22 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:25 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:28 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:31 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:33 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:36 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:45 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:48 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:51 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:54 md_update_router_stats: add coa 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:17:56 md_expire_router: expiring router fe80:0:0:0:211:5bff:fe2f:5cb7 on iface eth1 (4)
Fri May 15 15:17:56 __md_discover_router: discover link on iface eth1 (4)
Fri May 15 15:18:00 md_change_default_router: have entered change default router
Fri May 15 15:18:00 md_change_default_router: if = 0
Fri May 15 15:18:00 md_change_default_router: add new router fe80:0:0:0:21d:fff:fe13:cfe1 on interface eth1 (4)
Fri May 15 15:18:00 md_update_router_stats: add coa 3ffe:0:0:3:215:ff:fe4d:7046 on interface (4)
Fri May 15 15:18:01 mn_move: 1578
Fri May 15 15:18:01 mn_move: in foreign net
Fri May 15 15:18:01 mv_hoa: move HoA 3ffe:0:0:2:0:0:0:2/128 from iface 4 to 5
Fri May 15 15:18:01 mn_send_home_bu: 725
Fri May 15 15:18:01 mn_get_home_lifetime: CoA lifetime 2591998 s, HoA lifetime 11992 s, BU lifetime 11992 s
Fri May 15 15:18:01 process_first_home_bu: New bule for HA
Fri May 15 15:18:01 bul_add: Adding bule
== BUL_ENTRY ==
Home address 3ffe:0:0:2:0:0:0:2
Care-of address 3ffe:0:0:3:215:ff:fe4d:7046
CN address 3ffe:0:0:2:0:0:0:1
lifetime = 11992, delay = 1500
flags: IP6_MH_BU_HOME IP6_MH_BU_ACK
Fri May 15 15:18:01 mn_send_home_bu: New bule for HA
Fri May 15 15:18:01 mh_send: sending MH type 5
from 3ffe:0:0:2:0:0:0:2
to 3ffe:0:0:2:0:0:0:1
Fri May 15 15:18:01 mh_send: local CoA 3ffe:0:0:3:215:ff:fe4d:7046
:01 [DEBUG]: task.c:161: === DEL TASK: 0x8091628
2009-05-15 15:18:01 [DEBUG]: task.c:385: [RUN Queue][00]:0x8091628:0x8058afd
2009-05-15 15:18:01 [DEBUG]: spmd_pfkey.c:1821: Already the
*****************************************************************************************
spmd log in ha
*********************************
2009-05-15 15:12:31 [INFO]: main.c:171: Racoon Spmd - Security Policy Management Daemon - Started
2009-05-15 15:12:31 [INFO]: main.c:172: Spmd Version: 20090327c
2009-05-15 15:12:31 [INFO]: main.c:450: 'files' found in nsswitch.conf hosts line, we will read hosts file
2009-05-15 15:12:31 [INFO]: main.c:459: 'dns' found in nsswitch.conf hosts line, we will start dns proxy service
2009-05-15 15:12:32 [DEBUG]: spmd_pfkey.c:347: No install for (selector=HomeRegBinding_out)
2009-05-15 15:12:32 [DEBUG]: spmd_pfkey.c:347: No install for (selector=MobPfxDisc_out)
2009-05-15 15:12:32 [DEBUG]: spmd_pfkey.c:347: No install for (selector=TunnelPayload_out)
2009-05-15 15:12:32 [DEBUG]: task.c:86: === ALLOC TASK: 0x808ff40
2009-05-15 15:12:32 [DEBUG]: task.c:122: === ADD TASK: 0x808ff40
2009-05-15 15:12:32 [DEBUG]: cfsetup.c:3881: read 16 bytes
2009-05-15 15:12:32 [DEBUG]: shell.c:765: Password=13233818F4C7D5D83438E77311145047
2009-05-15 15:12:32 [DEBUG]: task.c:86: === ALLOC TASK: 0x808dc20
2009-05-15 15:12:32 [DEBUG]: task.c:122: === ADD TASK: 0x808dc20
2009-05-15 15:12:32 [DEBUG]: task.c:347: [READ Queue][00]:0x808ff40:0x8058afd
2009-05-15 15:12:32 [DEBUG]: task.c:347: [READ Queue][01]:0x808dc20:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:161: === DEL TASK: 0x808ff40
2009-05-15 15:12:35 [DEBUG]: task.c:385: [RUN Queue][00]:0x808ff40:0x8058afd
2009-05-15 15:12:35 [DEBUG]: if_pfkeyv2.c:276: REGISTER message is not interesting because pid 10715 is not mine
2009-05-15 15:12:35 [DEBUG]: task.c:86: === ALLOC TASK: 0x8089708
2009-05-15 15:12:35 [DEBUG]: task.c:122: === ADD TASK: 0x8089708
2009-05-15 15:12:35 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][00]:0x808dc20:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][01]:0x8089708:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:97: === FREE TASK: 0x808ff40
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][00]:0x808dc20:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][01]:0x8089708:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:161: === DEL TASK: 0x8089708
2009-05-15 15:12:35 [DEBUG]: task.c:385: [RUN Queue][00]:0x8089708:0x8058afd
2009-05-15 15:12:35 [DEBUG]: if_pfkeyv2.c:276: REGISTER message is not interesting because pid 10715 is not mine
2009-05-15 15:12:35 [DEBUG]: task.c:86: === ALLOC TASK: 0x808ff40
2009-05-15 15:12:35 [DEBUG]: task.c:122: === ADD TASK: 0x808ff40
2009-05-15 15:12:35 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][00]:0x808dc20:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][01]:0x808ff40:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:97: === FREE TASK: 0x8089708
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][00]:0x808dc20:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][01]:0x808ff40:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:161: === DEL TASK: 0x808dc20
2009-05-15 15:12:35 [DEBUG]: task.c:161: === DEL TASK: 0x808ff40
2009-05-15 15:12:35 [DEBUG]: task.c:385: [RUN Queue][00]:0x808dc20:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:385: [RUN Queue][01]:0x808ff40:0x8058afd
2009-05-15 15:12:35 [DEBUG]: shell.c:691: Seed=B21BF3A3FFEE49596A398DD908702A8913223325D7B5B296DA42D400A4BC45DC62E66EF5E9B40A8A7238108884FB09CC8B2587812BA388A48C414DD0440F19C037072009-05-15 15:12:35 [DEBUG]: task.c:86: === ALLOC TASK: 0x8090c08
2009-05-15 15:12:35 [DEBUG]: task.c:122: === ADD TASK: 0x8090c08
2009-05-15 15:12:35 [DEBUG]: task.c:86: === ALLOC TASK: 0x8090c40
2009-05-15 15:12:35 [DEBUG]: task.c:122: === ADD TASK: 0x8090c40
2009-05-15 15:12:35 [DEBUG]: if_pfkeyv2.c:276: REGISTER message is not interesting because pid 10715 is not mine
2009-05-15 15:12:35 [DEBUG]: task.c:86: === ALLOC TASK: 0x8090c88
2009-05-15 15:12:35 [DEBUG]: task.c:122: === ADD TASK: 0x8090c88
2009-05-15 15:12:35 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][00]:0x8090c08:0x805126c
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][01]:0x8090c40:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][02]:0x8090c88:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:97: === FREE TASK: 0x808dc20
2009-05-15 15:12:35 [DEBUG]: task.c:97: === FREE TASK: 0x808ff40
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][00]:0x8090c08:0x805126c
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][01]:0x8090c40:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][02]:0x8090c88:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:161: === DEL TASK: 0x8090c08
2009-05-15 15:12:35 [DEBUG]: task.c:385: [RUN Queue][00]:0x8090c08:0x805126c
2009-05-15 15:12:35 [DEBUG]: shell.c:615: SPMD Interface Command=>LOGIN
2009-05-15 15:12:35 [DEBUG]: cfsetup.c:3881: read 16 bytes
2009-05-15 15:12:35 [DEBUG]: shell.c:765: Password=13233818F4C7D5D83438E77311145047
2009-05-15 15:12:35 [DEBUG]: shell.c:807: Spmd interface Login Password=>13233818F4C7D5D83438E77311145047
2009-05-15 15:12:35 [DEBUG]: shell.c:808: Spmd interface Login Challenge=>4A7295EAE04A54F8370CE7FA2AAED4E1C6D3A7CF
2009-05-15 15:12:35 [DEBUG]: shell.c:809: Spmd interface Login Hash=>D12371D9CE7CB0ACBA3861FFC60604B90A3A13AF
2009-05-15 15:12:35 [DEBUG]: shell.c:852: Spmd interface connected(fd=6)
2009-05-15 15:12:35 [DEBUG]: task.c:86: === ALLOC TASK: 0x808dc20
2009-05-15 15:12:35 [DEBUG]: task.c:122: === ADD TASK: 0x808dc20
2009-05-15 15:12:35 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][00]:0x8090c40:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][01]:0x8090c88:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:403: [=READ Queue][02]:0x808dc20:0x805126c
2009-05-15 15:12:35 [DEBUG]: task.c:97: === FREE TASK: 0x8090c08
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][00]:0x8090c40:0x8050ecf
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][01]:0x8090c88:0x8058afd
2009-05-15 15:12:35 [DEBUG]: task.c:347: [READ Queue][02]:0x808dc20:0x805126c
2009-05-15 15:12:52 [DEBUG]: task.c:161: === DEL TASK: 0x8090c88
2009-05-15 15:12:52 [DEBUG]: task.c:385: [RUN Queue][00]:0x8090c88:0x8058afd
2009-05-15 15:12:52 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:12:52 [INTERNAL_ERR]: spmd_pfkey.c:953: Failed to update slid<->spid matching
2009-05-15 15:12:52 [DEBUG]: task.c:86: === ALLOC TASK: 0x8090cc0
2009-05-15 15:12:52 [DEBUG]: task.c:122: === ADD TASK: 0x8090cc0
2009-05-15 15:12:52 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:12:52 [DEBUG]: task.c:403: [=READ Queue][00]:0x8090c40:0x8050ecf
2009-05-15 15:12:52 [DEBUG]: task.c:403: [=READ Queue][01]:0x808dc20:0x805126c
2009-05-15 15:12:52 [DEBUG]: task.c:403: [=READ Queue][02]:0x8090cc0:0x8058afd
2009-05-15 15:12:52 [DEBUG]: task.c:97: === FREE TASK: 0x8090c88
2009-05-15 15:12:52 [DEBUG]: task.c:347: [READ Queue][00]:0x8090c40:0x8050ecf
2009-05-15 15:12:52 [DEBUG]: task.c:347: [READ Queue][01]:0x808dc20:0x805126c
2009-05-15 15:12:52 [DEBUG]: task.c:347: [READ Queue][02]:0x8090cc0:0x8058afd
2009-05-15 15:12:52 [DEBUG]: task.c:161: === DEL TASK: 0x8090cc0
2009-05-15 15:12:52 [DEBUG]: task.c:385: [RUN Queue][00]:0x8090cc0:0x8058afd
2009-05-15 15:12:52 [INTERNAL_ERR]: spmd_pfkey.c:1829: No spid_data entry with this sequence.
2009-05-15 15:12:52 [INTERNAL_ERR]: spmd_pfkey.c:953: Failed to update slid<->spid matching
2009-05-15 15:12:52 [DEBUG]: task.c:86: === ALLOC TASK: 0x8091c98
2009-05-15 15:12:52 [DEBUG]: task.c:122: === ADD TASK: 0x8091c98
2009-05-15 15:12:52 [DEBUG]: task.c:394: === TASK RUN
2009-05-15 15:12:52 [DEBUG]: task.c:403: [=READ Queue][00]:0x8090c40:0x8050ecf
2009-05-15 15:12:52 [DEBUG]: task.c:403: [=READ Queue][01]:0x808dc20:0x805126c
2009-05-15 15:12:52 [DEBUG]: task.c:403: [=READ Queue][02]:0x8091c98:0x8058afd
2009-05-15 15:12:52 [DEBUG]: task.c:97: === FREE TASK: 0x8090cc0
2009-05-15 15:12:52 [DEBUG]: task.c:347: [READ Queue][00]:0x8090c40:0x8050ecf
....................................................
iked log in ha (after switching mn to visit )
***********************************
2009-05-15 15:18:52 [DEBUG]: ikev2_auth.c:437:ikev2_auth_verify(): auth method 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:718:ikev2_public_key(): using /etc/openssl-ca/clients/certs/mn.mydomain.com.cert
2009-05-15 15:18:52 [DEBUG]: ikev2_auth.c:535:ikev2_auth_verify(): result: 1
2009-05-15 15:18:52 [DEBUG]: ikev2_notify.c:652:ikev2_process_child_notify(): ikev2_process_child_notify(0x80f83e0, 0xbfae2198)
2009-05-15 15:18:52 [DEBUG]: ikev2_notify.c:654:ikev2_process_child_notify(): notify type USE_TRANSPORT_MODE
2009-05-15 15:18:52 [DEBUG]: ikev2_child.c:386:ikev2_create_child_responder(): ikev2_create_child_responder(0x80f2d38, 0x00000001, 0x80f83e8, 0x80f8424, 0x80f8454, (nil), 0xbfae2198)
2009-05-15 15:18:52 [DEBUG]: isakmp.c:1266:isakmp_parse_proposal(): proposal #1 len=56
2009-05-15 15:18:52 [DEBUG]: ikev2_proposal.c:181:ikev2_get_transforms(): transform type 1 len 12
2009-05-15 15:18:52 [DEBUG]: ikev2_proposal.c:181:ikev2_get_transforms(): transform type 1 len 8
2009-05-15 15:18:52 [DEBUG]: ikev2_proposal.c:181:ikev2_get_transforms(): transform type 3 len 8
2009-05-15 15:18:52 [DEBUG]: ikev2_proposal.c:181:ikev2_get_transforms(): transform type 3 len 8
2009-05-15 15:18:52 [DEBUG]: ikev2_proposal.c:181:ikev2_get_transforms(): transform type 5 len 8
2009-05-15 15:18:52 [DEBUG]: ikev2_child.c:396:ikev2_create_child_responder(): child_sa: 0x80f8040
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:2152:ike_conf_find_ikev2sel_by_ts(): ike_conf_find_ikev2sel_by_ts
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:2081:ikev2_dump_traffic_selectors(): remote
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:286:ikev2_print_ts(): TS Payload: type=TS_IPV6_ADDR_RANGE proto=135 length=40 start_port=1536 end_port=1536
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:291:ikev2_print_ts(): TS Starting Address=3ffe:0:0:2::
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:293:ikev2_print_ts(): TS Ending Address=3ffe::2:ffff:ffff:ffff:ffff
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:295:ikev2_print_ts(): TS payload dump:
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:296:ikev2_print_ts():
08870028 06000600 3ffe0000 00000002 00000000 00000000 3ffe0000 00000002
ffffffff ffffffff
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:2081:ikev2_dump_traffic_selectors(): local
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:286:ikev2_print_ts(): TS Payload: type=TS_IPV6_ADDR_RANGE proto=135 length=40 start_port=1280 end_port=1280
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:291:ikev2_print_ts(): TS Starting Address=3ffe:0:0:2::1
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:293:ikev2_print_ts(): TS Ending Address=3ffe:0:0:2::1
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:295:ikev2_print_ts(): TS payload dump:
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:296:ikev2_print_ts():
08870028 05000500 3ffe0000 00000002 00000000 00000001 3ffe0000 00000002
00000000 00000001
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1909:ts_is_matching(): num_ts 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1937:ts_is_matching(): failed
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1909:ts_is_matching(): num_ts 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1937:ts_is_matching(): failed
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1909:ts_is_matching(): num_ts 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1937:ts_is_matching(): failed
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1909:ts_is_matching(): num_ts 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1937:ts_is_matching(): failed
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1909:ts_is_matching(): num_ts 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1925:ts_is_matching(): checking 0
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1937:ts_is_matching(): failed
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1909:ts_is_matching(): num_ts 1
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1925:ts_is_matching(): checking 0
2009-05-15 15:18:52 [DEBUG]: ike_conf.c:1937:ts_is_matching(): failed
2009-05-15 15:18:52 [PROTO_ERR]: ikev2_child.c:644:ikev2_create_child_responder(): 7:3ffe:0:0:2::1[500] - 3ffe::3:215:ff:fe4d:7046[500]:(nil):ts unacceptable
2009-05-15 15:18:52 [DEBUG]: ikev2_child.c:139:ikev2_child_state_set(): child_sa 0x80f8040 state IDLING -> EXPIRED
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:719:ikev2_notify_payload(): ikev2_notify_payload(0, (nil), 0, 38, (nil), 0)
2009-05-15 15:18:52 [DEBUG]: ikev2_packet.c:162:ikev2_packet_construct(): ikev2_packet_construct(35, 0x20, 0x1, 0x80f2d38, [0x80f4248, 1])
2009-05-15 15:18:52 [DEBUG]: ikev2_packet.c:170:ikev2_packet_construct(): payload 0 type 41 (NOTIFY) data 0x80f4848 len 4
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:617:ikev2_encrypt(): ikev2_encrypt(0x80f2d38, 0x80ec370)
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt(): encrypting:
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt(): plaintext:
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt():
00000008 00000026 4116b1c5 4582cf3d 4efad83a 63913eb1 2665e90b b8846d17
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt(): key:
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt():
541e9dbf 7bc6ccc0 7603ff3e a0371f92 2d1d8129 ad2e8ccb
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt(): iv:
2009-05-15 15:18:52 [DEBUG]: encryptor.c:195:encryptor_encrypt():
3f7a1cfb bed8dbf6 98ec1419 51376e5b
2009-05-15 15:18:52 [DEBUG]: ikev2_payload.c:699:ikev2_encrypt(): result 0x80ee388
2009-05-15 15:18:52 [DEBUG]: ikev2_packet.c:295:ikev2_packet_construct(): result 0x80f8628
2009-05-15 15:18:52 [DEBUG]: ikev2.c:582:ikev2_transmit_response(): ikev2_transmit_response(0x80f2d38, 0x80f8628) len 92
2009-05-15 15:18:52 [DEBUG]: isakmp.c:1678:isakmp_transmit_noretry(): transmit 0x80f2e28
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:324:sendfromto(): sockname 3ffe:0:0:2::1[500]
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:326:sendfromto(): send packet from 3ffe:0:0:2::1[500]
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:328:sendfromto(): send packet to 3ffe::3:215:ff:fe4d:7046[500]
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:384:sendfromto(): src6 3ffe:0:0:2::1[500] 0
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:388:sendfromto(): dst6 3ffe::3:215:ff:fe4d:7046[500] 0
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:400:sendfromto(): 1 times of 92 bytes message will be sent to 3ffe::3:215:ff:fe4d:7046[500]
2009-05-15 15:18:52 [DEBUG]: sockmisc.c:404:sendfromto():
b4cb6fc1 84c37a31 664c724b 3da4b3cb 2e202320 00000001 0000005c 29000040
3f7a1cfb bed8dbf6 98ec1419 51376e5b c713e50d 6e407efc 3359d24d 5e792798
c4a8686f 5e702cbf 9768f665 40a77281 e759ac8d 84c30a4e b0948c8b
2009-05-15 15:18:52 [DEBUG]: ikev2.c:541:ikev2_update_message_id(): update request message_id 0x1
2009-05-15 15:18:52 [DEBUG]: ike_sa.c:263:ikev2_abort(): ikev2_abort(0x80f2d38, 111)
2009-05-15 15:18:52 [INFO]: ike_sa.c:264:ikev2_abort(): 7:3ffe:0:0:2::1[500] - 3ffe::3:215:ff:fe4d:7046[500]:(nil):aborting ike_sa
2009-05-15 15:18:52 [DEBUG]: ike_sa.c:271:ikev2_abort(): child_sa 0x80f8040 state 5
2009-05-15 15:18:53 [DEBUG]: ike_sa.c:225:ikev2_sa_periodic_task(): ike_sa: 0x80f2d38 state 8
2009-05-15 15:18:53 [DEBUG]: ike_sa.c:230:ikev2_sa_periodic_task(): child_sa: 0x80f8040 state 5
2009-05-15 15:18:53 [DEBUG]: ike_sa.c:234:ikev2_sa_periodic_task(): deallocating child_sa 0x80f8040
2009-05-15 15:18:53 [DEBUG]: ike_pfkey.c:255:sadb_request_finish(): 0x80f8058
2009-05-15 15:18:53 [DEBUG]: ike_sa.c:242:ikev2_sa_periodic_task(): deallocating ike_sa 0x80f2d38
2009-05-15 15:18:53 [DEBUG]: ike_sa.c:837:ikev2_dispose_sa(): ikev2_dispose_sa(0x80f2d38)
mip6d log in ha
************************************
mip6d[10789]: MIPL Mobile IPv6 for Linux v2.0.2-umip-0.4 started (Home Agent)
Fri May 15 15:12:52 main: MIPL Mobile IPv6 for Linux started in debug mode, not detaching from terminal
Fri May 15 15:12:52 conf_show: config_file = /root/Desktop/final/ha/common/ipsec/mip6d-setkey1-u.conf~
Fri May 15 15:12:52 conf_show: vt_hostname = localhost
Fri May 15 15:12:52 conf_show: vt_service = 7777
Fri May 15 15:12:52 conf_show: mip6_entity = 2
Fri May 15 15:12:52 conf_show: debug_level = 10
Fri May 15 15:12:52 conf_show: debug_log_file = stderr
Fri May 15 15:12:52 conf_show: PolicyModulePath = [internal]
Fri May 15 15:12:52 conf_show: DefaultBindingAclPolicy = 0
Fri May 15 15:12:52 conf_show: NonVolatileBindingCache = disabled
Fri May 15 15:12:52 conf_show: KeyMngMobCapability = enabled
Fri May 15 15:12:52 conf_show: UseMnHaIPsec = enabled
Fri May 15 15:12:52 conf_show: MnMaxHaBindingLife = 262140
Fri May 15 15:12:52 conf_show: MnMaxCnBindingLife = 420
Fri May 15 15:12:52 conf_show: MnRouterProbes = 0
Fri May 15 15:12:52 conf_show: MnRouterProbeTimeout = 0.000000
Fri May 15 15:12:52 conf_show: InitialBindackTimeoutFirstReg = 1.500000
Fri May 15 15:12:52 conf_show: InitialBindackTimeoutReReg = 1.000000
Fri May 15 15:12:52 conf_show: UseCnBuAck = disabled
Fri May 15 15:12:52 conf_show: DoRouteOptimizationMN = enabled
Fri May 15 15:12:52 conf_show: MnUseAllInterfaces = disabled
Fri May 15 15:12:52 conf_show: MnDiscardHaParamProb = disabled
Fri May 15 15:12:52 conf_show: SendMobPfxSols = enabled
Fri May 15 15:12:52 conf_show: OptimisticHandoff = disabled
Fri May 15 15:12:52 conf_show: SendMobPfxAdvs = enabled
Fri May 15 15:12:52 conf_show: SendUnsolMobPfxAdvs = enabled
Fri May 15 15:12:52 conf_show: MaxMobPfxAdvInterval = 86400
Fri May 15 15:12:52 conf_show: MinMobPfxAdvInterval = 600
Fri May 15 15:12:52 conf_show: HaMaxBindingLife = 262140
Fri May 15 15:12:52 conf_show: DoRouteOptimizationCN = enabled
Fri May 15 15:12:52 xfrm_cn_init: Adding policies and states for CN
Fri May 15 15:12:52 xfrm_ha_init: Adding policies and states for HA
Fri May 15 15:12:52 ha_mn_ipsec_init: Adding IPsec policies and states for HA
Fri May 15 15:12:52 ha_if_addr_setup: Joined anycast group 3ffe:0:0:2:fdff:ffff:ffff:fffe on iface 5
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://ml.nautilus6.org/pipermail/support/attachments/20090515/f5f0154b/attachment-0001.htm
More information about the Support
mailing list