io.questdb.log.Logger Maven / Gradle / Ivy
/*******************************************************************************
* ___ _ ____ ____
* / _ \ _ _ ___ ___| |_| _ \| __ )
* | | | | | | |/ _ \/ __| __| | | | _ \
* | |_| | |_| | __/\__ \ |_| |_| | |_) |
* \__\_\\__,_|\___||___/\__|____/|____/
*
* Copyright (c) 2014-2019 Appsicle
* Copyright (c) 2019-2020 QuestDB
*
* Licensed under the Apache License, Version 2.0 (the "License");
* you may not use this file except in compliance with the License.
* You may obtain a copy of the License at
*
* http://www.apache.org/licenses/LICENSE-2.0
*
* Unless required by applicable law or agreed to in writing, software
* distributed under the License is distributed on an "AS IS" BASIS,
* WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
* See the License for the specific language governing permissions and
* limitations under the License.
*
******************************************************************************/
package io.questdb.log;
import io.questdb.mp.RingQueue;
import io.questdb.mp.Sequence;
import io.questdb.network.Net;
import io.questdb.std.Misc;
import io.questdb.std.Numbers;
import io.questdb.std.Sinkable;
import io.questdb.std.datetime.microtime.MicrosecondClock;
import io.questdb.std.datetime.microtime.TimestampFormatUtils;
import io.questdb.std.str.CharSink;
import org.jetbrains.annotations.NotNull;
import java.io.File;
/**
* Builds and sends log messages to writer thread. Log messages are constructed using "builder" pattern,
* which usually begins with "level method" {@link #debug()}, {@link #info()} or {@link #error()} followed by
* $(x) to append log message content and must terminate with {@link #$()}. There are $(x) methods for all types
* of "x" parameter to help avoid string concatenation and string creation in general.
*
*
* private static final Log LOG = LogFactory.getLog(MyClass.class);
* ...
* LOG.info().$("Hello world: ").$(123).$();
*
*
*
* Logger appends messages to native memory buffer and dispatches buffer to writer thread queue with {@link #$()} call.
* When writer queue is full all logger method calls between level and $() become no-ops. In this case queue size
* have to be increased or choice of log storage has to be reviewed. Depending on complexity of log message
* structure it should be possible to log between 1,000,000 and 10,000,000 messages per second to SSD device.
*
*/
class Logger implements LogRecord, Log {
private final CharSequence name;
private final RingQueue debugRing;
private final Sequence debugSeq;
private final RingQueue infoRing;
private final Sequence infoSeq;
private final RingQueue errorRing;
private final Sequence errorSeq;
private final RingQueue advisoryRing;
private final Sequence advisorySeq;
private final ThreadLocalCursor tl = new ThreadLocalCursor();
private final MicrosecondClock clock;
Logger(
MicrosecondClock clock,
CharSequence name,
RingQueue debugRing,
Sequence debugSeq,
RingQueue infoRing,
Sequence infoSeq,
RingQueue errorRing,
Sequence errorSeq,
RingQueue advisoryRing,
Sequence advisorySeq
) {
this.clock = clock;
this.name = name;
this.debugRing = debugRing;
this.debugSeq = debugSeq;
this.infoRing = infoRing;
this.infoSeq = infoSeq;
this.errorRing = errorRing;
this.errorSeq = errorSeq;
this.advisoryRing = advisoryRing;
this.advisorySeq = advisorySeq;
}
@Override
public void $() {
$(Misc.EOL);
Holder h = tl.get();
h.seq.done(h.cursor);
}
@Override
public LogRecord $(CharSequence sequence) {
if (sequence == null) {
sink().put("null");
} else {
sink().put(sequence);
}
return this;
}
@Override
public LogRecord $(CharSequence sequence, int lo, int hi) {
sink().put(sequence, lo, hi);
return this;
}
@Override
public LogRecord $(int x) {
sink().put(x);
return this;
}
@Override
public LogRecord $(double x) {
sink().put(x);
return this;
}
@Override
public LogRecord $(long x) {
sink().put(x);
return this;
}
@Override
public LogRecord $(boolean x) {
sink().put(x);
return this;
}
@Override
public LogRecord $(char c) {
sink().put(c);
return this;
}
@Override
public LogRecord $(Throwable e) {
if (e != null) {
sink().put(Misc.EOL).put(e);
}
return this;
}
@Override
public LogRecord $(File x) {
sink().put(x == null ? "null" : x.getAbsolutePath());
return this;
}
@Override
public LogRecord $(Object x) {
sink().put(x == null ? "null" : x.toString());
return this;
}
@Override
public LogRecord $(Sinkable x) {
if (x == null) {
sink().put("null");
} else {
x.toSink(sink());
}
return this;
}
@Override
public LogRecord $ip(long ip) {
Net.appendIP4(sink(), ip);
return this;
}
@Override
public LogRecord $ts(long x) {
sink().putISODate(x);
return this;
}
@Override
public LogRecord $256(long a, long b, long c, long d) {
Numbers.appendLong256(a, b, c, d, sink());
return this;
}
@Override
public boolean isEnabled() {
return true;
}
@Override
public LogRecord ts() {
sink().putISODate(clock.getTicks());
return this;
}
@Override
public LogRecord microTime(long x) {
TimestampFormatUtils.appendDateTimeUSec(sink(), x);
return this;
}
@Override
public LogRecord utf8(CharSequence sequence) {
if (sequence == null) {
sink().put("null");
} else {
sink().encodeUtf8(sequence);
}
return this;
}
@Override
public LogRecord debug() {
return addTimestamp(xdebug(), " D ");
}
@Override
public LogRecord error() {
return addTimestamp(xerror(), " E ");
}
@Override
public LogRecord info() {
return addTimestamp(xinfo(), " I ");
}
@Override
public LogRecord infoW() {
return addTimestamp(xInfoW(), " I ");
}
@Override
public LogRecord errorW() {
return addTimestamp(xErrorW(), " E ");
}
@Override
public LogRecord debugW() {
return addTimestamp(xDebugW(), " D ");
}
@Override
public LogRecord advisoryW() {
return addTimestamp(xAdvisoryW(), " A ");
}
@Override
public LogRecord advisory() {
return addTimestamp(xadvisory(), " A ");
}
@Override
public boolean isDebugEnabled() {
return debugSeq != null;
}
public LogRecord xerror() {
return next(errorSeq, errorRing, LogLevel.LOG_LEVEL_ERROR);
}
public LogRecord xinfo() {
return next(infoSeq, infoRing, LogLevel.LOG_LEVEL_INFO);
}
/**
* Guaranteed log delivery at INFO level. The calling thread will wait for async logger
* to become available instead of discarding log message.
*
* @return log record API
*/
public LogRecord xInfoW() {
return nextWaiting(infoSeq, infoRing, LogLevel.LOG_LEVEL_INFO);
}
public LogRecord xdebug() {
return next(debugSeq, debugRing, LogLevel.LOG_LEVEL_DEBUG);
}
@Override
public LogRecord xadvisory() {
return next(advisorySeq, advisoryRing, LogLevel.LOG_LEVEL_ADVISORY);
}
public LogRecord xAdvisoryW() {
return nextWaiting(infoSeq, infoRing, LogLevel.LOG_LEVEL_ADVISORY);
}
public LogRecord xDebugW() {
return nextWaiting(infoSeq, infoRing, LogLevel.LOG_LEVEL_DEBUG);
}
public LogRecord xErrorW() {
return nextWaiting(infoSeq, infoRing, LogLevel.LOG_LEVEL_ERROR);
}
private LogRecord addTimestamp(LogRecord rec, String level) {
return rec.ts().$(level).$(name);
}
private LogRecord next(Sequence seq, RingQueue ring, int level) {
if (seq == null) {
return NullLogRecord.INSTANCE;
}
final long cursor = seq.next();
if (cursor < 0) {
return NullLogRecord.INSTANCE;
}
return prepareLogRecord(seq, ring, level, cursor);
}
private LogRecord nextWaiting(Sequence seq, RingQueue ring, int level) {
if (seq == null) {
return NullLogRecord.INSTANCE;
}
return prepareLogRecord(seq, ring, level, seq.nextBully());
}
@NotNull
private LogRecord prepareLogRecord(Sequence seq, RingQueue ring, int level, long cursor) {
Holder h = tl.get();
h.cursor = cursor;
h.seq = seq;
h.ring = ring;
LogRecordSink r = ring.get(cursor);
r.setLevel(level);
r.clear(0);
return this;
}
private CharSink sink() {
Holder h = tl.get();
return h.ring.get(h.cursor);
}
private static class Holder {
private long cursor;
private Sequence seq;
private RingQueue ring;
}
private static class ThreadLocalCursor extends ThreadLocal {
@Override
protected Holder initialValue() {
return new Holder();
}
}
}