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