[Openswan Users] pluto segfault

[Roel van Meer]

Hi list,

I have the problem that pluto segfaults now and then. Sometimes it runs for 
weeks without trouble, sometimes it segfaults a few times per day. I cannot 
determine a specific cause.

Gdb backtraces are always similar:

Core was generated by `/usr/libexec/ipsec/pluto --nofork --secretsfile /etc/ipsec.secrets --ipsecdir /'.
Program terminated with signal 11, Segmentation fault.
#0  0x00007f316c3a7d3d in free () from /lib64/libc.so.6
(gdb) bt
#0  0x00007f316c3a7d3d in free () from /lib64/libc.so.6
#1  0x0000000000445ff9 in scan_proc_shunts () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/kernel_pfkey.c:1535
#2  0x000000000041b272 in handle_next_timer_event () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/timer.c:505
#3  0x000000000041ba1a in handle_timer_event () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/timer.c:435
#4  0x0000000000419725 in call_server () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/server.c:806
#5  0x00000000004160e5 in main (argc=10, argv=0x7fff3e0a9058) at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/plutomain.c:1104

This system is
Slackware 13.37 64-bit, with
openswan 2.6.38 and
kernel 3.2.18

but the problem also existed with
Slackware 13.1 32-bit, with
openswan 2.6.31 and
kernel 2.6.32.23.

The logs show nothing unusual. For a crash at 02:30:13 I have

Aug 28 02:29:41 polariseer pluto[14451]: "conn1" #345891: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "conn1" #345890: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "v22" #345853: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "v53" #345849: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "haven39" #345843: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "haven39" #345841: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:30:13 polariseer pluto[14453]: pluto_crypto_helper: helper (0) is  normal exiting 
Aug 28 02:30:13 polariseer pluto[14455]: pluto_crypto_helper: helper (2) is normal exiting
Aug 28 02:30:13 polariseer pluto[14454]: helper 1 got error: Operation not permitted
Aug 28 02:30:13 polariseer pluto[14454]: pluto_crypto_helper: helper (1) is  normal exiting 

and

Aug 28 02:30:13 polariseer kernel: pluto[14451]: segfault at 98 ip 00007f2a2c17dd3d sp 00007fffbfd3f210 error 4 in libc-2.13.so[7f2a2c101000+19b000]
Aug 28 02:30:13 polariseer kernel: klips_debug:pfkey_acquire: no sockets registered for SAtype=3(ESP).
Aug 28 02:30:13 polariseer kernel: klips_debug:pfkey_acquire: no sockets registered for SAtype=3(ESP).

Any ideas how to tackle this?

Thanks in advance,

Roel

PS: The logs are also attached as a separate file, in case my email client 
changes the formatting above.
-------------- next part --------------
Backtrace:

Core was generated by `/usr/libexec/ipsec/pluto --nofork --secretsfile /etc/ipsec.secrets --ipsecdir /'.
Program terminated with signal 11, Segmentation fault.
#0  0x00007f316c3a7d3d in free () from /lib64/libc.so.6
(gdb) bt
#0  0x00007f316c3a7d3d in free () from /lib64/libc.so.6
#1  0x0000000000445ff9 in scan_proc_shunts () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/kernel_pfkey.c:1535
#2  0x000000000041b272 in handle_next_timer_event () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/timer.c:505
#3  0x000000000041ba1a in handle_timer_event () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/timer.c:435
#4  0x0000000000419725 in call_server () at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/server.c:806
#5  0x00000000004160e5 in main (argc=10, argv=0x7fff3e0a9058) at /tmp/tmp.Trn7N8/openswan-2.6.38/programs/pluto/plutomain.c:1104

Excerpt from /var/log/secure:

Aug 28 02:29:41 polariseer pluto[14451]: "conn1" #345891: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "conn1" #345890: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "v22" #345853: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "v53" #345849: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "haven39" #345843: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:29:41 polariseer pluto[14451]: "haven39" #345841: max number of retransmissions (2) reached STATE_MAIN_R1
Aug 28 02:30:13 polariseer pluto[14453]: pluto_crypto_helper: helper (0) is  normal exiting
Aug 28 02:30:13 polariseer pluto[14455]: pluto_crypto_helper: helper (2) is normal exiting
Aug 28 02:30:13 polariseer pluto[14454]: helper 1 got error: Operation not permitted
Aug 28 02:30:13 polariseer pluto[14454]: pluto_crypto_helper: helper (1) is  normal exiting

Excerpt from /var/log/messages:

Aug 28 02:30:13 polariseer kernel: pluto[14451]: segfault at 98 ip 00007f2a2c17dd3d sp 00007fffbfd3f210 error 4 in libc-2.13.so[7f2a2c101000+19b000]
Aug 28 02:30:13 polariseer kernel: klips_debug:pfkey_acquire: no sockets registered for SAtype=3(ESP).
Aug 28 02:30:13 polariseer kernel: klips_debug:pfkey_acquire: no sockets registered for SAtype=3(ESP).