ext_session_acl helpers crashing

Firstly please tell me if I should be posting this to the users mailing
list...

I am trying to set up a splash page using the ext_session_acl helper.
However, I get the error message "FATAL: The ext_session_acl helpers are
crashing too rapidly, need help!" in the log.

I tried initially using V3.1.2 as provided with Debian, and am now
trying with V3.2.0.7

The configuration I am using is as follows:

external_acl_type ext_session_acl ttl=60 %SRC /usr/lib/squid3/ext_session_acl -t 7200 -b /etc/squid3/session.db
acl new_users external ext_session_acl
deny_info http://nelsonwr.wardroom/test.html new_users
http_access deny !new_users

Hopefully the above is correct. I was a little confused with the helper
rename as of V3.2. The man page at
http://www.squid-cache.org/Versions/v3/3.2/manuals/ext_session_acl.html
appears to still use the old configuration example.

I have set the log level to 9, which generates the following entries:

2011/06/05 16:35:39.724 kid1| ACLChecklist::preCheck: 0xa506eb0 checking 'http_access deny !new_users'
2011/06/05 16:35:39.724 kid1| ACLList::matches: checking !new_users
2011/06/05 16:35:39.724 kid1| ACL::checklistMatches: checking 'new_users'
2011/06/05 16:35:39.724 kid1| external_acl.cc(744) aclMatchExternal: acl="ext_session_acl"
2011/06/05 16:35:39.725 kid1| external_acl.cc(766) aclMatchExternal: No helper entry available
2011/06/05 16:35:39.725 kid1| aclMatchExternal: ext_session_acl("10.0.10.206") = lookup needed
2011/06/05 16:35:39.725 kid1| aclMatchExternal: "10.0.10.206": entry=@0, age=0
2011/06/05 16:35:39.725 kid1| aclMatchExternal: "10.0.10.206": queueing a call.
2011/06/05 16:35:39.725 kid1| aclMatchExternal: "10.0.10.206": return -1.
2011/06/05 16:35:39.725 kid1| ACL::ChecklistMatches: result for 'new_users' is -1
2011/06/05 16:35:39.725 kid1| ACLList::matches: result is false
2011/06/05 16:35:39.725 kid1| aclmatchAclList: 0xa506eb0 returning false (AND list entry failed to match)
2011/06/05 16:35:39.725 kid1| ACL::FindByName 'new_users'
2011/06/05 16:35:39.725 kid1| ACLChecklist::asyncInProgress: 0xa506eb0 async set to 1
2011/06/05 16:35:39.725 kid1| externalAclLookup: lookup in 'ext_session_acl' for '10.0.10.206'
2011/06/05 16:35:39.725 kid1| cbdataLock: 0xa4d6c50=2
2011/06/05 16:35:39.725 kid1| cbdataLock: 0xa506eb0=1
2011/06/05 16:35:39.725 kid1| externalAclLookup: looking up for '10.0.10.206' in 'ext_session_acl'.
2011/06/05 16:35:39.725 kid1| cbdataLock: 0xa507808=1
2011/06/05 16:35:39.725 kid1| cbdataReferenceValid: 0xa507808
2011/06/05 16:35:39.725 kid1| cbdataLock: 0xa4e8978=3
2011/06/05 16:35:39.725 kid1| cbdataLock: 0xa4e8978=4
2011/06/05 16:35:39.726 kid1| The AsyncCall helperDispatchWriteDone constructed, this=0xa5088d8 [call55]
2011/06/05 16:35:39.726 kid1| cbdataLock: 0xa4e8978=5
2011/06/05 16:35:39.726 kid1| cbdataUnlock: 0xa4e8978=4
2011/06/05 16:35:39.726 kid1| cbdataUnlock: 0xa4e8978=3
2011/06/05 16:35:39.726 kid1| Write.cc(20) Write: FD 10: sz 12: asynCall 0xa5088d8*1
2011/06/05 16:35:39.726 kid1| ModEpoll.cc(137) SetSelect: FD 10, type=2, handler=1, client_data=0xb5a7c37c, timeout=0
2011/06/05 16:35:39.726 kid1| helperDispatch: Request sent to ext_session_acl #1, 12 bytes
2011/06/05 16:35:39.726 kid1| helperSubmit: 10.0.10.206
2011/06/05 16:35:39.726 kid1| externalAclLookup: will wait for the result of '10.0.10.206' in 'ext_session_acl' (ch=0xa506eb0).
...
2011/06/05 16:35:39.729 kid1| helperHandleRead: 0 bytes from ext_session_acl #1
...
2011/06/05 16:35:39.730 kid1| WARNING: ext_session_acl #1 (FD 10) exited
2011/06/05 16:35:39.730 kid1| Too few ext_session_acl processes are running (need 1/5)
2011/06/05 16:35:39.730 kid1| leave_suid: PID 18612 called
2011/06/05 16:35:39.730 kid1| storeDirWriteCleanLogs: Starting...
2011/06/05 16:35:39.730 kid1| Finished. Wrote 0 entries.
2011/06/05 16:35:39.730 kid1| Took 0.00 seconds ( 0.00 entries/sec).
FATAL: The ext_session_acl helpers are crashing too rapidly, need help!

Is this a bug or have I done something wrong?

Thanks,

Andy
Received on Sun Jun 05 2011 - 16:04:52 MDT