logging.c 6.84 KB
Newer Older
1
2
3
4
5
6
7
8
9
10
/* Icecast
 *
 * This program is distributed under the GNU General Public License, version 2.
 * A copy of this license is included with this source.
 *
 * Copyright 2000-2004, Jack Moffitt <jack@xiph.org, 
 *                      Michael Smith <msmith@xiph.org>,
 *                      oddsock <oddsock@xiph.org>,
 *                      Karl Heyes <karl@xiph.org>
 *                      and others (see AUTHORS for details).
Philipp Schafft's avatar
Philipp Schafft committed
11
 * Copyright 2011-2012, Philipp "ph3-der-loewe" Schafft <lion@lion.leolix.org>,
12
13
 */

14
15
16
17
#ifdef HAVE_CONFIG_H
#include <config.h>
#endif

Jack Moffitt's avatar
Jack Moffitt committed
18
19
#include <stdio.h>
#include <time.h>
20
#include <string.h>
Jack Moffitt's avatar
Jack Moffitt committed
21

Karl Heyes's avatar
Karl Heyes committed
22
23
#include "thread/thread.h"
#include "httpp/httpp.h"
Jack Moffitt's avatar
Jack Moffitt committed
24
25
26
27
28

#include "connection.h"
#include "refbuf.h"
#include "client.h"

29
#include "compat.h"
30
#include "cfgfile.h"
Jack Moffitt's avatar
Jack Moffitt committed
31
#include "logging.h"
32
#include "util.h"
Jack Moffitt's avatar
Jack Moffitt committed
33

34
35
#ifdef _WIN32
#define snprintf _snprintf
36
#define vsnprintf _vsnprintf
37
38
#endif

Jack Moffitt's avatar
Jack Moffitt committed
39
/* the global log descriptors */
Michael Smith's avatar
Michael Smith committed
40
41
int errorlog = 0;
int accesslog = 0;
42
int playlistlog = 0;
Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57

#ifdef _WIN32
/* Since strftime's %z option on win32 is different, we need
   to go through a few loops to get the same info as %z */
int get_clf_time (char *buffer, unsigned len, struct tm *t)
{
    char    sign;
    char    *timezone_string;
    struct tm gmt;
    time_t time1 = time(NULL);
    int time_days, time_hours, time_tz;
    int tempnum1, tempnum2;
    struct tm *thetime;
    time_t now;

58
59
60
61
62
63
64
65
66
#if !defined(_WIN32)
    thetime = gmtime_r(&time1, &gmt)
#else
    /* gmtime() on W32 breaks POSIX and IS thread-safe (uses TLS) */
    thetime = gmtime (&time1);
    if (thetime)
      memcpy (&gmt, thetime, sizeof (gmt));
#endif
    /* FIXME: bail out if gmtime* returns NULL */
Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92

    time_days = t->tm_yday - gmt.tm_yday;

    if (time_days < -1) {
        tempnum1 = 24;
    }
    else {
        tempnum1 = 1;
    }
    if (tempnum1 < time_days) {
       tempnum2 = -24;
    }
    else {
        tempnum2 = time_days*24;
    }

    time_hours = (tempnum2 + t->tm_hour - gmt.tm_hour);
    time_tz = time_hours * 60 + t->tm_min - gmt.tm_min;

    if (time_tz < 0) {
        sign = '-';
        time_tz = -time_tz;
    }
    else {
        sign = '+';
    }
93

Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
94
95
96
97
98
99
100
101
    timezone_string = calloc(1, 7);
    snprintf(timezone_string, 7, " %c%.2d%.2d", sign, time_tz / 60, time_tz % 60);

    now = time(NULL);

    thetime = localtime(&now);
    strftime (buffer, len-7, "%d/%b/%Y:%H:%M:%S", thetime);
    strcat(buffer, timezone_string);
Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
102
	free(timezone_string);
Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
103
104
    return 1;
}
105
#endif
Jack Moffitt's avatar
Jack Moffitt committed
106
/* 
107
** ADDR IDENT USER DATE REQUEST CODE BYTES REFERER AGENT [TIME]
Jack Moffitt's avatar
Jack Moffitt committed
108
109
**
** ADDR = client->con->ip
110
** IDENT = always - , we don't support it because it's useless
111
** USER = client->username
Jack Moffitt's avatar
Jack Moffitt committed
112
113
114
115
116
117
118
119
120
121
** DATE = _make_date(client->con->con_time)
** REQUEST = build from client->parser
** CODE = client->respcode
** BYTES = client->con->sent_bytes
** REFERER = get from client->parser
** AGENT = get from client->parser
** TIME = timing_get_time() - client->con->con_time
*/
void logging_access(client_t *client)
{
122
123
    char datebuf[128];
    char reqbuf[1024];
124
    struct tm thetime;
125
126
    time_t now;
    time_t stayed;
127
    const char *referrer, *user_agent, *username;
128
129
130

    now = time(NULL);

131
    localtime_r (&now, &thetime);
132
    /* build the data */
Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
133
#ifdef _WIN32
134
135
    memset(datebuf, '\000', sizeof(datebuf));
    get_clf_time(datebuf, sizeof(datebuf)-1, &thetime);
Ed "oddsock" Zaleski's avatar
Ed "oddsock" Zaleski committed
136
#else
137
    strftime (datebuf, sizeof(datebuf), LOGGING_FORMAT_CLF, &thetime);
138
#endif
139
    /* build the request */
140
141
142
143
144
    snprintf (reqbuf, sizeof(reqbuf), "%s %s %s/%s",
            httpp_getvar (client->parser, HTTPP_VAR_REQ_TYPE),
            httpp_getvar (client->parser, HTTPP_VAR_URI),
            httpp_getvar (client->parser, HTTPP_VAR_PROTOCOL),
            httpp_getvar (client->parser, HTTPP_VAR_VERSION));
145
146
147

    stayed = now - client->con->con_time;

148
149
150
151
152
    if (client->username == NULL)
        username = "-"; 
    else
        username = client->username;

153
154
155
156
157
158
159
160
    referrer = httpp_getvar (client->parser, "referer");
    if (referrer == NULL)
        referrer = "-";

    user_agent = httpp_getvar (client->parser, "user-agent");
    if (user_agent == NULL)
        user_agent = "-";

161
    log_write_direct (accesslog,
162
            "%s - %s [%s] \"%s\" %d %" PRIu64 " \"%s\" \"%s\" %lu",
163
            client->con->ip,
164
            username,
165
166
167
168
169
170
171
            datebuf,
            reqbuf,
            client->respcode,
            client->con->sent_bytes,
            referrer,
            user_agent,
            (unsigned long)stayed);
Jack Moffitt's avatar
Jack Moffitt committed
172
}
173
174
175
/* This function will provide a log of metadata for each
   mountpoint.  The metadata *must* be in UTF-8, and thus
   you can assume that the log itself is UTF-8 encoded */
176
void logging_playlist(const char *mount, const char *metadata, long listeners)
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
{
    char datebuf[128];
    struct tm thetime;
    time_t now;

    if (playlistlog == -1) {
        return;
    }

    now = time(NULL);

    localtime_r (&now, &thetime);
    /* build the data */
#ifdef _WIN32
    memset(datebuf, '\000', sizeof(datebuf));
    get_clf_time(datebuf, sizeof(datebuf)-1, &thetime);
#else
    strftime (datebuf, sizeof(datebuf), LOGGING_FORMAT_CLF, &thetime);
#endif
    /* This format MAY CHANGE OVER TIME.  We are looking into finding a good
       standard format for this, if you have any ideas, please let us know */
198
    log_write_direct (playlistlog, "%s|%s|%ld|%s",
199
200
201
202
203
             datebuf,
             mount,
             listeners,
             metadata);
}
Jack Moffitt's avatar
Jack Moffitt committed
204
205


206
207
208
209
210
211
212
213
214
215
216
217
218
219
void log_parse_failure (void *ctx, const char *fmt, ...)
{
    char line [200];
    va_list ap;
    char *eol;

    va_start (ap, fmt);
    vsnprintf (line, sizeof (line), fmt, ap);
    eol = strrchr (line, '\n');
    if (eol) *eol='\0';
    va_end (ap);
    log_write (errorlog, 2, (char*)ctx, "", "%s", line);
}

Jack Moffitt's avatar
Jack Moffitt committed
220

221
void restart_logging (ice_config_t *config)
222
223
224
225
226
227
228
{
    if (strcmp (config->error_log, "-"))
    {
        char fn_error[FILENAME_MAX];
        snprintf (fn_error, FILENAME_MAX, "%s%s%s", config->log_dir, PATH_SEPARATOR, config->error_log);
        log_set_filename (errorlog, fn_error);
        log_set_level (errorlog, config->loglevel);
229
230
        log_set_trigger (errorlog, config->logsize);
        log_set_archive_timestamp(errorlog, config->logarchive);
231
232
233
234
235
236
237
238
        log_reopen (errorlog);
    }

    if (strcmp (config->access_log, "-"))
    {
        char fn_error[FILENAME_MAX];
        snprintf (fn_error, FILENAME_MAX, "%s%s%s", config->log_dir, PATH_SEPARATOR, config->access_log);
        log_set_filename (accesslog, fn_error);
239
240
        log_set_trigger (accesslog, config->logsize);
        log_set_archive_timestamp (accesslog, config->logarchive);
241
242
        log_reopen (accesslog);
    }
243
244
245
246
247
248

    if (config->playlist_log)
    {
        char fn_error[FILENAME_MAX];
        snprintf (fn_error, FILENAME_MAX, "%s%s%s", config->log_dir, PATH_SEPARATOR, config->playlist_log);
        log_set_filename (playlistlog, fn_error);
249
250
        log_set_trigger (playlistlog, config->logsize);
        log_set_archive_timestamp (playlistlog, config->logarchive);
251
252
        log_reopen (playlistlog);
    }
253
}