跳到主要內容

Bridge JUL to SLF4j

Problem

許多third-party api都是使用Java Util Logging(JUL)來Log資訊，但你可能會遇到幾點問題:

  • Log格式與Log4j或Slf4j不相同，看了礙眼。
  • 我平常不需要JUL的訊息，但發生問題時就需要了。怎樣可以方便設定?
  • 不要讓我學兩份Config的方式。好嗎?

也許還有種種原因，我只是希望能有一個統一輸出界面。還好有好心人士做了Bridge，目前看到有Log4j的JULBridgeLogManager與SLF4J的SLF4JBridgeHandler。這篇文章中挑SLF4JBridgeHandler做介紹。

How to use?

Config

直接看我的cofng。首先我將JUL的所有Log level打開。假如你沒這麼做，JUL預設Level為Info，你只有在Info Level以上的才有辦法將內容導向SLF4J；再來就是針對你要的Package做設定。我選用Expect4j做範例

log4j.rootCategory=WARN, CONSOLE

.level= ALL
handlers= java.util.logging.ConsoleHandler
java.util.logging.ConsoleHandler.level= ALL

log4j.additivity.expect4j=false
log4j.logger.expect4j=TRACE, CONSOLE

# CONSOLE is set to be a ConsoleAppender using a PatternLayout.
log4j.appender.CONSOLE=org.apache.log4j.ConsoleAppender
log4j.appender.CONSOLE.Threshold=TRACE
log4j.appender.CONSOLE.layout=org.apache.log4j.PatternLayout
log4j.appender.CONSOLE.layout.ConversionPattern=%d{dd/MM HH:mm:ss} %5p[%t] %X{usedheap}(%F:%M:%L) - %m%n

這是JUL與Log4j之對應，可以根據這個設定你的config。(參考Javadoc for SLF4JBridgeHandler)

FINEST  -> TRACE
FINER   -> DEBUG
FINE    -> DEBUG
INFO    -> INFO
WARNING -> WARN
SEVERE  -> ERROR

Load Config

除了用檔案設定，當然你也可以透過寫Code的方式去設定，但我比較偏好透過檔案。提供給你兩種方法: 1. 從Classpath讀取設定；2. 從File path讀取設定。我將檔案放在source folder test目錄下。

Load from ClassPath

private void readLog4jConfigFromClasspath(){
	InputStream is = this.getClass().getResourceAsStream("/log4j.properties");
	try { 
		LogManager.getLogManager().readConfiguration(is); 
	} catch(Exception e) {
		throw new RuntimeException(e);
	} finally {
		Cleaner.close(is);
	}
}

註: Cleaner是自製類別，負責關閉串流。

Load from FilePath

這是透過java.util.logging.config.file的設定，你也可以藉由傳入-Djava.util.logging.config.file參數。

private void readLog4jConfigFromFilePath(){
	Properties prop = System.getProperties();
	prop.setProperty("java.util.logging.config.file", "test/log4j.properties"); 
	try { 
		LogManager.getLogManager().readConfiguration(); 
	} catch(Exception e) {
		throw new RuntimeException(e);
	}
}

Bridging

先閱讀一下範例程式:

@Test
public void test() throws Exception {
	readLog4jConfigFromClasspath();
	//readLog4jConfigFromFilePath();
 
	SLF4JBridgeHandler.removeHandlersForRootLogger();
	SLF4JBridgeHandler.install();
 
	Expect4j.log.finest("test1");
	Expect4j.log.info("test2");
	Expect4j.log.fine("test3");
}

最重要的兩個method是removeHandlersForRootLogger與install。以下圖結果來說，假如你沒呼叫removeHandlersForRootLogger，原本JUL的訊息也會被Log，如圖片中紅色的字。install就是安裝bridge的功能，當然也有uninstall去移除拉!另外紅色的有三筆，黑色的只有兩筆，原因是finest為TRACE Level，設定為Debug並不會顯示。

友藏內心獨白: 趁著修改測試程式，順便學習新招。

Reference

標籤：

留言

張貼留言

這個網誌中的熱門文章

解決RobotFramework從3.1.2升級到3.2.2之後，Choose File突然會整個Hand住的問題

考慮到自動測試環境的維護，我們很久以前就使用java去執行robot framework。前陣子開始處理從3.1.2升級到3.2.2的事情，主要先把明確的runtime語法錯誤與deprecate item處理好，這部分內容可以參考: link 。 直到最近才發現，透過SeleniumLibrary執行Choose File去上傳檔案的動作，會導致測試案例timeout。本篇文章主要分享心路歷程與解決方法，我也送了一條issue給robot framework: link 。 我的環境如下: RobotFramework: 3.2.2 Selenium: 3.141.0 SeleniumLibrary: 3.3.1 Remote Selenium Version: selenium-server-standalone-3.141.59 首先並非所有Choose File的動作都會hang住，有些測試案例是可以執行的，但是上傳一個作業系統ISO檔案一定會發生問題。後來我透過wireshark去比對新舊版本的上傳動作，因為我使用 Remote Selenium ，所以Selenium會先把檔案透過REST API發送到Remote Selenium Server上。從下圖我們可以發現，在3.2.2的最後一個TCP封包，比3.1.2大概少了500個bytes。 於是就開始了我trace code之路。包含SeleniumLibrary產生要送給Remote Selenium Server的request內容，還有HTTP Content-Length的計算，我都確認過沒有問題。 最後發現問題是出在socket API的使用上，就是下圖的這支code: 最後發現可能因為開始使用nio的方式送資料，但沒處理到尚未送完的資料內容，而導致發生問題。加一個loop去做計算就可以解決了。 最後我有把解法提供給robot framework官方，在他們出新的版本之前，我是將改完的_socket.py放在我們自己的Lib底下，好讓我們測試可以正常進行。(shutil.py應該也是為了解某個bug而產生的樣子..)
張貼留言
閱讀完整內容

Show NIC selection when setting the network command with the device option

 Problem  在answer file中設定網卡名稱後，安裝時會停在以下畫面: 所使用的command參數如下: network --onboot = yes --bootproto =dhcp --ipv6 =auto --device =eth1 Diagnostic Result 這樣的參數，以前試驗過是可以安裝完成的。因此在發生這個問題後，我檢查了它的debug console: 從console得知，eth1可能是沒有連接網路線或者是網路太慢而導致的問題。後來和Ivy再三確認，有問題的是有接網路線的網卡，且問題是發生在activate階段: Solution 我想既然有retry應該就有次數或者timeout限制，因此發現在Anaconda的說明文件中( link )，有提到dhcptimeout這個boot參數。看了一些人的使用範例，應該是可以直接串在isolinux.cfg中，如下: default linux ksdevice = link ip =dhcp ks =cdrom: / ks.cfg dhcptimeout = 90 然而我在RHEL/CentOS 6.7與6.8試驗後都無效。 因此我就拿了顯示的錯誤字串，問問Google大師，想找一下Anaconda source code來看一下。最後找到別人根據Anaconda code修改的版本: link ，關鍵在於setupIfaceStruct函式中的setupIfaceStruct與readNetConfig: setupIfaceStruct: 會在dhcp時設定dhcptimeout。 readNetConfig: 在writeEnabledNetInfo將timeout寫入dhclient config中；在wait_for_iface_activation內會根據timeout做retry。 再來從log與code可以得知，它讀取的檔案是answer file而不是boot command line。因此我接下來的測試，就是在answer file的network command上加入dhcptimeout: network --onboot = yes --bootproto =dhcp --ipv6 =auto --device =eth1 --...
張貼留言
閱讀完整內容

Robot Framework - Evaluate該怎麼用?

Evaluate該怎麼用? 前言 Builtin的RobotFramework Library提供了Evaluate Keyword。它所提供的功能是「執行Python描述句」。但實際上到底有什麼用途呢?原本我僅僅拿來將string轉為int的功用，經過一些查詢與試驗，我將心得整理給大家。 Builtin Builtin的function可以參考Library Doc for Evaluate。我以有使用過的function做說明。 數字轉換 Python提供了int、long、float與complex等function讓你可以將字串轉為數字，也可以透過它們做四則運算。首先以字串轉數字為例，我將8設於${num_str}中，再透過Evaluate+int轉為數字。這裡必須注意的是: 「int()中放變數必須以單引號'括起」。否則，假如你設定的數字為08，在轉換int時會出現Syntax Error。 ${num_str} | Set Variable | 8 ${num} | Evaluate | int('${num_str}') 其中int與long的第二個參數為base，這是根據你的input所決定: Comment | num = 9 ${num} | Evaluate | int('11', 8) Comment | num = 11 ${num} | Evaluate | int('11', 10) Comment | num = 17 ${num} | Evaluate | int('11', 16) 其它還有像bin、oct、hex，可以將整數轉為2、8、16進位。 運算 四則運算: 直接將運算子加上即可: ${num} | Evaluate | int('${hour}')*60 + int('${min}') 指數: 可以用pow。以下面兩個例子來說，第一個是2的3次方為8，第二個是2的3次方再mod 7為1。需注意的是: 「傳入值必須是數字不可為字串」。 ${num} | Evaluate | pow(2,3) ${num} | Evaluate | pow(2,3,7) 取最大最小值: 使用max/min，可以選擇丟一個array的方式...
張貼留言
閱讀完整內容