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