Add filtered status on ingest_record. Lowered log level on filtered records.
[pazpar2-moved-to-github.git] / src / session.c
1 /* This file is part of Pazpar2.
2    Copyright (C) 2006-2011 Index Data
3
4 Pazpar2 is free software; you can redistribute it and/or modify it under
5 the terms of the GNU General Public License as published by the Free
6 Software Foundation; either version 2, or (at your option) any later
7 version.
8
9 Pazpar2 is distributed in the hope that it will be useful, but WITHOUT ANY
10 WARRANTY; without even the implied warranty of MERCHANTABILITY or
11 FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
12 for more details.
13
14 You should have received a copy of the GNU General Public License
15 along with this program; if not, write to the Free Software
16 Foundation, Inc., 51 Franklin St, Fifth Floor, Boston, MA  02110-1301  USA
17
18 */
19
20 /** \file session.c
21     \brief high-level logic; mostly user sessions and settings
22 */
23
24 #if HAVE_CONFIG_H
25 #include <config.h>
26 #endif
27
28 #include <time.h>
29 #include <stdlib.h>
30 #include <stdio.h>
31 #include <string.h>
32 #if HAVE_SYS_TIME_H
33 #include <sys/time.h>
34 #endif
35 #if HAVE_UNISTD_H
36 #include <unistd.h>
37 #endif
38 #ifdef WIN32
39 #include <windows.h>
40 #endif
41 #include <signal.h>
42 #include <ctype.h>
43 #include <assert.h>
44 #include <math.h>
45
46 #include <yaz/marcdisp.h>
47 #include <yaz/comstack.h>
48 #include <yaz/tcpip.h>
49 #include <yaz/proto.h>
50 #include <yaz/readconf.h>
51 #include <yaz/pquery.h>
52 #include <yaz/otherinfo.h>
53 #include <yaz/yaz-util.h>
54 #include <yaz/nmem.h>
55 #include <yaz/query-charset.h>
56 #include <yaz/querytowrbuf.h>
57 #include <yaz/oid_db.h>
58 #include <yaz/snprintf.h>
59 #include <yaz/gettimeofday.h>
60
61 #define USE_TIMING 0
62 #if USE_TIMING
63 #include <yaz/timing.h>
64 #endif
65
66 #include "ppmutex.h"
67 #include "parameters.h"
68 #include "session.h"
69 #include "eventl.h"
70 #include "http.h"
71 #include "termlists.h"
72 #include "reclists.h"
73 #include "relevance.h"
74 #include "database.h"
75 #include "client.h"
76 #include "settings.h"
77 #include "normalize7bit.h"
78
79 #define TERMLIST_HIGH_SCORE 25
80
81 #define MAX_CHUNK 15
82
83 #define MAX(a,b) ((a)>(b)?(a):(b))
84
85 // Note: Some things in this structure will eventually move to configuration
86 struct parameters global_parameters = 
87 {
88     0,   // dump_records
89     0    // debug_mode
90 };
91
92 struct client_list {
93     struct client *client;
94     struct client_list *next;
95 };
96
97 /* session counting (1) , disable client counting (0) */
98 static YAZ_MUTEX g_session_mutex = 0;
99 static int no_sessions = 0;
100 static int no_session_total = 0;
101
102 static int session_use(int delta)
103 {
104     int sessions;
105     if (!g_session_mutex)
106         yaz_mutex_create(&g_session_mutex);
107     yaz_mutex_enter(g_session_mutex);
108     no_sessions += delta;
109     if (delta > 0)
110         no_session_total += delta;
111     sessions = no_sessions;
112     yaz_mutex_leave(g_session_mutex);
113     yaz_log(YLOG_DEBUG, "%s sesions=%d", delta == 0 ? "" : (delta > 0 ? "INC" : "DEC"), no_sessions);
114     return sessions;
115 }
116
117 int sessions_count(void) {
118     return session_use(0);
119 }
120
121 int  session_count_total(void) {
122     int total = 0;
123     if (!g_session_mutex)
124         return 0;
125     yaz_mutex_enter(g_session_mutex);
126     total = no_session_total;
127     yaz_mutex_leave(g_session_mutex);
128     return total;
129 }
130
131
132 static void log_xml_doc(xmlDoc *doc)
133 {
134     FILE *lf = yaz_log_file();
135     xmlChar *result = 0;
136     int len = 0;
137 #if LIBXML_VERSION >= 20600
138     xmlDocDumpFormatMemory(doc, &result, &len, 1);
139 #else
140     xmlDocDumpMemory(doc, &result, &len);
141 #endif
142     if (lf && len)
143     {
144         (void) fwrite(result, 1, len, lf);
145         fprintf(lf, "\n");
146     }
147     xmlFree(result);
148 }
149
150 static void session_enter(struct session *s)
151 {
152     yaz_mutex_enter(s->session_mutex);
153 }
154
155 static void session_leave(struct session *s)
156 {
157     yaz_mutex_leave(s->session_mutex);
158 }
159
160 // Recursively traverse query structure to extract terms.
161 void pull_terms(NMEM nmem, struct ccl_rpn_node *n, char **termlist, int *num)
162 {
163     char **words;
164     int numwords;
165     int i;
166
167     switch (n->kind)
168     {
169     case CCL_RPN_AND:
170     case CCL_RPN_OR:
171     case CCL_RPN_NOT:
172     case CCL_RPN_PROX:
173         pull_terms(nmem, n->u.p[0], termlist, num);
174         pull_terms(nmem, n->u.p[1], termlist, num);
175         break;
176     case CCL_RPN_TERM:
177         nmem_strsplit(nmem, " ", n->u.t.term, &words, &numwords);
178         for (i = 0; i < numwords; i++)
179             termlist[(*num)++] = words[i];
180         break;
181     default: // NOOP
182         break;
183     }
184 }
185
186
187 void add_facet(struct session *s, const char *type, const char *value, int count)
188 {
189     struct conf_service *service = s->service;
190     pp2_relevance_token_t prt;
191     const char *facet_component;
192     WRBUF facet_wrbuf = wrbuf_alloc();
193     prt = pp2_relevance_tokenize(service->facet_pct);
194     
195     pp2_relevance_first(prt, value, 0);
196     while ((facet_component = pp2_relevance_token_next(prt)))
197     {
198         if (*facet_component)
199         {
200             if (wrbuf_len(facet_wrbuf))
201                 wrbuf_puts(facet_wrbuf, " ");
202             wrbuf_puts(facet_wrbuf, facet_component);
203         }
204     }
205     pp2_relevance_token_destroy(prt);
206     
207     if (wrbuf_len(facet_wrbuf))
208     {
209         int i;
210         for (i = 0; i < s->num_termlists; i++)
211             if (!strcmp(s->termlists[i].name, type))
212                 break;
213         if (i == s->num_termlists)
214         {
215             if (i == SESSION_MAX_TERMLISTS)
216             {
217                 session_log(s, YLOG_FATAL, "Too many termlists");
218                 wrbuf_destroy(facet_wrbuf);
219                 return;
220             }
221             
222             s->termlists[i].name = nmem_strdup(s->nmem, type);
223             s->termlists[i].termlist 
224                 = termlist_create(s->nmem, TERMLIST_HIGH_SCORE);
225             s->num_termlists = i + 1;
226         }
227         
228 #if 0
229         session_log(s, YLOG_DEBUG, "Facets for %s: %s norm:%s (%d)", type, value, wrbuf_cstr(facet_wrbuf), count);
230 #endif
231         termlist_insert(s->termlists[i].termlist, wrbuf_cstr(facet_wrbuf),
232                         count);
233     }
234     wrbuf_destroy(facet_wrbuf);
235 }
236
237 static xmlDoc *record_to_xml(struct session *se,
238                              struct session_database *sdb, const char *rec)
239 {
240     struct database *db = sdb->database;
241     xmlDoc *rdoc = 0;
242
243     rdoc = xmlParseMemory(rec, strlen(rec));
244
245     if (!rdoc)
246     {
247         session_log(se, YLOG_FATAL, "Non-wellformed XML received from %s",
248                     db->url);
249         return 0;
250     }
251
252     if (global_parameters.dump_records)
253     {
254         session_log(se, YLOG_LOG, "Un-normalized record from %s", db->url);
255         log_xml_doc(rdoc);
256     }
257
258     return rdoc;
259 }
260
261 #define MAX_XSLT_ARGS 16
262
263 // Add static values from session database settings if applicable
264 static void insert_settings_parameters(struct session_database *sdb,
265                                        struct conf_service *service,
266                                        char **parms,
267                                        NMEM nmem)
268 {
269     int i;
270     int nparms = 0;
271     int offset = 0;
272
273     for (i = 0; i < service->num_metadata; i++)
274     {
275         struct conf_metadata *md = &service->metadata[i];
276         int setting;
277
278         if (md->setting == Metadata_setting_parameter &&
279             (setting = settings_lookup_offset(service, md->name)) >= 0)
280         {
281             const char *val = session_setting_oneval(sdb, setting);
282             if (val && nparms < MAX_XSLT_ARGS)
283             {
284                 char *buf;
285                 int len = strlen(val);
286                 buf = nmem_malloc(nmem, len + 3);
287                 buf[0] = '\'';
288                 strcpy(buf + 1, val);
289                 buf[len+1] = '\'';
290                 buf[len+2] = '\0';
291                 parms[offset++] = md->name;
292                 parms[offset++] = buf;
293                 nparms++;
294             }
295         }
296     }
297     parms[offset] = 0;
298 }
299
300 // Add static values from session database settings if applicable
301 static void insert_settings_values(struct session_database *sdb, xmlDoc *doc,
302     struct conf_service *service)
303 {
304     int i;
305
306     for (i = 0; i < service->num_metadata; i++)
307     {
308         struct conf_metadata *md = &service->metadata[i];
309         int offset;
310
311         if (md->setting == Metadata_setting_postproc &&
312             (offset = settings_lookup_offset(service, md->name)) >= 0)
313         {
314             const char *val = session_setting_oneval(sdb, offset);
315             if (val)
316             {
317                 xmlNode *r = xmlDocGetRootElement(doc);
318                 xmlNode *n = xmlNewTextChild(r, 0, (xmlChar *) "metadata",
319                                              (xmlChar *) val);
320                 xmlSetProp(n, (xmlChar *) "type", (xmlChar *) md->name);
321             }
322         }
323     }
324 }
325
326 static xmlDoc *normalize_record(struct session *se,
327                                 struct session_database *sdb,
328                                 struct conf_service *service,
329                                 const char *rec, NMEM nmem)
330 {
331     xmlDoc *rdoc = record_to_xml(se, sdb, rec);
332
333     if (rdoc)
334     {
335         char *parms[MAX_XSLT_ARGS*2+1];
336         
337         insert_settings_parameters(sdb, service, parms, nmem);
338         
339         if (normalize_record_transform(sdb->map, &rdoc, (const char **)parms))
340         {
341             session_log(se, YLOG_WARN, "Normalize failed from %s",
342                         sdb->database->url);
343         }
344         else
345         {
346             insert_settings_values(sdb, rdoc, service);
347             
348             if (global_parameters.dump_records)
349             {
350                 session_log(se, YLOG_LOG, "Normalized record from %s", 
351                             sdb->database->url);
352                 log_xml_doc(rdoc);
353             }
354         }
355     }
356     return rdoc;
357 }
358
359 void session_settings_dump(struct session *se,
360                            struct session_database *db,
361                            WRBUF w)
362 {
363     if (db->settings)
364     {
365         int i, num = db->num_settings;
366         for (i = 0; i < num; i++)
367         {
368             struct setting *s = db->settings[i];
369             for (;s ; s = s->next)
370             {
371                 wrbuf_puts(w, "<set name=\"");
372                 wrbuf_xmlputs(w, s->name);
373                 wrbuf_puts(w, "\" value=\"");
374                 wrbuf_xmlputs(w, s->value);
375                 wrbuf_puts(w, "\"/>");
376             }
377             if (db->settings[i])
378                 wrbuf_puts(w, "\n");
379         }
380     }
381 }
382
383 // Retrieve first defined value for 'name' for given database.
384 // Will be extended to take into account user associated with session
385 const char *session_setting_oneval(struct session_database *db, int offset)
386 {
387     if (offset >= db->num_settings || !db->settings[offset])
388         return "";
389     return db->settings[offset]->value;
390 }
391
392 // Prepare XSLT stylesheets for record normalization
393 // Structures are allocated on the session_wide nmem to avoid having
394 // to recompute this for every search. This would lead
395 // to leaking if a single session was to repeatedly change the PZ_XSLT
396 // setting. However, this is not a realistic use scenario.
397 static int prepare_map(struct session *se, struct session_database *sdb)
398 {
399     const char *s;
400
401     if (!sdb->settings)
402     {
403         session_log(se, YLOG_WARN, "No settings on %s", sdb->database->url);
404         return -1;
405     }
406     if ((s = session_setting_oneval(sdb, PZ_XSLT)))
407     {
408         char auto_stylesheet[256];
409
410         if (!strcmp(s, "auto"))
411         {
412             const char *request_syntax = session_setting_oneval(
413                 sdb, PZ_REQUESTSYNTAX);
414             if (request_syntax)
415             {
416                 char *cp;
417                 yaz_snprintf(auto_stylesheet, sizeof(auto_stylesheet),
418                              "%s.xsl", request_syntax);
419                 for (cp = auto_stylesheet; *cp; cp++)
420                 {
421                     /* deliberately only consider ASCII */
422                     if (*cp > 32 && *cp < 127)
423                         *cp = tolower(*cp);
424                 }
425                 s = auto_stylesheet;
426             }
427             else
428             {
429                 session_log(se, YLOG_WARN,
430                             "No pz:requestsyntax for auto stylesheet");
431             }
432         }
433         sdb->map = normalize_cache_get(se->normalize_cache,
434                                        se->service->server->config, s);
435         if (!sdb->map)
436             return -1;
437     }
438     return 0;
439 }
440
441 // This analyzes settings and recomputes any supporting data structures
442 // if necessary.
443 static int prepare_session_database(struct session *se, 
444                                     struct session_database *sdb)
445 {
446     if (!sdb->settings)
447     {
448         session_log(se, YLOG_WARN, 
449                 "No settings associated with %s", sdb->database->url);
450         return -1;
451     }
452     if (sdb->settings[PZ_XSLT] && !sdb->map)
453     {
454         if (prepare_map(se, sdb) < 0)
455             return -1;
456     }
457     return 0;
458 }
459
460 // called if watch should be removed because http_channel is to be destroyed
461 static void session_watch_cancel(void *data, struct http_channel *c,
462                                  void *data2)
463 {
464     struct session_watchentry *ent = data;
465
466     ent->fun = 0;
467     ent->data = 0;
468     ent->obs = 0;
469 }
470
471 // set watch. Returns 0=OK, -1 if watch is already set
472 int session_set_watch(struct session *s, int what, 
473                       session_watchfun fun, void *data,
474                       struct http_channel *chan)
475 {
476     int ret;
477     session_enter(s);
478     if (s->watchlist[what].fun)
479         ret = -1;
480     else
481     {
482         
483         s->watchlist[what].fun = fun;
484         s->watchlist[what].data = data;
485         s->watchlist[what].obs = http_add_observer(chan, &s->watchlist[what],
486                                                    session_watch_cancel);
487         ret = 0;
488     }
489     session_leave(s);
490     return 0;
491 }
492
493 void session_alert_watch(struct session *s, int what)
494 {
495     assert(s);
496     session_enter(s);
497     if (s->watchlist[what].fun)
498     {
499         /* our watch is no longer associated with http_channel */
500         void *data;
501         session_watchfun fun;
502
503         http_remove_observer(s->watchlist[what].obs);
504         fun  = s->watchlist[what].fun;
505         data = s->watchlist[what].data;
506
507         /* reset watch before fun is invoked - in case fun wants to set
508            it again */
509         s->watchlist[what].fun = 0;
510         s->watchlist[what].data = 0;
511         s->watchlist[what].obs = 0;
512
513         session_leave(s);
514         session_log(s, YLOG_DEBUG,
515                     "Alert Watch: %d calling function: %p", what, fun);
516         fun(data);
517     }
518     else
519         session_leave(s);
520 }
521
522 //callback for grep_databases
523 static void select_targets_callback(void *context, struct session_database *db)
524 {
525     struct session *se = (struct session*) context;
526     struct client *cl = client_create();
527     struct client_list *l;
528     client_set_database(cl, db);
529
530     client_set_session(cl, se);
531
532     l = xmalloc(sizeof(*l));
533     l->client = cl;
534     l->next = se->clients;
535     se->clients = l;
536 }
537
538 static void session_remove_clients(struct session *se)
539 {
540     struct client_list *l;
541
542     session_enter(se);
543     l = se->clients;
544     se->clients = 0;
545     session_leave(se);
546
547     while (l)
548     {
549         struct client_list *l_next = l->next;
550         client_lock(l->client);
551         client_set_session(l->client, 0);
552         client_set_database(l->client, 0);
553         client_unlock(l->client);
554         client_destroy(l->client);
555         xfree(l);
556         l = l_next;
557     }
558 }
559
560 // Associates a set of clients with a session;
561 // Note: Session-databases represent databases with per-session 
562 // setting overrides
563 static int select_targets(struct session *se, const char *filter)
564 {
565     return session_grep_databases(se, filter, select_targets_callback);
566 }
567
568 int session_active_clients(struct session *s)
569 {
570     struct client_list *l;
571     int res = 0;
572
573     for (l = s->clients; l; l = l->next)
574         if (client_is_active(l->client))
575             res++;
576
577     return res;
578 }
579
580 int session_is_preferred_clients_ready(struct session *s)
581 {
582     struct client_list *l;
583     int res = 0;
584
585     for (l = s->clients; l; l = l->next)
586         if (client_is_active_preferred(l->client))
587             res++;
588     session_log(s, YLOG_DEBUG, "Has %d active preferred clients.", res);
589     return res == 0;
590 }
591
592
593
594 enum pazpar2_error_code search(struct session *se,
595                                const char *query,
596                                const char *startrecs, const char *maxrecs,
597                                const char *filter,
598                                const char **addinfo)
599 {
600     int live_channels = 0;
601     int no_working = 0;
602     int no_failed = 0;
603     struct client_list *l;
604     struct timeval tval;
605
606     session_log(se, YLOG_DEBUG, "Search");
607
608     *addinfo = 0;
609
610     session_remove_clients(se);
611     
612     session_enter(se);
613     reclist_destroy(se->reclist);
614     se->reclist = 0;
615     relevance_destroy(&se->relevance);
616     nmem_reset(se->nmem);
617     se->total_records = se->total_hits = se->total_merged = 0;
618     se->num_termlists = 0;
619     live_channels = select_targets(se, filter);
620     if (!live_channels)
621     {
622         session_leave(se);
623         return PAZPAR2_NO_TARGETS;
624     }
625     se->reclist = reclist_create(se->nmem);
626
627     yaz_gettimeofday(&tval);
628     
629     tval.tv_sec += 5;
630
631     for (l = se->clients; l; l = l->next)
632     {
633         struct client *cl = l->client;
634
635         if (maxrecs)
636             client_set_maxrecs(cl, atoi(maxrecs));
637         if (startrecs)
638             client_set_startrecs(cl, atoi(startrecs));
639         if (prepare_session_database(se, client_get_database(cl)) < 0)
640             ;
641         else if (client_parse_query(cl, query) < 0)
642             no_failed++;
643         else
644         {
645             no_working++;
646             if (client_prep_connection(cl, se->service->z3950_operation_timeout,
647                                        se->service->z3950_session_timeout,
648                                        se->service->server->iochan_man,
649                                        &tval))
650                 client_start_search(cl);
651         }
652     }
653     session_leave(se);
654     if (no_working == 0)
655     {
656         if (no_failed > 0)
657         {
658             *addinfo = "query";
659             return PAZPAR2_MALFORMED_PARAMETER_VALUE;
660         }
661         else
662             return PAZPAR2_NO_TARGETS;
663     }
664     return PAZPAR2_NO_ERROR;
665 }
666
667 // Creates a new session_database object for a database
668 static void session_init_databases_fun(void *context, struct database *db)
669 {
670     struct session *se = (struct session *) context;
671     struct session_database *new = nmem_malloc(se->session_nmem, sizeof(*new));
672     int i;
673
674     new->database = db;
675     
676     new->map = 0;
677     assert(db->settings);
678     new->settings = nmem_malloc(se->session_nmem,
679                                 sizeof(struct settings *) * db->num_settings);
680     new->num_settings = db->num_settings;
681     for (i = 0; i < db->num_settings; i++)
682     {
683         struct setting *setting = db->settings[i];
684         new->settings[i] = setting;
685     }
686     new->next = se->databases;
687     se->databases = new;
688 }
689
690 // Doesn't free memory associated with sdb -- nmem takes care of that
691 static void session_database_destroy(struct session_database *sdb)
692 {
693     sdb->map = 0;
694 }
695
696 // Initialize session_database list -- this represents this session's view
697 // of the database list -- subject to modification by the settings ws command
698 void session_init_databases(struct session *se)
699 {
700     se->databases = 0;
701     predef_grep_databases(se, se->service, session_init_databases_fun);
702 }
703
704 // Probably session_init_databases_fun should be refactored instead of
705 // called here.
706 static struct session_database *load_session_database(struct session *se, 
707                                                       char *id)
708 {
709     struct database *db = new_database(id, se->session_nmem);
710
711     resolve_database(se->service, db);
712
713     session_init_databases_fun((void*) se, db);
714
715     // New sdb is head of se->databases list
716     return se->databases;
717 }
718
719 // Find an existing session database. If not found, load it
720 static struct session_database *find_session_database(struct session *se, 
721                                                       char *id)
722 {
723     struct session_database *sdb;
724
725     for (sdb = se->databases; sdb; sdb = sdb->next)
726         if (!strcmp(sdb->database->url, id))
727             return sdb;
728     return load_session_database(se, id);
729 }
730
731 // Apply a session override to a database
732 void session_apply_setting(struct session *se, char *dbname, char *setting,
733                            char *value)
734 {
735     struct session_database *sdb = find_session_database(se, dbname);
736     struct conf_service *service = se->service;
737     struct setting *new = nmem_malloc(se->session_nmem, sizeof(*new));
738     int offset = settings_create_offset(service, setting);
739
740     expand_settings_array(&sdb->settings, &sdb->num_settings, offset,
741                           se->session_nmem);
742     new->precedence = 0;
743     new->target = dbname;
744     new->name = setting;
745     new->value = value;
746     new->next = sdb->settings[offset];
747     sdb->settings[offset] = new;
748
749     // Force later recompute of settings-driven data structures
750     // (happens when a search starts and client connections are prepared)
751     switch (offset)
752     {
753     case PZ_XSLT:
754         if (sdb->map)
755         {
756             sdb->map = 0;
757         }
758         break;
759     }
760 }
761
762 /* Depreciated: use session_destroy */
763 void destroy_session(struct session *se)
764 {
765     session_destroy(se);
766 }
767
768 void session_destroy(struct session *se) {
769     struct session_database *sdb;
770     session_log(se, YLOG_DEBUG, "Destroying");
771     session_use(-1);
772     session_remove_clients(se);
773
774     for (sdb = se->databases; sdb; sdb = sdb->next)
775         session_database_destroy(sdb);
776     normalize_cache_destroy(se->normalize_cache);
777     relevance_destroy(&se->relevance);
778     reclist_destroy(se->reclist);
779     nmem_destroy(se->nmem);
780     service_destroy(se->service);
781     yaz_mutex_destroy(&se->session_mutex);
782     wrbuf_destroy(se->wrbuf);
783
784 }
785
786
787 size_t session_get_memory_status(struct session *session) {
788     size_t session_nmem;
789     if (session == 0)
790         return 0;
791     session_enter(session);
792     session_nmem = nmem_total(session->nmem);
793     session_leave(session);
794     return session_nmem;
795 }
796
797
798 struct session *new_session(NMEM nmem, struct conf_service *service,
799                             unsigned session_id)
800 {
801     int i;
802     struct session *session = nmem_malloc(nmem, sizeof(*session));
803
804     char tmp_str[50];
805
806     sprintf(tmp_str, "session#%u", session_id);
807
808     session->session_id = session_id;
809     session_log(session, YLOG_DEBUG, "New");
810     session->service = service;
811     session->relevance = 0;
812     session->total_hits = 0;
813     session->total_records = 0;
814     session->number_of_warnings_unknown_elements = 0;
815     session->number_of_warnings_unknown_metadata = 0;
816     session->num_termlists = 0;
817     session->reclist = 0;
818     session->clients = 0;
819     session->session_nmem = nmem;
820     session->nmem = nmem_create();
821     session->wrbuf = wrbuf_alloc();
822     session->databases = 0;
823     for (i = 0; i <= SESSION_WATCH_MAX; i++)
824     {
825         session->watchlist[i].data = 0;
826         session->watchlist[i].fun = 0;
827     }
828     session->normalize_cache = normalize_cache_create();
829     session->session_mutex = 0;
830     pazpar2_mutex_create(&session->session_mutex, tmp_str);
831     session_use(1);
832     return session;
833 }
834
835 struct hitsbytarget *hitsbytarget(struct session *se, int *count, NMEM nmem)
836 {
837     struct hitsbytarget *res = 0;
838     struct client_list *l;
839     size_t sz = 0;
840
841     session_enter(se);
842     for (l = se->clients; l; l = l->next)
843         sz++;
844
845     res = nmem_malloc(nmem, sizeof(*res) * sz);
846     *count = 0;
847     for (l = se->clients; l; l = l->next)
848     {
849         struct client *cl = l->client;
850         WRBUF w = wrbuf_alloc();
851         const char *name = session_setting_oneval(client_get_database(cl),
852                                                   PZ_NAME);
853
854         res[*count].id = client_get_database(cl)->database->url;
855         res[*count].name = *name ? name : "Unknown";
856         res[*count].hits = client_get_hits(cl);
857         res[*count].records = client_get_num_records(cl);
858         res[*count].diagnostic = client_get_diagnostic(cl);
859         res[*count].state = client_get_state_str(cl);
860         res[*count].connected  = client_get_connection(cl) ? 1 : 0;
861         session_settings_dump(se, client_get_database(cl), w);
862         res[*count].settings_xml = w;
863         (*count)++;
864     }
865     session_leave(se);
866     return res;
867 }
868
869 struct termlist_score **termlist(struct session *se, const char *name, int *num)
870 {
871     int i;
872     struct termlist_score **tl = 0;
873
874     session_enter(se);
875     for (i = 0; i < se->num_termlists; i++)
876         if (!strcmp((const char *) se->termlists[i].name, name))
877         {
878             tl = termlist_highscore(se->termlists[i].termlist, num);
879             break;
880         }
881     session_leave(se);
882     return tl;
883 }
884
885 #ifdef MISSING_HEADERS
886 void report_nmem_stats(void)
887 {
888     size_t in_use, is_free;
889
890     nmem_get_memory_in_use(&in_use);
891     nmem_get_memory_free(&is_free);
892
893     yaz_log(YLOG_LOG, "nmem stat: use=%ld free=%ld", 
894             (long) in_use, (long) is_free);
895 }
896 #endif
897
898 struct record_cluster *show_single_start(struct session *se, const char *id,
899                                          struct record_cluster **prev_r,
900                                          struct record_cluster **next_r)
901 {
902     struct record_cluster *r = 0;
903
904     session_enter(se);
905     *prev_r = 0;
906     *next_r = 0;
907     if (se->reclist)
908     {
909         reclist_enter(se->reclist);
910         while ((r = reclist_read_record(se->reclist)))
911         {
912             if (!strcmp(r->recid, id))
913             {
914                 *next_r = reclist_read_record(se->reclist);
915                 break;
916             }
917             *prev_r = r;
918         }
919         reclist_leave(se->reclist);
920     }
921     if (!r)
922         session_leave(se);
923     return r;
924 }
925
926 void show_single_stop(struct session *se, struct record_cluster *rec)
927 {
928     session_leave(se);
929 }
930
931 struct record_cluster **show_range_start(struct session *se,
932                                          struct reclist_sortparms *sp, 
933                                          int start, int *num, int *total, Odr_int *sumhits)
934 {
935     struct record_cluster **recs;
936     struct reclist_sortparms *spp;
937     int i;
938 #if USE_TIMING    
939     yaz_timing_t t = yaz_timing_create();
940 #endif
941     session_enter(se);
942     recs = nmem_malloc(se->nmem, *num * sizeof(struct record_cluster *));
943     if (!se->relevance)
944     {
945         *num = 0;
946         *total = 0;
947         *sumhits = 0;
948         recs = 0;
949     }
950     else
951     {
952         for (spp = sp; spp; spp = spp->next)
953             if (spp->type == Metadata_sortkey_relevance)
954             {
955                 relevance_prepare_read(se->relevance, se->reclist);
956                 break;
957             }
958         reclist_sort(se->reclist, sp);
959         
960         reclist_enter(se->reclist);
961         *total = reclist_get_num_records(se->reclist);
962         *sumhits = se->total_hits;
963         
964         for (i = 0; i < start; i++)
965             if (!reclist_read_record(se->reclist))
966             {
967                 *num = 0;
968                 recs = 0;
969                 break;
970             }
971         
972         for (i = 0; i < *num; i++)
973         {
974             struct record_cluster *r = reclist_read_record(se->reclist);
975             if (!r)
976             {
977                 *num = i;
978                 break;
979             }
980             recs[i] = r;
981         }
982         reclist_leave(se->reclist);
983     }
984 #if USE_TIMING
985     yaz_timing_stop(t);
986     yaz_log(YLOG_LOG, "show %6.5f %3.2f %3.2f", 
987             yaz_timing_get_real(t), yaz_timing_get_user(t),
988             yaz_timing_get_sys(t));
989     yaz_timing_destroy(&t);
990 #endif
991     return recs;
992 }
993
994 void show_range_stop(struct session *se, struct record_cluster **recs)
995 {
996     session_leave(se);
997 }
998
999 void statistics(struct session *se, struct statistics *stat)
1000 {
1001     struct client_list *l;
1002     int count = 0;
1003
1004     memset(stat, 0, sizeof(*stat));
1005     for (l = se->clients; l; l = l->next)
1006     {
1007         struct client *cl = l->client;
1008         if (!client_get_connection(cl))
1009             stat->num_no_connection++;
1010         switch (client_get_state(cl))
1011         {
1012         case Client_Connecting: stat->num_connecting++; break;
1013         case Client_Working: stat->num_working++; break;
1014         case Client_Idle: stat->num_idle++; break;
1015         case Client_Failed: stat->num_failed++; break;
1016         case Client_Error: stat->num_error++; break;
1017         default: break;
1018         }
1019         count++;
1020     }
1021     stat->num_hits = se->total_hits;
1022     stat->num_records = se->total_records;
1023
1024     stat->num_clients = count;
1025 }
1026
1027 static struct record_metadata *record_metadata_init(
1028     NMEM nmem, const char *value, enum conf_metadata_type type,
1029     struct _xmlAttr *attr)
1030 {
1031     struct record_metadata *rec_md = record_metadata_create(nmem);
1032     struct record_metadata_attr **attrp = &rec_md->attributes;
1033     
1034     for (; attr; attr = attr->next)
1035     {
1036         if (attr->children && attr->children->content)
1037         {
1038             if (strcmp((const char *) attr->name, "type"))
1039             {  /* skip the "type" attribute.. Its value is already part of
1040                   the element in output (md-%s) and so repeating it here
1041                   is redundant */
1042                 *attrp = nmem_malloc(nmem, sizeof(**attrp));
1043                 (*attrp)->name =
1044                     nmem_strdup(nmem, (const char *) attr->name);
1045                 (*attrp)->value =
1046                     nmem_strdup(nmem, (const char *) attr->children->content);
1047                 attrp = &(*attrp)->next;
1048             }
1049         }
1050     }
1051     *attrp = 0;
1052
1053     if (type == Metadata_type_generic)
1054     {
1055         char *p = nmem_strdup(nmem, value);
1056
1057         p = normalize7bit_generic(p, " ,/.:([");
1058         
1059         rec_md->data.text.disp = p;
1060         rec_md->data.text.sort = 0;
1061     }
1062     else if (type == Metadata_type_year || type == Metadata_type_date)
1063     {
1064         int first, last;
1065         int longdate = 0;
1066
1067         if (type == Metadata_type_date)
1068             longdate = 1;
1069         if (extract7bit_dates((char *) value, &first, &last, longdate) < 0)
1070             return 0;
1071
1072         rec_md->data.number.min = first;
1073         rec_md->data.number.max = last;
1074     }
1075     else
1076         return 0;
1077     return rec_md;
1078 }
1079
1080 static int get_mergekey_from_doc(xmlDoc *doc, xmlNode *root, const char *name,
1081                                  struct conf_service *service, WRBUF norm_wr)
1082 {
1083     xmlNode *n;
1084     int no_found = 0;
1085     for (n = root->children; n; n = n->next)
1086     {
1087         if (n->type != XML_ELEMENT_NODE)
1088             continue;
1089         if (!strcmp((const char *) n->name, "metadata"))
1090         {
1091             xmlChar *type = xmlGetProp(n, (xmlChar *) "type");
1092             if (type == NULL) {
1093                 yaz_log(YLOG_FATAL, "Missing type attribute on metadata element. Skipping!");
1094             }
1095             else if (!strcmp(name, (const char *) type))
1096             {
1097                 xmlChar *value = xmlNodeListGetString(doc, n->children, 1);
1098                 if (value)
1099                 {
1100                     const char *norm_str;
1101                     pp2_relevance_token_t prt =
1102                         pp2_relevance_tokenize(service->mergekey_pct);
1103                     
1104                     pp2_relevance_first(prt, (const char *) value, 0);
1105                     if (wrbuf_len(norm_wr) > 0)
1106                         wrbuf_puts(norm_wr, " ");
1107                     wrbuf_puts(norm_wr, name);
1108                     while ((norm_str =
1109                             pp2_relevance_token_next(prt)))
1110                     {
1111                         if (*norm_str)
1112                         {
1113                             wrbuf_puts(norm_wr, " ");
1114                             wrbuf_puts(norm_wr, norm_str);
1115                         }
1116                     }
1117                     xmlFree(value);
1118                     pp2_relevance_token_destroy(prt);
1119                     no_found++;
1120                 }
1121             }
1122             xmlFree(type);
1123         }
1124     }
1125     return no_found;
1126 }
1127
1128 static const char *get_mergekey(xmlDoc *doc, struct client *cl, int record_no,
1129                                 struct conf_service *service, NMEM nmem)
1130 {
1131     char *mergekey_norm = 0;
1132     xmlNode *root = xmlDocGetRootElement(doc);
1133     WRBUF norm_wr = wrbuf_alloc();
1134
1135     /* consider mergekey from XSL first */
1136     xmlChar *mergekey = xmlGetProp(root, (xmlChar *) "mergekey");
1137     if (mergekey)
1138     {
1139         const char *norm_str;
1140         pp2_relevance_token_t prt =
1141             pp2_relevance_tokenize(service->mergekey_pct);
1142
1143         pp2_relevance_first(prt, (const char *) mergekey, 0);
1144         while ((norm_str = pp2_relevance_token_next(prt)))
1145         {
1146             if (*norm_str)
1147             {
1148                 if (wrbuf_len(norm_wr))
1149                     wrbuf_puts(norm_wr, " ");
1150                 wrbuf_puts(norm_wr, norm_str);
1151             }
1152         }
1153         pp2_relevance_token_destroy(prt);
1154         xmlFree(mergekey);
1155     }
1156     else
1157     {
1158         /* no mergekey defined in XSL. Look for mergekey metadata instead */
1159         int field_id;
1160         for (field_id = 0; field_id < service->num_metadata; field_id++)
1161         {
1162             struct conf_metadata *ser_md = &service->metadata[field_id];
1163             if (ser_md->mergekey != Metadata_mergekey_no)
1164             {
1165                 int r = get_mergekey_from_doc(doc, root, ser_md->name,
1166                                               service, norm_wr);
1167                 if (r == 0 && ser_md->mergekey == Metadata_mergekey_required)
1168                 {
1169                     /* no mergekey on this one and it is required.. 
1170                        Generate unique key instead */
1171                     wrbuf_rewind(norm_wr);
1172                     break;
1173                 }
1174             }
1175         }
1176     }
1177
1178     /* generate unique key if none is not generated already or is empty */
1179     if (wrbuf_len(norm_wr) == 0)
1180     {
1181         wrbuf_printf(norm_wr, "%s-%d",
1182                      client_get_database(cl)->database->url, record_no);
1183     }
1184     if (wrbuf_len(norm_wr) > 0)
1185         mergekey_norm = nmem_strdup(nmem, wrbuf_cstr(norm_wr));
1186     wrbuf_destroy(norm_wr);
1187     return mergekey_norm;
1188 }
1189
1190 /** \brief see if metadata for pz:recordfilter exists 
1191     \param root xml root element of normalized record
1192     \param sdb session database for client
1193     \retval 0 if there is no metadata for pz:recordfilter
1194     \retval 1 if there is metadata for pz:recordfilter
1195
1196     If there is no pz:recordfilter defined, this function returns 1
1197     as well.
1198 */
1199     
1200 static int check_record_filter(xmlNode *root, struct session_database *sdb)
1201 {
1202     int match = 0;
1203     xmlNode *n;
1204     const char *s;
1205     s = session_setting_oneval(sdb, PZ_RECORDFILTER);
1206
1207     if (!s || !*s)
1208         return 1;
1209
1210     for (n = root->children; n; n = n->next)
1211     {
1212         if (n->type != XML_ELEMENT_NODE)
1213             continue;
1214         if (!strcmp((const char *) n->name, "metadata"))
1215         {
1216             xmlChar *type = xmlGetProp(n, (xmlChar *) "type");
1217             if (type)
1218             {
1219                 size_t len;
1220                 int substring;
1221                 const char *eq;
1222
1223                 if ((eq = strchr(s, '=')))
1224                     substring = 0;
1225                 else if ((eq = strchr(s, '~')))
1226                     substring = 1;
1227                 if (eq)
1228                     len = eq - s;
1229                 else
1230                     len = strlen(s);
1231                 if (len == strlen((const char *)type) &&
1232                     !memcmp((const char *) type, s, len))
1233                 {
1234                     xmlChar *value = xmlNodeGetContent(n);
1235                     if (value && *value)
1236                     {
1237                         if (!eq ||
1238                             (substring && strstr((const char *) value, eq+1)) ||
1239                             (!substring && !strcmp((const char *) value, eq + 1)))
1240                             match = 1;
1241                     }
1242                     xmlFree(value);
1243                 }
1244                 xmlFree(type);
1245             }
1246         }
1247     }
1248     return match;
1249 }
1250
1251
1252 static int ingest_to_cluster(struct client *cl,
1253                              xmlDoc *xdoc,
1254                              xmlNode *root,
1255                              int record_no,
1256                              const char *mergekey_norm);
1257
1258 /** \brief ingest XML record
1259     \param cl client holds the result set for record
1260     \param rec record buffer (0 terminated)
1261     \param record_no record position (1, 2, ..)
1262     \param nmem working NMEM
1263     \retval 0 OK
1264     \retval -1 failure
1265     \retval -2 Filtered
1266 */
1267 int ingest_record(struct client *cl, const char *rec,
1268                   int record_no, NMEM nmem)
1269 {
1270     struct session *se = client_get_session(cl);
1271     int ret = 0;
1272     struct session_database *sdb = client_get_database(cl);
1273     struct conf_service *service = se->service;
1274     xmlDoc *xdoc = normalize_record(se, sdb, service, rec, nmem);
1275     xmlNode *root;
1276     const char *mergekey_norm;
1277     
1278     if (!xdoc)
1279         return -1;
1280     
1281     root = xmlDocGetRootElement(xdoc);
1282     
1283     if (!check_record_filter(root, sdb))
1284     {
1285         session_log(se, YLOG_LOG, "Filtered out record no %d from %s",
1286                     record_no, sdb->database->url);
1287         xmlFreeDoc(xdoc);
1288         return -2;
1289     }
1290     
1291     mergekey_norm = get_mergekey(xdoc, cl, record_no, service, nmem);
1292     if (!mergekey_norm)
1293     {
1294         session_log(se, YLOG_WARN, "Got no mergekey");
1295         xmlFreeDoc(xdoc);
1296         return -1;
1297     }
1298     session_enter(se);
1299     if (client_get_session(cl) == se)
1300         ret = ingest_to_cluster(cl, xdoc, root, record_no, mergekey_norm);
1301     session_leave(se);
1302     
1303     xmlFreeDoc(xdoc);
1304     return ret;
1305 }
1306
1307 static int ingest_to_cluster(struct client *cl,
1308                              xmlDoc *xdoc,
1309                              xmlNode *root,
1310                              int record_no,
1311                              const char *mergekey_norm)
1312 {
1313     xmlNode *n;
1314     xmlChar *type = 0;
1315     xmlChar *value = 0;
1316     struct session_database *sdb = client_get_database(cl);
1317     struct session *se = client_get_session(cl);
1318     struct conf_service *service = se->service;
1319     struct record *record = record_create(se->nmem, 
1320                                           service->num_metadata,
1321                                           service->num_sortkeys, cl,
1322                                           record_no);
1323     struct record_cluster *cluster = reclist_insert(se->reclist,
1324                                                     service, 
1325                                                     record,
1326                                                     mergekey_norm,
1327                                                     &se->total_merged);
1328
1329     const char *use_term_factor_str = session_setting_oneval(sdb, PZ_TERMLIST_TERM_FACTOR);
1330     int use_term_factor = 0;
1331     int term_factor = 1; 
1332     if (use_term_factor_str && use_term_factor_str[0] != 0)
1333        use_term_factor =  atoi(use_term_factor_str);
1334     if (use_term_factor) {
1335         int maxrecs = client_get_maxrecs(cl);
1336         int hits = (int) client_get_hits(cl);
1337         term_factor = MAX(hits, maxrecs) /  MAX(1, maxrecs);
1338         assert(term_factor >= 1);
1339         yaz_log(YLOG_DEBUG, "Using term factor: %d (%d / %d)", term_factor, MAX(hits, maxrecs), MAX(1, maxrecs));
1340     }
1341
1342     if (!cluster)
1343         return -1;
1344     if (global_parameters.dump_records)
1345         session_log(se, YLOG_LOG, "Cluster id %s from %s (#%d)", cluster->recid,
1346                     sdb->database->url, record_no);
1347     relevance_newrec(se->relevance, cluster);
1348     
1349     // now parsing XML record and adding data to cluster or record metadata
1350     for (n = root->children; n; n = n->next)
1351     {
1352         pp2_relevance_token_t prt;
1353         if (type)
1354             xmlFree(type);
1355         if (value)
1356             xmlFree(value);
1357         type = value = 0;
1358         
1359         if (n->type != XML_ELEMENT_NODE)
1360             continue;
1361         if (!strcmp((const char *) n->name, "metadata"))
1362         {
1363             struct conf_metadata *ser_md = 0;
1364             struct conf_sortkey *ser_sk = 0;
1365             struct record_metadata **wheretoput = 0;
1366             struct record_metadata *rec_md = 0;
1367             int md_field_id = -1;
1368             int sk_field_id = -1;
1369             
1370             type = xmlGetProp(n, (xmlChar *) "type");
1371             value = xmlNodeListGetString(xdoc, n->children, 1);
1372             
1373             if (!type || !value || !*value)
1374                 continue;
1375             
1376             md_field_id 
1377                 = conf_service_metadata_field_id(service, (const char *) type);
1378             if (md_field_id < 0)
1379             {
1380                 if (se->number_of_warnings_unknown_metadata == 0)
1381                 {
1382                     session_log(se, YLOG_WARN, 
1383                             "Ignoring unknown metadata element: %s", type);
1384                 }
1385                 se->number_of_warnings_unknown_metadata++;
1386                 continue;
1387             }
1388             
1389             ser_md = &service->metadata[md_field_id];
1390             
1391             if (ser_md->sortkey_offset >= 0){
1392                 sk_field_id = ser_md->sortkey_offset;
1393                 ser_sk = &service->sortkeys[sk_field_id];
1394             }
1395
1396             // non-merged metadata
1397             rec_md = record_metadata_init(se->nmem, (const char *) value,
1398                                           ser_md->type, n->properties);
1399             if (!rec_md)
1400             {
1401                 session_log(se, YLOG_WARN, "bad metadata data '%s' "
1402                             "for element '%s'", value, type);
1403                 continue;
1404             }
1405             wheretoput = &record->metadata[md_field_id];
1406             while (*wheretoput)
1407                 wheretoput = &(*wheretoput)->next;
1408             *wheretoput = rec_md;
1409
1410             // merged metadata
1411             rec_md = record_metadata_init(se->nmem, (const char *) value,
1412                                           ser_md->type, 0);
1413             wheretoput = &cluster->metadata[md_field_id];
1414
1415             // and polulate with data:
1416             // assign cluster or record based on merge action
1417             if (ser_md->merge == Metadata_merge_unique)
1418             {
1419                 struct record_metadata *mnode;
1420                 for (mnode = *wheretoput; mnode; mnode = mnode->next)
1421                     if (!strcmp((const char *) mnode->data.text.disp, 
1422                                 rec_md->data.text.disp))
1423                         break;
1424                 if (!mnode)
1425                 {
1426                     rec_md->next = *wheretoput;
1427                     *wheretoput = rec_md;
1428                 }
1429             }
1430             else if (ser_md->merge == Metadata_merge_longest)
1431             {
1432                 if (!*wheretoput 
1433                     || strlen(rec_md->data.text.disp) 
1434                     > strlen((*wheretoput)->data.text.disp))
1435                 {
1436                     *wheretoput = rec_md;
1437                     if (ser_sk)
1438                     {
1439                         const char *sort_str = 0;
1440                         int skip_article = 
1441                             ser_sk->type == Metadata_sortkey_skiparticle;
1442
1443                         if (!cluster->sortkeys[sk_field_id])
1444                             cluster->sortkeys[sk_field_id] = 
1445                                 nmem_malloc(se->nmem, 
1446                                             sizeof(union data_types));
1447                          
1448                         prt = pp2_relevance_tokenize(service->sort_pct);
1449
1450                         pp2_relevance_first(prt, rec_md->data.text.disp,
1451                                             skip_article);
1452
1453                         pp2_relevance_token_next(prt);
1454                          
1455                         sort_str = pp2_get_sort(prt);
1456                          
1457                         cluster->sortkeys[sk_field_id]->text.disp = 
1458                             rec_md->data.text.disp;
1459                         if (!sort_str)
1460                         {
1461                             sort_str = rec_md->data.text.disp;
1462                             session_log(se, YLOG_WARN, 
1463                                     "Could not make sortkey. Bug #1858");
1464                         }
1465                         cluster->sortkeys[sk_field_id]->text.sort = 
1466                             nmem_strdup(se->nmem, sort_str);
1467                         pp2_relevance_token_destroy(prt);
1468                     }
1469                 }
1470             }
1471             else if (ser_md->merge == Metadata_merge_all)
1472             {
1473                 rec_md->next = *wheretoput;
1474                 *wheretoput = rec_md;
1475             }
1476             else if (ser_md->merge == Metadata_merge_range)
1477             {
1478                 if (!*wheretoput)
1479                 {
1480                     *wheretoput = rec_md;
1481                     if (ser_sk)
1482                         cluster->sortkeys[sk_field_id] 
1483                             = &rec_md->data;
1484                 }
1485                 else
1486                 {
1487                     int this_min = rec_md->data.number.min;
1488                     int this_max = rec_md->data.number.max;
1489                     if (this_min < (*wheretoput)->data.number.min)
1490                         (*wheretoput)->data.number.min = this_min;
1491                     if (this_max > (*wheretoput)->data.number.max)
1492                         (*wheretoput)->data.number.max = this_max;
1493                 }
1494             }
1495
1496
1497             // ranking of _all_ fields enabled ... 
1498             if (ser_md->rank)
1499                 relevance_countwords(se->relevance, cluster, 
1500                                      (char *) value, ser_md->rank,
1501                                      ser_md->name);
1502
1503             // construct facets ... unless the client already has reported them
1504             if (ser_md->termlist && !client_has_facet(cl, (char *) type))
1505             {
1506
1507                 if (ser_md->type == Metadata_type_year)
1508                 {
1509                     char year[64];
1510                     sprintf(year, "%d", rec_md->data.number.max);
1511
1512                     add_facet(se, (char *) type, year, term_factor);
1513                     if (rec_md->data.number.max != rec_md->data.number.min)
1514                     {
1515                         sprintf(year, "%d", rec_md->data.number.min);
1516                         add_facet(se, (char *) type, year, term_factor);
1517                     }
1518                 }
1519                 else
1520                     add_facet(se, (char *) type, (char *) value, term_factor);
1521             }
1522
1523             // cleaning up
1524             xmlFree(type);
1525             xmlFree(value);
1526             type = value = 0;
1527         }
1528         else
1529         {
1530             if (se->number_of_warnings_unknown_elements == 0)
1531                 session_log(se, YLOG_WARN,
1532                         "Unexpected element in internal record: %s", n->name);
1533             se->number_of_warnings_unknown_elements++;
1534         }
1535     }
1536     if (type)
1537         xmlFree(type);
1538     if (value)
1539         xmlFree(value);
1540
1541     relevance_donerecord(se->relevance, cluster);
1542     se->total_records++;
1543
1544     return 0;
1545 }
1546
1547 void session_log(struct session *s, int level, const char *fmt, ...)
1548 {
1549     char buf[1024];
1550     va_list ap;
1551     va_start(ap, fmt);
1552
1553     yaz_vsnprintf(buf, sizeof(buf)-30, fmt, ap);
1554     yaz_log(level, "Session (%u): %s", s->session_id, buf);
1555
1556     va_end(ap);
1557 }
1558
1559 /*
1560  * Local variables:
1561  * c-basic-offset: 4
1562  * c-file-style: "Stroustrup"
1563  * indent-tabs-mode: nil
1564  * End:
1565  * vim: shiftwidth=4 tabstop=8 expandtab
1566  */
1567