2 * Copyright (C) 1996-2014 The Squid Software Foundation and contributors
4 * Squid software is distributed under GPLv2+ license and includes
5 * contributions from numerous individuals and organizations.
6 * Please see the COPYING and CONTRIBUTORS files for details.
9 /* DEBUG: section 84 Helper process maintenance */
12 #include "base/AsyncCbdataCalls.h"
14 #include "comm/Connection.h"
15 #include "comm/Read.h"
16 #include "comm/Write.h"
19 #include "format/Quoting.h"
24 #include "SquidMath.h"
25 #include "SquidTime.h"
29 #define HELPER_MAX_ARGS 64
31 /** Initial Squid input buffer size. Helper responses may exceed this, and
32 * Squid will grow the input buffer as needed, up to ReadBufMaxSize.
34 const size_t ReadBufMinSize(4*1024);
36 /** Maximum safe size of a helper-to-Squid response message plus one.
37 * Squid will warn and close the stream if a helper sends a too-big response.
38 * ssl_crtd helper is known to produce responses of at least 10KB in size.
39 * Some undocumented helpers are known to produce responses exceeding 8KB.
41 const size_t ReadBufMaxSize(32*1024);
43 static IOCB helperHandleRead
;
44 static IOCB helperStatefulHandleRead
;
45 static void helperServerFree(helper_server
*srv
);
46 static void helperStatefulServerFree(helper_stateful_server
*srv
);
47 static void Enqueue(helper
* hlp
, helper_request
*);
48 static helper_request
*Dequeue(helper
* hlp
);
49 static helper_stateful_request
*StatefulDequeue(statefulhelper
* hlp
);
50 static helper_server
*GetFirstAvailable(helper
* hlp
);
51 static helper_stateful_server
*StatefulGetFirstAvailable(statefulhelper
* hlp
);
52 static void helperDispatch(helper_server
* srv
, helper_request
* r
);
53 static void helperStatefulDispatch(helper_stateful_server
* srv
, helper_stateful_request
* r
);
54 static void helperKickQueue(helper
* hlp
);
55 static void helperStatefulKickQueue(statefulhelper
* hlp
);
56 static void helperStatefulServerDone(helper_stateful_server
* srv
);
57 static void helperRequestFree(helper_request
* r
);
58 static void helperStatefulRequestFree(helper_stateful_request
* r
);
59 static void StatefulEnqueue(statefulhelper
* hlp
, helper_stateful_request
* r
);
60 static bool helperStartStats(StoreEntry
*sentry
, void *hlp
, const char *label
);
62 CBDATA_CLASS_INIT(helper
);
63 CBDATA_CLASS_INIT(helper_server
);
64 CBDATA_CLASS_INIT(statefulhelper
);
65 CBDATA_CLASS_INIT(helper_stateful_server
);
67 InstanceIdDefinitions(HelperServerBase
, "Hlpr");
70 HelperServerBase::initStats()
79 HelperServerBase::closePipesSafely(const char *id_name
)
82 shutdown(writePipe
->fd
, SD_BOTH
);
86 if (readPipe
->fd
== writePipe
->fd
)
94 if (WaitForSingleObject(hIpc
, 5000) != WAIT_OBJECT_0
) {
96 debugs(84, DBG_IMPORTANT
, "WARNING: " << id_name
<<
97 " #" << index
<< " (PID " << (long int)pid
<< ") didn't exit in 5 seconds");
105 HelperServerBase::closeWritePipeSafely(const char *id_name
)
108 shutdown(writePipe
->fd
, (readPipe
->fd
== writePipe
->fd
? SD_BOTH
: SD_SEND
));
111 flags
.closing
= true;
112 if (readPipe
->fd
== writePipe
->fd
)
118 if (WaitForSingleObject(hIpc
, 5000) != WAIT_OBJECT_0
) {
120 debugs(84, DBG_IMPORTANT
, "WARNING: " << id_name
<<
121 " #" << index
<< " (PID " << (long int)pid
<< ") didn't exit in 5 seconds");
129 helperOpenServers(helper
* hlp
)
135 const char *args
[HELPER_MAX_ARGS
+1]; // save space for a NULL terminator
136 char fd_note_buf
[FD_DESC_SZ
];
146 if (hlp
->cmdline
== NULL
)
149 progname
= hlp
->cmdline
->key
;
151 if ((s
= strrchr(progname
, '/')))
152 shortname
= xstrdup(s
+ 1);
154 shortname
= xstrdup(progname
);
156 /* figure out how many new child are actually needed. */
157 int need_new
= hlp
->childs
.needNew();
159 debugs(84, DBG_IMPORTANT
, "helperOpenServers: Starting " << need_new
<< "/" << hlp
->childs
.n_max
<< " '" << shortname
<< "' processes");
162 debugs(84, DBG_IMPORTANT
, "helperOpenServers: No '" << shortname
<< "' processes needed.");
165 procname
= (char *)xmalloc(strlen(shortname
) + 3);
167 snprintf(procname
, strlen(shortname
) + 3, "(%s)", shortname
);
169 args
[nargs
] = procname
;
172 for (w
= hlp
->cmdline
->next
; w
&& nargs
< HELPER_MAX_ARGS
; w
= w
->next
) {
173 args
[nargs
] = w
->key
;
180 assert(nargs
<= HELPER_MAX_ARGS
);
182 for (k
= 0; k
< need_new
; ++k
) {
185 pid
= ipcCreate(hlp
->ipc_type
,
195 debugs(84, DBG_IMPORTANT
, "WARNING: Cannot run '" << progname
<< "' process.");
199 ++ hlp
->childs
.n_running
;
200 ++ hlp
->childs
.n_active
;
201 srv
= new helper_server
;
205 srv
->addr
= hlp
->addr
;
206 srv
->readPipe
= new Comm::Connection
;
207 srv
->readPipe
->fd
= rfd
;
208 srv
->writePipe
= new Comm::Connection
;
209 srv
->writePipe
->fd
= wfd
;
210 srv
->rbuf
= (char *)memAllocBuf(ReadBufMinSize
, &srv
->rbuf_sz
);
211 srv
->wqueue
= new MemBuf
;
213 srv
->requests
= (helper_request
**)xcalloc(hlp
->childs
.concurrency
? hlp
->childs
.concurrency
: 1, sizeof(*srv
->requests
));
214 srv
->parent
= cbdataReference(hlp
);
215 dlinkAddTail(srv
, &srv
->link
, &hlp
->servers
);
218 snprintf(fd_note_buf
, FD_DESC_SZ
, "%s #%d", shortname
, k
+ 1);
219 fd_note(rfd
, fd_note_buf
);
221 snprintf(fd_note_buf
, FD_DESC_SZ
, "reading %s #%d", shortname
, k
+ 1);
222 fd_note(rfd
, fd_note_buf
);
223 snprintf(fd_note_buf
, FD_DESC_SZ
, "writing %s #%d", shortname
, k
+ 1);
224 fd_note(wfd
, fd_note_buf
);
227 commSetNonBlocking(rfd
);
230 commSetNonBlocking(wfd
);
232 AsyncCall::Pointer closeCall
= asyncCall(5,4, "helperServerFree", cbdataDialer(helperServerFree
, srv
));
233 comm_add_close_handler(rfd
, closeCall
);
235 AsyncCall::Pointer call
= commCbCall(5,4, "helperHandleRead",
236 CommIoCbPtrFun(helperHandleRead
, srv
));
237 comm_read(srv
->readPipe
, srv
->rbuf
, srv
->rbuf_sz
- 1, call
);
240 hlp
->last_restart
= squid_curtime
;
241 safe_free(shortname
);
243 helperKickQueue(hlp
);
249 * helperStatefulOpenServers: create the stateful child helper processes
252 helperStatefulOpenServers(statefulhelper
* hlp
)
255 const char *args
[HELPER_MAX_ARGS
+1]; // save space for a NULL terminator
256 char fd_note_buf
[FD_DESC_SZ
];
259 if (hlp
->cmdline
== NULL
)
262 if (hlp
->childs
.concurrency
)
263 debugs(84, DBG_CRITICAL
, "ERROR: concurrency= is not yet supported for stateful helpers ('" << hlp
->cmdline
<< "')");
265 char *progname
= hlp
->cmdline
->key
;
268 if ((s
= strrchr(progname
, '/')))
269 shortname
= xstrdup(s
+ 1);
271 shortname
= xstrdup(progname
);
273 /* figure out haw mant new helpers are needed. */
274 int need_new
= hlp
->childs
.needNew();
276 debugs(84, DBG_IMPORTANT
, "helperOpenServers: Starting " << need_new
<< "/" << hlp
->childs
.n_max
<< " '" << shortname
<< "' processes");
279 debugs(84, DBG_IMPORTANT
, "helperStatefulOpenServers: No '" << shortname
<< "' processes needed.");
282 char *procname
= (char *)xmalloc(strlen(shortname
) + 3);
284 snprintf(procname
, strlen(shortname
) + 3, "(%s)", shortname
);
286 args
[nargs
] = procname
;
289 for (wordlist
*w
= hlp
->cmdline
->next
; w
&& nargs
< HELPER_MAX_ARGS
; w
= w
->next
) {
290 args
[nargs
] = w
->key
;
297 assert(nargs
<= HELPER_MAX_ARGS
);
299 for (int k
= 0; k
< need_new
; ++k
) {
304 pid_t pid
= ipcCreate(hlp
->ipc_type
,
314 debugs(84, DBG_IMPORTANT
, "WARNING: Cannot run '" << progname
<< "' process.");
318 ++ hlp
->childs
.n_running
;
319 ++ hlp
->childs
.n_active
;
320 helper_stateful_server
*srv
= new helper_stateful_server
;
323 srv
->flags
.reserved
= false;
325 srv
->addr
= hlp
->addr
;
326 srv
->readPipe
= new Comm::Connection
;
327 srv
->readPipe
->fd
= rfd
;
328 srv
->writePipe
= new Comm::Connection
;
329 srv
->writePipe
->fd
= wfd
;
330 srv
->rbuf
= (char *)memAllocBuf(ReadBufMinSize
, &srv
->rbuf_sz
);
332 srv
->parent
= cbdataReference(hlp
);
334 if (hlp
->datapool
!= NULL
)
335 srv
->data
= hlp
->datapool
->alloc();
337 dlinkAddTail(srv
, &srv
->link
, &hlp
->servers
);
340 snprintf(fd_note_buf
, FD_DESC_SZ
, "%s #%d", shortname
, k
+ 1);
341 fd_note(rfd
, fd_note_buf
);
343 snprintf(fd_note_buf
, FD_DESC_SZ
, "reading %s #%d", shortname
, k
+ 1);
344 fd_note(rfd
, fd_note_buf
);
345 snprintf(fd_note_buf
, FD_DESC_SZ
, "writing %s #%d", shortname
, k
+ 1);
346 fd_note(wfd
, fd_note_buf
);
349 commSetNonBlocking(rfd
);
352 commSetNonBlocking(wfd
);
354 AsyncCall::Pointer closeCall
= asyncCall(5,4, "helperStatefulServerFree", cbdataDialer(helperStatefulServerFree
, srv
));
355 comm_add_close_handler(rfd
, closeCall
);
357 AsyncCall::Pointer call
= commCbCall(5,4, "helperStatefulHandleRead",
358 CommIoCbPtrFun(helperStatefulHandleRead
, srv
));
359 comm_read(srv
->readPipe
, srv
->rbuf
, srv
->rbuf_sz
- 1, call
);
362 hlp
->last_restart
= squid_curtime
;
363 safe_free(shortname
);
365 helperStatefulKickQueue(hlp
);
369 helperSubmit(helper
* hlp
, const char *buf
, HLPCB
* callback
, void *data
)
372 debugs(84, 3, "helperSubmit: hlp == NULL");
373 HelperReply nilReply
;
374 callback(data
, nilReply
);
378 helper_request
*r
= new helper_request
;
381 r
->callback
= callback
;
382 r
->data
= cbdataReference(data
);
383 r
->buf
= xstrdup(buf
);
385 if ((srv
= GetFirstAvailable(hlp
)))
386 helperDispatch(srv
, r
);
390 debugs(84, DBG_DATA
, Raw("buf", buf
, strlen(buf
)));
393 /// lastserver = "server last used as part of a reserved request sequence"
395 helperStatefulSubmit(statefulhelper
* hlp
, const char *buf
, HLPCB
* callback
, void *data
, helper_stateful_server
* lastserver
)
398 debugs(84, 3, "helperStatefulSubmit: hlp == NULL");
399 HelperReply nilReply
;
400 callback(data
, nilReply
);
404 helper_stateful_request
*r
= new helper_stateful_request
;
406 r
->callback
= callback
;
407 r
->data
= cbdataReference(data
);
410 r
->buf
= xstrdup(buf
);
417 if ((buf
!= NULL
) && lastserver
) {
418 debugs(84, 5, "StatefulSubmit with lastserver " << lastserver
);
419 assert(lastserver
->flags
.reserved
);
420 assert(!(lastserver
->request
));
422 debugs(84, 5, "StatefulSubmit dispatching");
423 helperStatefulDispatch(lastserver
, r
);
425 helper_stateful_server
*srv
;
426 if ((srv
= StatefulGetFirstAvailable(hlp
))) {
427 helperStatefulDispatch(srv
, r
);
429 StatefulEnqueue(hlp
, r
);
432 debugs(84, DBG_DATA
, "placeholder: '" << r
->placeholder
<<
433 "', " << Raw("buf", buf
, strlen(buf
)));
439 * helperStatefulReleaseServer tells the helper that whoever was
440 * using it no longer needs its services.
443 helperStatefulReleaseServer(helper_stateful_server
* srv
)
445 debugs(84, 3, HERE
<< "srv-" << srv
->index
<< " flags.reserved = " << srv
->flags
.reserved
);
446 if (!srv
->flags
.reserved
)
449 ++ srv
->stats
.releases
;
451 srv
->flags
.reserved
= false;
452 if (srv
->parent
->OnEmptyQueue
!= NULL
&& srv
->data
)
453 srv
->parent
->OnEmptyQueue(srv
->data
);
455 helperStatefulServerDone(srv
);
458 /** return a pointer to the stateful routines data area */
460 helperStatefulServerGetData(helper_stateful_server
* srv
)
466 * Dump some stats about the helper states to a StoreEntry
469 helperStats(StoreEntry
* sentry
, helper
* hlp
, const char *label
)
471 if (!helperStartStats(sentry
, hlp
, label
))
474 storeAppendPrintf(sentry
, "program: %s\n",
476 storeAppendPrintf(sentry
, "number active: %d of %d (%d shutting down)\n",
477 hlp
->childs
.n_active
, hlp
->childs
.n_max
, (hlp
->childs
.n_running
- hlp
->childs
.n_active
) );
478 storeAppendPrintf(sentry
, "requests sent: %d\n",
479 hlp
->stats
.requests
);
480 storeAppendPrintf(sentry
, "replies received: %d\n",
482 storeAppendPrintf(sentry
, "queue length: %d\n",
483 hlp
->stats
.queue_size
);
484 storeAppendPrintf(sentry
, "avg service time: %d msec\n",
485 hlp
->stats
.avg_svc_time
);
486 storeAppendPrintf(sentry
, "\n");
487 storeAppendPrintf(sentry
, "%7s\t%7s\t%7s\t%11s\t%11s\t%s\t%7s\t%7s\t%7s\n",
498 for (dlink_node
*link
= hlp
->servers
.head
; link
; link
= link
->next
) {
499 helper_server
*srv
= (helper_server
*)link
->data
;
500 double tt
= 0.001 * (srv
->requests
[0] ? tvSubMsec(srv
->requests
[0]->dispatch_time
, current_time
) : tvSubMsec(srv
->dispatch_time
, srv
->answer_time
));
501 storeAppendPrintf(sentry
, "%7u\t%7d\t%7d\t%11" PRIu64
"\t%11" PRIu64
"\t%c%c%c%c\t%7.3f\t%7d\t%s\n",
507 srv
->stats
.pending
? 'B' : ' ',
508 srv
->flags
.writing
? 'W' : ' ',
509 srv
->flags
.closing
? 'C' : ' ',
510 srv
->flags
.shutdown
? 'S' : ' ',
513 srv
->requests
[0] ? Format::QuoteMimeBlob(srv
->requests
[0]->buf
) : "(none)");
516 storeAppendPrintf(sentry
, "\nFlags key:\n\n");
517 storeAppendPrintf(sentry
, " B = BUSY\n");
518 storeAppendPrintf(sentry
, " W = WRITING\n");
519 storeAppendPrintf(sentry
, " C = CLOSING\n");
520 storeAppendPrintf(sentry
, " S = SHUTDOWN PENDING\n");
524 helperStatefulStats(StoreEntry
* sentry
, statefulhelper
* hlp
, const char *label
)
526 if (!helperStartStats(sentry
, hlp
, label
))
529 storeAppendPrintf(sentry
, "program: %s\n",
531 storeAppendPrintf(sentry
, "number active: %d of %d (%d shutting down)\n",
532 hlp
->childs
.n_active
, hlp
->childs
.n_max
, (hlp
->childs
.n_running
- hlp
->childs
.n_active
) );
533 storeAppendPrintf(sentry
, "requests sent: %d\n",
534 hlp
->stats
.requests
);
535 storeAppendPrintf(sentry
, "replies received: %d\n",
537 storeAppendPrintf(sentry
, "queue length: %d\n",
538 hlp
->stats
.queue_size
);
539 storeAppendPrintf(sentry
, "avg service time: %d msec\n",
540 hlp
->stats
.avg_svc_time
);
541 storeAppendPrintf(sentry
, "\n");
542 storeAppendPrintf(sentry
, "%7s\t%7s\t%7s\t%11s\t%11s\t%6s\t%7s\t%7s\t%7s\n",
553 for (dlink_node
*link
= hlp
->servers
.head
; link
; link
= link
->next
) {
554 helper_stateful_server
*srv
= (helper_stateful_server
*)link
->data
;
555 double tt
= 0.001 * tvSubMsec(srv
->dispatch_time
, srv
->flags
.busy
? current_time
: srv
->answer_time
);
556 storeAppendPrintf(sentry
, "%7u\t%7d\t%7d\t%11" PRIu64
"\t%11" PRIu64
"\t%c%c%c%c%c\t%7.3f\t%7d\t%s\n",
562 srv
->flags
.busy
? 'B' : ' ',
563 srv
->flags
.closing
? 'C' : ' ',
564 srv
->flags
.reserved
? 'R' : ' ',
565 srv
->flags
.shutdown
? 'S' : ' ',
566 srv
->request
? (srv
->request
->placeholder
? 'P' : ' ') : ' ',
569 srv
->request
? Format::QuoteMimeBlob(srv
->request
->buf
) : "(none)");
572 storeAppendPrintf(sentry
, "\nFlags key:\n\n");
573 storeAppendPrintf(sentry
, " B = BUSY\n");
574 storeAppendPrintf(sentry
, " C = CLOSING\n");
575 storeAppendPrintf(sentry
, " R = RESERVED\n");
576 storeAppendPrintf(sentry
, " S = SHUTDOWN PENDING\n");
577 storeAppendPrintf(sentry
, " P = PLACEHOLDER\n");
581 helperShutdown(helper
* hlp
)
583 dlink_node
*link
= hlp
->servers
.head
;
587 srv
= (helper_server
*)link
->data
;
590 if (srv
->flags
.shutdown
) {
591 debugs(84, 3, "helperShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " has already SHUT DOWN.");
595 assert(hlp
->childs
.n_active
> 0);
596 -- hlp
->childs
.n_active
;
597 srv
->flags
.shutdown
= true; /* request it to shut itself down */
599 if (srv
->flags
.closing
) {
600 debugs(84, 3, "helperShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " is CLOSING.");
604 if (srv
->stats
.pending
) {
605 debugs(84, 3, "helperShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " is BUSY.");
609 debugs(84, 3, "helperShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " shutting down.");
610 /* the rest of the details is dealt with in the helperServerFree
613 srv
->closePipesSafely(hlp
->id_name
);
618 helperStatefulShutdown(statefulhelper
* hlp
)
620 dlink_node
*link
= hlp
->servers
.head
;
621 helper_stateful_server
*srv
;
624 srv
= (helper_stateful_server
*)link
->data
;
627 if (srv
->flags
.shutdown
) {
628 debugs(84, 3, "helperStatefulShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " has already SHUT DOWN.");
632 assert(hlp
->childs
.n_active
> 0);
633 -- hlp
->childs
.n_active
;
634 srv
->flags
.shutdown
= true; /* request it to shut itself down */
636 if (srv
->flags
.busy
) {
637 debugs(84, 3, "helperStatefulShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " is BUSY.");
641 if (srv
->flags
.closing
) {
642 debugs(84, 3, "helperStatefulShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " is CLOSING.");
646 if (srv
->flags
.reserved
) {
648 debugs(84, 3, "helperStatefulShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " is RESERVED. Closing anyway.");
650 debugs(84, 3, "helperStatefulShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " is RESERVED. Not Shutting Down Yet.");
655 debugs(84, 3, "helperStatefulShutdown: " << hlp
->id_name
<< " #" << srv
->index
<< " shutting down.");
657 /* the rest of the details is dealt with in the helperStatefulServerFree
660 srv
->closePipesSafely(hlp
->id_name
);
666 /* note, don't free id_name, it probably points to static memory */
669 debugs(84, DBG_CRITICAL
, "WARNING: freeing " << id_name
<< " helper with " << stats
.queue_size
<< " requests queued");
672 /* ====================================================================== */
673 /* LOCAL FUNCTIONS */
674 /* ====================================================================== */
677 helperServerFree(helper_server
*srv
)
679 helper
*hlp
= srv
->parent
;
681 int i
, concurrency
= hlp
->childs
.concurrency
;
687 memFreeBuf(srv
->rbuf_sz
, srv
->rbuf
);
691 srv
->wqueue
->clean();
695 srv
->writebuf
->clean();
696 delete srv
->writebuf
;
697 srv
->writebuf
= NULL
;
700 if (Comm::IsConnOpen(srv
->writePipe
))
701 srv
->closeWritePipeSafely(hlp
->id_name
);
703 dlinkDelete(&srv
->link
, &hlp
->servers
);
705 assert(hlp
->childs
.n_running
> 0);
706 -- hlp
->childs
.n_running
;
708 if (!srv
->flags
.shutdown
) {
709 assert(hlp
->childs
.n_active
> 0);
710 -- hlp
->childs
.n_active
;
711 debugs(84, DBG_CRITICAL
, "WARNING: " << hlp
->id_name
<< " #" << srv
->index
<< " exited");
713 if (hlp
->childs
.needNew() > 0) {
714 debugs(80, DBG_IMPORTANT
, "Too few " << hlp
->id_name
<< " processes are running (need " << hlp
->childs
.needNew() << "/" << hlp
->childs
.n_max
<< ")");
716 if (hlp
->childs
.n_active
< hlp
->childs
.n_startup
&& hlp
->last_restart
> squid_curtime
- 30) {
717 if (srv
->stats
.replies
< 1)
718 fatalf("The %s helpers are crashing too rapidly, need help!\n", hlp
->id_name
);
720 debugs(80, DBG_CRITICAL
, "ERROR: The " << hlp
->id_name
<< " helpers are crashing too rapidly, need help!");
723 debugs(80, DBG_IMPORTANT
, "Starting new helpers");
724 helperOpenServers(hlp
);
728 for (i
= 0; i
< concurrency
; ++i
) {
729 // XXX: re-schedule these on another helper?
730 if ((r
= srv
->requests
[i
])) {
733 if (cbdataReferenceValidDone(r
->data
, &cbdata
)) {
734 HelperReply nilReply
;
735 r
->callback(cbdata
, nilReply
);
738 helperRequestFree(r
);
740 srv
->requests
[i
] = NULL
;
743 safe_free(srv
->requests
);
745 cbdataReferenceDone(srv
->parent
);
750 helperStatefulServerFree(helper_stateful_server
*srv
)
752 statefulhelper
*hlp
= srv
->parent
;
753 helper_stateful_request
*r
;
756 memFreeBuf(srv
->rbuf_sz
, srv
->rbuf
);
761 srv
->wqueue
->clean();
767 /* TODO: walk the local queue of requests and carry them all out */
768 if (Comm::IsConnOpen(srv
->writePipe
))
769 srv
->closeWritePipeSafely(hlp
->id_name
);
771 dlinkDelete(&srv
->link
, &hlp
->servers
);
773 assert(hlp
->childs
.n_running
> 0);
774 -- hlp
->childs
.n_running
;
776 if (!srv
->flags
.shutdown
) {
777 assert( hlp
->childs
.n_active
> 0);
778 -- hlp
->childs
.n_active
;
779 debugs(84, DBG_CRITICAL
, "WARNING: " << hlp
->id_name
<< " #" << srv
->index
<< " exited");
781 if (hlp
->childs
.needNew() > 0) {
782 debugs(80, DBG_IMPORTANT
, "Too few " << hlp
->id_name
<< " processes are running (need " << hlp
->childs
.needNew() << "/" << hlp
->childs
.n_max
<< ")");
784 if (hlp
->childs
.n_active
< hlp
->childs
.n_startup
&& hlp
->last_restart
> squid_curtime
- 30) {
785 if (srv
->stats
.replies
< 1)
786 fatalf("The %s helpers are crashing too rapidly, need help!\n", hlp
->id_name
);
788 debugs(80, DBG_CRITICAL
, "ERROR: The " << hlp
->id_name
<< " helpers are crashing too rapidly, need help!");
791 debugs(80, DBG_IMPORTANT
, "Starting new helpers");
792 helperStatefulOpenServers(hlp
);
796 if ((r
= srv
->request
)) {
799 if (cbdataReferenceValidDone(r
->data
, &cbdata
)) {
800 HelperReply nilReply
;
801 nilReply
.whichServer
= srv
;
802 r
->callback(cbdata
, nilReply
);
805 helperStatefulRequestFree(r
);
810 if (srv
->data
!= NULL
)
811 hlp
->datapool
->freeOne(srv
->data
);
813 cbdataReferenceDone(srv
->parent
);
818 /// Calls back with a pointer to the buffer with the helper output
820 helperReturnBuffer(int request_number
, helper_server
* srv
, helper
* hlp
, char * msg
, char * msg_end
)
822 helper_request
*r
= srv
->requests
[request_number
];
824 HLPCB
*callback
= r
->callback
;
826 srv
->requests
[request_number
] = NULL
;
831 if (cbdataReferenceValidDone(r
->data
, &cbdata
)) {
832 HelperReply
response(msg
, (msg_end
-msg
));
833 callback(cbdata
, response
);
836 -- srv
->stats
.pending
;
837 ++ srv
->stats
.replies
;
839 ++ hlp
->stats
.replies
;
841 srv
->answer_time
= current_time
;
843 srv
->dispatch_time
= r
->dispatch_time
;
845 hlp
->stats
.avg_svc_time
=
846 Math::intAverage(hlp
->stats
.avg_svc_time
,
847 tvSubMsec(r
->dispatch_time
, current_time
),
848 hlp
->stats
.replies
, REDIRECT_AV_FACTOR
);
850 helperRequestFree(r
);
852 debugs(84, DBG_IMPORTANT
, "helperHandleRead: unexpected reply on channel " <<
853 request_number
<< " from " << hlp
->id_name
<< " #" << srv
->index
<<
854 " '" << srv
->rbuf
<< "'");
857 if (!srv
->flags
.shutdown
) {
858 helperKickQueue(hlp
);
859 } else if (!srv
->flags
.closing
&& !srv
->stats
.pending
) {
860 srv
->flags
.closing
=true;
861 srv
->writePipe
->close();
866 helperHandleRead(const Comm::ConnectionPointer
&conn
, char *buf
, size_t len
, Comm::Flag flag
, int xerrno
, void *data
)
869 helper_server
*srv
= (helper_server
*)data
;
870 helper
*hlp
= srv
->parent
;
871 assert(cbdataReferenceValid(data
));
873 /* Bail out early on Comm::ERR_CLOSING - close handlers will tidy up for us */
875 if (flag
== Comm::ERR_CLOSING
) {
879 assert(conn
->fd
== srv
->readPipe
->fd
);
881 debugs(84, 5, "helperHandleRead: " << len
<< " bytes from " << hlp
->id_name
<< " #" << srv
->index
);
883 if (flag
!= Comm::OK
|| len
== 0) {
884 srv
->closePipesSafely(hlp
->id_name
);
889 srv
->rbuf
[srv
->roffset
] = '\0';
890 debugs(84, DBG_DATA
, Raw("accumulated", srv
->rbuf
, srv
->roffset
));
892 if (!srv
->stats
.pending
) {
893 /* someone spoke without being spoken to */
894 debugs(84, DBG_IMPORTANT
, "helperHandleRead: unexpected read from " <<
895 hlp
->id_name
<< " #" << srv
->index
<< ", " << (int)len
<<
896 " bytes '" << srv
->rbuf
<< "'");
902 while ((t
= strchr(srv
->rbuf
, hlp
->eom
))) {
903 /* end of reply found */
904 char *msg
= srv
->rbuf
;
907 debugs(84, 3, "helperHandleRead: end of reply found");
909 if (t
> srv
->rbuf
&& t
[-1] == '\r' && hlp
->eom
== '\n') {
911 // rewind to the \r octet which is the real terminal now
912 // and remember that we have to skip forward 2 places now.
919 if (hlp
->childs
.concurrency
) {
920 i
= strtol(msg
, &msg
, 10);
922 while (*msg
&& xisspace(*msg
))
926 helperReturnBuffer(i
, srv
, hlp
, msg
, t
);
927 srv
->roffset
-= (t
- srv
->rbuf
) + skip
;
928 memmove(srv
->rbuf
, t
+ skip
, srv
->roffset
);
929 srv
->rbuf
[srv
->roffset
] = '\0';
932 if (Comm::IsConnOpen(srv
->readPipe
) && !fd_table
[srv
->readPipe
->fd
].closing()) {
933 int spaceSize
= srv
->rbuf_sz
- srv
->roffset
- 1;
934 assert(spaceSize
>= 0);
936 // grow the input buffer if needed and possible
937 if (!spaceSize
&& srv
->rbuf_sz
+ 4096 <= ReadBufMaxSize
) {
938 srv
->rbuf
= (char *)memReallocBuf(srv
->rbuf
, srv
->rbuf_sz
+ 4096, &srv
->rbuf_sz
);
939 debugs(84, 3, HERE
<< "Grew read buffer to " << srv
->rbuf_sz
);
940 spaceSize
= srv
->rbuf_sz
- srv
->roffset
- 1;
941 assert(spaceSize
>= 0);
944 // quit reading if there is no space left
946 debugs(84, DBG_IMPORTANT
, "ERROR: Disconnecting from a " <<
947 "helper that overflowed " << srv
->rbuf_sz
<< "-byte " <<
948 "Squid input buffer: " << hlp
->id_name
<< " #" << srv
->index
);
949 srv
->closePipesSafely(hlp
->id_name
);
953 AsyncCall::Pointer call
= commCbCall(5,4, "helperHandleRead",
954 CommIoCbPtrFun(helperHandleRead
, srv
));
955 comm_read(srv
->readPipe
, srv
->rbuf
+ srv
->roffset
, spaceSize
, call
);
960 helperStatefulHandleRead(const Comm::ConnectionPointer
&conn
, char *buf
, size_t len
, Comm::Flag flag
, int xerrno
, void *data
)
963 helper_stateful_server
*srv
= (helper_stateful_server
*)data
;
964 helper_stateful_request
*r
;
965 statefulhelper
*hlp
= srv
->parent
;
966 assert(cbdataReferenceValid(data
));
968 /* Bail out early on Comm::ERR_CLOSING - close handlers will tidy up for us */
970 if (flag
== Comm::ERR_CLOSING
) {
974 assert(conn
->fd
== srv
->readPipe
->fd
);
976 debugs(84, 5, "helperStatefulHandleRead: " << len
<< " bytes from " <<
977 hlp
->id_name
<< " #" << srv
->index
);
979 if (flag
!= Comm::OK
|| len
== 0) {
980 srv
->closePipesSafely(hlp
->id_name
);
985 srv
->rbuf
[srv
->roffset
] = '\0';
987 debugs(84, DBG_DATA
, Raw("accumulated", srv
->rbuf
, srv
->roffset
));
990 /* someone spoke without being spoken to */
991 debugs(84, DBG_IMPORTANT
, "helperStatefulHandleRead: unexpected read from " <<
992 hlp
->id_name
<< " #" << srv
->index
<< ", " << (int)len
<<
993 " bytes '" << srv
->rbuf
<< "'");
998 if ((t
= strchr(srv
->rbuf
, hlp
->eom
))) {
999 /* end of reply found */
1002 debugs(84, 3, "helperStatefulHandleRead: end of reply found");
1004 if (t
> srv
->rbuf
&& t
[-1] == '\r' && hlp
->eom
== '\n') {
1006 // rewind to the \r octet which is the real terminal now
1007 // and remember that we have to skip forward 2 places now.
1014 if (r
&& cbdataReferenceValid(r
->data
)) {
1015 HelperReply
res(srv
->rbuf
, (t
- srv
->rbuf
));
1016 res
.whichServer
= srv
;
1017 r
->callback(r
->data
, res
);
1019 debugs(84, DBG_IMPORTANT
, "StatefulHandleRead: no callback data registered");
1022 // only skip off the \0's _after_ passing its location in HelperReply above
1025 srv
->flags
.busy
= false;
1027 * BUG: the below assumes that only one response per read() was received and discards any octets remaining.
1028 * Doing this prohibits concurrency support with multiple replies per read().
1029 * TODO: check that read() setup on these buffers pays attention to roffest!=0
1030 * TODO: check that replies bigger than the buffer are discarded and do not to affect future replies
1033 helperStatefulRequestFree(r
);
1034 srv
->request
= NULL
;
1036 -- srv
->stats
.pending
;
1037 ++ srv
->stats
.replies
;
1039 ++ hlp
->stats
.replies
;
1040 srv
->answer_time
= current_time
;
1041 hlp
->stats
.avg_svc_time
=
1042 Math::intAverage(hlp
->stats
.avg_svc_time
,
1043 tvSubMsec(srv
->dispatch_time
, current_time
),
1044 hlp
->stats
.replies
, REDIRECT_AV_FACTOR
);
1047 helperStatefulServerDone(srv
);
1049 helperStatefulReleaseServer(srv
);
1052 if (Comm::IsConnOpen(srv
->readPipe
) && !fd_table
[srv
->readPipe
->fd
].closing()) {
1053 int spaceSize
= srv
->rbuf_sz
- srv
->roffset
- 1;
1054 assert(spaceSize
>= 0);
1056 // grow the input buffer if needed and possible
1057 if (!spaceSize
&& srv
->rbuf_sz
+ 4096 <= ReadBufMaxSize
) {
1058 srv
->rbuf
= (char *)memReallocBuf(srv
->rbuf
, srv
->rbuf_sz
+ 4096, &srv
->rbuf_sz
);
1059 debugs(84, 3, HERE
<< "Grew read buffer to " << srv
->rbuf_sz
);
1060 spaceSize
= srv
->rbuf_sz
- srv
->roffset
- 1;
1061 assert(spaceSize
>= 0);
1064 // quit reading if there is no space left
1066 debugs(84, DBG_IMPORTANT
, "ERROR: Disconnecting from a " <<
1067 "helper that overflowed " << srv
->rbuf_sz
<< "-byte " <<
1068 "Squid input buffer: " << hlp
->id_name
<< " #" << srv
->index
);
1069 srv
->closePipesSafely(hlp
->id_name
);
1073 AsyncCall::Pointer call
= commCbCall(5,4, "helperStatefulHandleRead",
1074 CommIoCbPtrFun(helperStatefulHandleRead
, srv
));
1075 comm_read(srv
->readPipe
, srv
->rbuf
+ srv
->roffset
, spaceSize
, call
);
1080 Enqueue(helper
* hlp
, helper_request
* r
)
1082 dlink_node
*link
= (dlink_node
*)memAllocate(MEM_DLINK_NODE
);
1083 dlinkAddTail(r
, link
, &hlp
->queue
);
1084 ++ hlp
->stats
.queue_size
;
1086 /* do this first so idle=N has a chance to grow the child pool before it hits critical. */
1087 if (hlp
->childs
.needNew() > 0) {
1088 debugs(84, DBG_CRITICAL
, "Starting new " << hlp
->id_name
<< " helpers...");
1089 helperOpenServers(hlp
);
1093 if (hlp
->stats
.queue_size
< (int)hlp
->childs
.n_running
)
1096 if (squid_curtime
- hlp
->last_queue_warn
< 600)
1099 if (shutting_down
|| reconfiguring
)
1102 hlp
->last_queue_warn
= squid_curtime
;
1104 debugs(84, DBG_CRITICAL
, "WARNING: All " << hlp
->childs
.n_active
<< "/" << hlp
->childs
.n_max
<< " " << hlp
->id_name
<< " processes are busy.");
1105 debugs(84, DBG_CRITICAL
, "WARNING: " << hlp
->stats
.queue_size
<< " pending requests queued");
1106 debugs(84, DBG_CRITICAL
, "WARNING: Consider increasing the number of " << hlp
->id_name
<< " processes in your config file.");
1108 if (hlp
->stats
.queue_size
> (int)hlp
->childs
.n_running
* 2)
1109 fatalf("Too many queued %s requests", hlp
->id_name
);
1113 StatefulEnqueue(statefulhelper
* hlp
, helper_stateful_request
* r
)
1115 dlink_node
*link
= (dlink_node
*)memAllocate(MEM_DLINK_NODE
);
1116 dlinkAddTail(r
, link
, &hlp
->queue
);
1117 ++ hlp
->stats
.queue_size
;
1119 /* do this first so idle=N has a chance to grow the child pool before it hits critical. */
1120 if (hlp
->childs
.needNew() > 0) {
1121 debugs(84, DBG_CRITICAL
, "Starting new " << hlp
->id_name
<< " helpers...");
1122 helperStatefulOpenServers(hlp
);
1126 if (hlp
->stats
.queue_size
< (int)hlp
->childs
.n_running
)
1129 if (hlp
->stats
.queue_size
> (int)hlp
->childs
.n_running
* 2)
1130 fatalf("Too many queued %s requests", hlp
->id_name
);
1132 if (squid_curtime
- hlp
->last_queue_warn
< 600)
1135 if (shutting_down
|| reconfiguring
)
1138 hlp
->last_queue_warn
= squid_curtime
;
1140 debugs(84, DBG_CRITICAL
, "WARNING: All " << hlp
->childs
.n_active
<< "/" << hlp
->childs
.n_max
<< " " << hlp
->id_name
<< " processes are busy.");
1141 debugs(84, DBG_CRITICAL
, "WARNING: " << hlp
->stats
.queue_size
<< " pending requests queued");
1142 debugs(84, DBG_CRITICAL
, "WARNING: Consider increasing the number of " << hlp
->id_name
<< " processes in your config file.");
1145 static helper_request
*
1146 Dequeue(helper
* hlp
)
1149 helper_request
*r
= NULL
;
1151 if ((link
= hlp
->queue
.head
)) {
1152 r
= (helper_request
*)link
->data
;
1153 dlinkDelete(link
, &hlp
->queue
);
1154 memFree(link
, MEM_DLINK_NODE
);
1155 -- hlp
->stats
.queue_size
;
1161 static helper_stateful_request
*
1162 StatefulDequeue(statefulhelper
* hlp
)
1165 helper_stateful_request
*r
= NULL
;
1167 if ((link
= hlp
->queue
.head
)) {
1168 r
= (helper_stateful_request
*)link
->data
;
1169 dlinkDelete(link
, &hlp
->queue
);
1170 memFree(link
, MEM_DLINK_NODE
);
1171 -- hlp
->stats
.queue_size
;
1177 static helper_server
*
1178 GetFirstAvailable(helper
* hlp
)
1182 helper_server
*selected
= NULL
;
1183 debugs(84, 5, "GetFirstAvailable: Running servers " << hlp
->childs
.n_running
);
1185 if (hlp
->childs
.n_running
== 0)
1188 /* Find "least" loaded helper (approx) */
1189 for (n
= hlp
->servers
.head
; n
!= NULL
; n
= n
->next
) {
1190 srv
= (helper_server
*)n
->data
;
1192 if (selected
&& selected
->stats
.pending
<= srv
->stats
.pending
)
1195 if (srv
->flags
.shutdown
)
1198 if (!srv
->stats
.pending
)
1209 /* Check for overload */
1211 debugs(84, 5, "GetFirstAvailable: None available.");
1215 if (selected
->stats
.pending
>= (hlp
->childs
.concurrency
? hlp
->childs
.concurrency
: 1)) {
1216 debugs(84, 3, "GetFirstAvailable: Least-loaded helper is overloaded!");
1220 debugs(84, 5, "GetFirstAvailable: returning srv-" << selected
->index
);
1224 static helper_stateful_server
*
1225 StatefulGetFirstAvailable(statefulhelper
* hlp
)
1228 helper_stateful_server
*srv
= NULL
;
1229 debugs(84, 5, "StatefulGetFirstAvailable: Running servers " << hlp
->childs
.n_running
);
1231 if (hlp
->childs
.n_running
== 0)
1234 for (n
= hlp
->servers
.head
; n
!= NULL
; n
= n
->next
) {
1235 srv
= (helper_stateful_server
*)n
->data
;
1237 if (srv
->flags
.busy
)
1240 if (srv
->flags
.reserved
)
1243 if (srv
->flags
.shutdown
)
1246 if ((hlp
->IsAvailable
!= NULL
) && (srv
->data
!= NULL
) && !(hlp
->IsAvailable(srv
->data
)))
1249 debugs(84, 5, "StatefulGetFirstAvailable: returning srv-" << srv
->index
);
1253 debugs(84, 5, "StatefulGetFirstAvailable: None available.");
1258 helperDispatchWriteDone(const Comm::ConnectionPointer
&conn
, char *buf
, size_t len
, Comm::Flag flag
, int xerrno
, void *data
)
1260 helper_server
*srv
= (helper_server
*)data
;
1262 srv
->writebuf
->clean();
1263 delete srv
->writebuf
;
1264 srv
->writebuf
= NULL
;
1265 srv
->flags
.writing
= false;
1267 if (flag
!= Comm::OK
) {
1268 /* Helper server has crashed */
1269 debugs(84, DBG_CRITICAL
, "helperDispatch: Helper " << srv
->parent
->id_name
<< " #" << srv
->index
<< " has crashed");
1273 if (!srv
->wqueue
->isNull()) {
1274 srv
->writebuf
= srv
->wqueue
;
1275 srv
->wqueue
= new MemBuf
;
1276 srv
->flags
.writing
= true;
1277 AsyncCall::Pointer call
= commCbCall(5,5, "helperDispatchWriteDone",
1278 CommIoCbPtrFun(helperDispatchWriteDone
, srv
));
1279 Comm::Write(srv
->writePipe
, srv
->writebuf
->content(), srv
->writebuf
->contentSize(), call
, NULL
);
1284 helperDispatch(helper_server
* srv
, helper_request
* r
)
1286 helper
*hlp
= srv
->parent
;
1287 helper_request
**ptr
= NULL
;
1290 if (!cbdataReferenceValid(r
->data
)) {
1291 debugs(84, DBG_IMPORTANT
, "helperDispatch: invalid callback data");
1292 helperRequestFree(r
);
1296 for (slot
= 0; slot
< (hlp
->childs
.concurrency
? hlp
->childs
.concurrency
: 1); ++slot
) {
1297 if (!srv
->requests
[slot
]) {
1298 ptr
= &srv
->requests
[slot
];
1305 r
->dispatch_time
= current_time
;
1307 if (srv
->wqueue
->isNull())
1308 srv
->wqueue
->init();
1310 if (hlp
->childs
.concurrency
)
1311 srv
->wqueue
->Printf("%d %s", slot
, r
->buf
);
1313 srv
->wqueue
->append(r
->buf
, strlen(r
->buf
));
1315 if (!srv
->flags
.writing
) {
1316 assert(NULL
== srv
->writebuf
);
1317 srv
->writebuf
= srv
->wqueue
;
1318 srv
->wqueue
= new MemBuf
;
1319 srv
->flags
.writing
= true;
1320 AsyncCall::Pointer call
= commCbCall(5,5, "helperDispatchWriteDone",
1321 CommIoCbPtrFun(helperDispatchWriteDone
, srv
));
1322 Comm::Write(srv
->writePipe
, srv
->writebuf
->content(), srv
->writebuf
->contentSize(), call
, NULL
);
1325 debugs(84, 5, "helperDispatch: Request sent to " << hlp
->id_name
<< " #" << srv
->index
<< ", " << strlen(r
->buf
) << " bytes");
1328 ++ srv
->stats
.pending
;
1329 ++ hlp
->stats
.requests
;
1333 helperStatefulDispatchWriteDone(const Comm::ConnectionPointer
&conn
, char *buf
, size_t len
, Comm::Flag flag
,
1334 int xerrno
, void *data
)
1340 helperStatefulDispatch(helper_stateful_server
* srv
, helper_stateful_request
* r
)
1342 statefulhelper
*hlp
= srv
->parent
;
1344 if (!cbdataReferenceValid(r
->data
)) {
1345 debugs(84, DBG_IMPORTANT
, "helperStatefulDispatch: invalid callback data");
1346 helperStatefulRequestFree(r
);
1347 helperStatefulReleaseServer(srv
);
1351 debugs(84, 9, "helperStatefulDispatch busying helper " << hlp
->id_name
<< " #" << srv
->index
);
1353 if (r
->placeholder
== 1) {
1354 /* a callback is needed before this request can _use_ a helper. */
1355 /* we don't care about releasing this helper. The request NEVER
1356 * gets to the helper. So we throw away the return code */
1357 HelperReply nilReply
;
1358 nilReply
.whichServer
= srv
;
1359 r
->callback(r
->data
, nilReply
);
1360 /* throw away the placeholder */
1361 helperStatefulRequestFree(r
);
1362 /* and push the queue. Note that the callback may have submitted a new
1363 * request to the helper which is why we test for the request */
1365 if (srv
->request
== NULL
)
1366 helperStatefulServerDone(srv
);
1371 srv
->flags
.busy
= true;
1372 srv
->flags
.reserved
= true;
1374 srv
->dispatch_time
= current_time
;
1375 AsyncCall::Pointer call
= commCbCall(5,5, "helperStatefulDispatchWriteDone",
1376 CommIoCbPtrFun(helperStatefulDispatchWriteDone
, hlp
));
1377 Comm::Write(srv
->writePipe
, r
->buf
, strlen(r
->buf
), call
, NULL
);
1378 debugs(84, 5, "helperStatefulDispatch: Request sent to " <<
1379 hlp
->id_name
<< " #" << srv
->index
<< ", " <<
1380 (int) strlen(r
->buf
) << " bytes");
1383 ++ srv
->stats
.pending
;
1384 ++ hlp
->stats
.requests
;
1388 helperKickQueue(helper
* hlp
)
1393 while ((srv
= GetFirstAvailable(hlp
)) && (r
= Dequeue(hlp
)))
1394 helperDispatch(srv
, r
);
1398 helperStatefulKickQueue(statefulhelper
* hlp
)
1400 helper_stateful_request
*r
;
1401 helper_stateful_server
*srv
;
1403 while ((srv
= StatefulGetFirstAvailable(hlp
)) && (r
= StatefulDequeue(hlp
)))
1404 helperStatefulDispatch(srv
, r
);
1408 helperStatefulServerDone(helper_stateful_server
* srv
)
1410 if (!srv
->flags
.shutdown
) {
1411 helperStatefulKickQueue(srv
->parent
);
1412 } else if (!srv
->flags
.closing
&& !srv
->flags
.reserved
&& !srv
->flags
.busy
) {
1413 srv
->closeWritePipeSafely(srv
->parent
->id_name
);
1419 helperRequestFree(helper_request
* r
)
1421 cbdataReferenceDone(r
->data
);
1427 helperStatefulRequestFree(helper_stateful_request
* r
)
1430 cbdataReferenceDone(r
->data
);
1436 // TODO: should helper_ and helper_stateful_ have a common parent?
1438 helperStartStats(StoreEntry
*sentry
, void *hlp
, const char *label
)
1442 storeAppendPrintf(sentry
, "%s: unavailable\n", label
);
1447 storeAppendPrintf(sentry
, "%s:\n", label
);