flog 0.1.2

A fast logging library
Documentation 
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
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
93
94
95
96
97
98
99
100
101
102
103
104
105

use std::f64::consts::PI;
use std::fs::File;
use std::io::{SeekFrom, Read, Seek};
use std::thread;
use std::time::Instant;
use flog::{log_str, flush, LogItem, log};
use std::io::Write;
use std::sync::{Mutex, Arc};

const N: usize = 1 << 22;

fn prepare() {
    let mut file = File::create("./input.bin").unwrap();
    for i in 0..N {
        file.write_all(&i.to_le_bytes()).unwrap();
    }
    File::create("./output.txt").unwrap();
}

fn fib(i: usize) -> usize {
    if i < 2 { 1 } else { fib(i - 1) + fib(i - 2) }
}

pub fn heavy_cpu(i: usize) -> f64 {
    let data = [
        i as f64,
        (i as f64).sin(),
        (i as f64).cos(),
        (i as f64).tan(),
        (i as f64).log(2.0),
        (i as f64).log(10.0),
        (i as f64).exp(),
        (i as f64).acos(),
        (i as f64).asin(),
        (i as f64).powf(PI),
        (i as f64).powf(PI * 2.0),
        (fib(i % 25) as f64).sin().acos().tan(),
    ];
    data.iter().sum()
}

fn mixed(from: Arc<Mutex<File>>, to: Arc<Mutex<File>>, i: usize) {
    let mut obj = LogItem::new();
    let start_time = minstant::now();

    let mut from = from.lock().unwrap();
    let mut buffer = [0u8; 8];
    from.seek(SeekFrom::Start(i as u64 * 8)).unwrap();
    from.read_exact(&mut buffer).unwrap();
    drop(from);
    let n = u64::from_le_bytes(buffer);
    obj.u64(i as u64).str(" read ").u64(i as u64).u64(minstant::now() - start_time).char('\n');

    let start_time = minstant::now();
    let result = heavy_cpu(n as usize);
    obj.u64(i as u64).str(" heavy_cpu ").u64(i as u64).u64(minstant::now() - start_time).char('\n');
    let start_time = minstant::now();
    let mut to = to.lock().unwrap();
    write!(to, "result is: {}\n", result).unwrap();
    obj.u64(i as u64).str(" write ").u64(i as u64).u64(minstant::now() - start_time).char('\n');

    log(obj);
}

fn use_log(thread_count: usize, i: usize) {
    let from = Arc::new(Mutex::new(File::open("./input.bin").unwrap()));
    let to = Arc::new(Mutex::new(File::create("./output.txt").unwrap()));
    let start_time = minstant::now();
    let mut threads = Vec::new();
    for _tid in 0..thread_count {
        let from = from.clone();
        let to = to.clone();
        threads.push(thread::spawn(move || {
            for i in 0..i / thread_count {
                let from = from.clone();
                let to = to.clone();

                let mut obj = LogItem::new();
                obj.char('[').u64(minstant::now() - start_time).str("] ").u64(i as u64).str(" start\n");
                log(obj);

                mixed(from, to, i);

                let mut obj = LogItem::new();
                obj.char('[').u64(minstant::now() - start_time).str("] ").u64(i as u64).str(" end\n");
                log(obj);
            }
        }))
    }
    for thread in threads {
        thread.join().unwrap();
    }
    flush();
}

fn main() {
    prepare();
    println!("prepared");
    let guard = pprof::ProfilerGuard::new(100).unwrap();
    use_log(128, N);
    if let Ok(report) = guard.report().build() {
        let file = File::create("flamegraph.svg").unwrap();
        report.flamegraph(file).unwrap();
    };
}