譬如下面的日志
2018-03-01 15:20:36,201 [pool-2-thread-4] DEBUG - 填充用戶組JT1000000100395的緩存 [TransactionHelper:31]
2018-03-01 15:20:36,202 [pool-2-thread-4] DEBUG - 進入同步塊 [TransactionHelper:34]
2018-03-01 15:20:36,201 [pool-2-thread-5] DEBUG - 填充用戶組JT1000000100395的緩存 [TransactionHelper:31]
2018-03-01 15:20:36,201 [pool-2-thread-2] DEBUG - 填充用戶組JT1000000100395的緩存 [TransactionHelper:31]
2018-03-01 15:20:36,202 [pool-2-thread-5] DEBUG - 進入同步塊 [TransactionHelper:34]
2018-03-01 15:20:36,202 [pool-2-thread-1] DEBUG - 進入同步塊 [TransactionHelper:34]
2018-03-01 15:20:36,202 [pool-2-thread-2] DEBUG - 進入同步塊 [TransactionHelper:34]
2018-03-01 15:20:36,206 [pool-2-thread-5] INFO - 白名單組加入 5 個號碼[TransactionHelper:44]
2018-03-01 15:20:36,207 [pool-2-thread-1] INFO - 白名單組加入 5 個號碼 [TransactionHelper:44]
2018-03-01 15:20:36,207 [pool-2-thread-2] INFO - 白名單組加入 5 個號碼 [TransactionHelper:44]
2018-03-01 15:20:36,206 [pool-2-thread-4]
線程之間的順序是嚴格按照時間嗎? 譬如說這個 2018-03-01 15:20:36,206 [pool-2-thread-4] 肯定在 “2018-03-01 15:20:36,207 [pool-2-thread-2] INFO - 白名單組加入 5 個號碼 ” 前面?
時間是LogEvent創(chuàng)建時間, 多線程時,寫入的順序可能與事件的創(chuàng)建時間不一致.
下面是我寫的一個測試程序
import org.apache.commons.logging.Log;
import org.apache.commons.logging.LogFactory;
import org.junit.Test;
public class LogTest {
final Log log = LogFactory.getLog(this.getClass());
Object o = new Object() {
public String toString() {
try {
Thread.sleep(1000);
} catch (InterruptedException e) {
e.printStackTrace();
}
return "this is slow! " + System.currentTimeMillis();
}
};
@Test
public void testFIFO() throws InterruptedException {
new Thread() {
public void run() {
log.info(o);
}
}.start();
Thread.sleep(100);
new Thread() {
public void run() {
log.info("this is fast! " + System.currentTimeMillis());
}
}.start();
Thread.sleep(2000);
}
@Test
public void testFILO() throws InterruptedException {
new Thread() {
public void run() {
log.info(o.toString());
}
}.start();
Thread.sleep(100);
new Thread() {
public void run() {
log.info("this is fast! " + System.currentTimeMillis());
}
}.start();
Thread.sleep(2000);
}
}
這里用的是commons-logging 來間接使用Log4j. 原理上是一樣的
輸出如下:
FIFO
INFO 2018-03-02 12:43:26,846 LogTest$2:run - this is slow! 1519965807848
INFO 2018-03-02 12:43:26,946 LogTest$3:run - this is fast! 1519965806946
FILO
INFO 2018-03-02 12:43:29,048 LogTest$5:run - this is fast! 1519965809048
INFO 2018-03-02 12:43:29,948 LogTest$4:run - this is slow! 1519965809948
第一個測試和第二個不同在于一個(FILO)是 log.info(o.toString());
, 一個(FIFO)是log.info(o);
我故意把toString方法變慢. 對比兩個結(jié)果, 可以看到發(fā)生時間和寫出時間的差異.
北大青鳥APTECH成立于1999年。依托北京大學(xué)優(yōu)質(zhì)雄厚的教育資源和背景,秉承“教育改變生活”的發(fā)展理念,致力于培養(yǎng)中國IT技能型緊缺人才,是大數(shù)據(jù)專業(yè)的國家
北大青鳥中博軟件學(xué)院創(chuàng)立于2003年,作為華東區(qū)著名互聯(lián)網(wǎng)學(xué)院和江蘇省首批服務(wù)外包人才培訓(xùn)基地,中博成功培育了近30000名軟件工程師走向高薪崗位,合作企業(yè)超4
中公教育集團創(chuàng)建于1999年,經(jīng)過二十年潛心發(fā)展,已由一家北大畢業(yè)生自主創(chuàng)業(yè)的信息技術(shù)與教育服務(wù)機構(gòu),發(fā)展為教育服務(wù)業(yè)的綜合性企業(yè)集團,成為集合面授教學(xué)培訓(xùn)、網(wǎng)
達內(nèi)教育集團成立于2002年,是一家由留學(xué)海歸創(chuàng)辦的高端職業(yè)教育培訓(xùn)機構(gòu),是中國一站式人才培養(yǎng)平臺、一站式人才輸送平臺。2014年4月3日在美國成功上市,融資1
曾工作于聯(lián)想擔任系統(tǒng)開發(fā)工程師,曾在博彥科技股份有限公司擔任項目經(jīng)理從事移動互聯(lián)網(wǎng)管理及研發(fā)工作,曾創(chuàng)辦藍懿科技有限責任公司從事總經(jīng)理職務(wù)負責iOS教學(xué)及管理工作。
浪潮集團項目經(jīng)理。精通Java與.NET 技術(shù), 熟練的跨平臺面向?qū)ο箝_發(fā)經(jīng)驗,技術(shù)功底深厚。 授課風(fēng)格 授課風(fēng)格清新自然、條理清晰、主次分明、重點難點突出、引人入勝。
精通HTML5和CSS3;Javascript及主流js庫,具有快速界面開發(fā)的能力,對瀏覽器兼容性、前端性能優(yōu)化等有深入理解。精通網(wǎng)頁制作和網(wǎng)頁游戲開發(fā)。
具有10 年的Java 企業(yè)應(yīng)用開發(fā)經(jīng)驗。曾經(jīng)歷任德國Software AG 技術(shù)顧問,美國Dachieve 系統(tǒng)架構(gòu)師,美國AngelEngineers Inc. 系統(tǒng)架構(gòu)師。