java - SBT 运行 scala 和 java 之间的差异？

Question

我正在尝试关注 log4j2 configuration tutorials在 SBT 0.12.1 项目中。这是我的build.sbt:

name := "Logging Test"

version := "0.0"

scalaVersion := "2.9.2"

libraryDependencies ++= Seq(
  "org.apache.logging.log4j" % "log4j-api" % "2.0-beta3",
  "org.apache.logging.log4j" % "log4j-core" % "2.0-beta3"
)

我有两个独立的主类。第一个是src/main/scala/logtest/ScalaTest.scala 中的logtest.ScalaTest:

package logtest

import org.apache.logging.log4j.{Logger, LogManager}

object ScalaTest {
  private val logger = LogManager.getLogger(getClass())
  def main(args: Array[String]) {
    logger.trace("Entering application.")
    val bar = new Bar()
    if (!bar.doIt())
      logger.error("Didn't do it.")

    logger.trace("Exiting application.")
  }
}

第二个是src/main/java/logtest/JavaTest.java中的logtest.JavaTest:

package logtest;

import org.apache.logging.log4j.Logger;
import org.apache.logging.log4j.LogManager;

public class JavaTest {
  private static Logger logger = LogManager.getLogger(JavaTest.class.getName());

  public static void main(String[] args) {
    logger.trace("Entering application.");
    Bar bar = new Bar();

    if (!bar.doIt())
      logger.error("Didn't do it.");

    logger.trace("Exiting application.");
  }
}

如果我从 sbt 内部运行 logtest.ScalaTest.main() 我会得到我期望的输出，因为 src/main/resources/log4j2.xml 设置了要跟踪的根日志记录级别:

> run-main logtest.ScalaTest
[info] Running logtest.ScalaTest 
10:26:23.730 [run-main] TRACE logtest.ScalaTest$ - Entering application.
10:26:23.733 [run-main] TRACE logtest.Bar -  entry
10:26:23.733 [run-main] ERROR logtest.Bar - Did it again!
10:26:23.733 [run-main] TRACE logtest.Bar -  exit with (false)
10:26:23.733 [run-main] ERROR logtest.ScalaTest$ - Didn't do it.
10:26:23.733 [run-main] TRACE logtest.ScalaTest$ - Exiting application.
[success] Total time: 0 s, completed Dec 21, 2012 10:26:23 AM

但是，当我从 sbt 内部运行 logtest.JavaTest.main() 时，我得到了不同的输出

> run-main logtest.JavaTest
[info] Running logtest.JavaTest 
ERROR StatusLogger Unable to locate a logging implementation, using SimpleLogger
ERROR Bar Did it again!
ERROR JavaTest Didn't do it.
[success] Total time: 0 s, completed Dec 21, 2012 10:27:29 AM

据我所知，ERROR StatusLogger Unable to ... 通常表示 log4j-core 不在我的类路径中。缺少 TRACE 消息似乎表明我的 log4j2.xml 设置也不在类路径中。如果我正在运行 Foo.main 与 LoggerTest.main，为什么类路径会有任何差异？或者是否有其他原因导致此行为？

更新

我使用 SBT Assembly 构建了这个项目的 fat jar，并指定 logtest.JavaTest 为主类。从命令行运行它产生了正确的结果:

$ java -jar "Logging Test-assembly-0.0.jar" 
10:29:41.089 [main] TRACE logtest.JavaTest - Entering application.
10:29:41.091 [main] TRACE logtest.Bar -  entry
10:29:41.091 [main] ERROR logtest.Bar - Did it again!
10:29:41.091 [main] TRACE logtest.Bar -  exit with (false)
10:29:41.091 [main] ERROR logtest.JavaTest - Didn't do it.
10:29:41.091 [main] TRACE logtest.JavaTest - Exiting application.

GitHub 示例

根据Edmondo1984 的建议，我整理了一个完整的示例并将其放在github 上。 .

Answer 1

这类问题通常是由于类加载差异造成的，在这种情况下，差异是非常重要的。

在此初始化阶段，LogManager 静态初始化器在类首次加载时被调用。如果你查看静态初始化程序，你会看到:

        Enumeration<URL> enumResources = null;
        try {
            enumResources = cl.getResources(LOGGER_RESOURCE);
        } catch (IOException e) {
            logger.fatal("Unable to locate " + LOGGER_RESOURCE, e);
        }

稍后在代码中，您将看到一个遍历枚举资源的循环以创建记录器上下文工厂。

但是，当您运行 Scala 类时，enumResources.hasMoreElements() 返回 true，而当您运行 java 类时，它返回 false (所以没有记录器上下文，也没有记录器被添加到 LogManager)。

如果进一步调查，您会发现 cl 变量实际上是一个类加载器，在 Java 类的情况下是 sun.misc.Launcher$AppClassLoader 的一个实例 而 Scala 类是一个实例 sbt.classpath.ClasspathUtilities$$anon$1

如果您查看静态初始化程序的开头，您会看到以下语句:

 static {
        // Shortcut binding to force a specific logging implementation.
        PropsUtil managerProps = new PropsUtil("log4j2.LogManager.properties");
        String factoryClass = managerProps.getStringProperty(FACTORY_PROPERTY_NAME);
        ClassLoader cl = findClassLoader(); 

所以你可能想看看 findClassLoader() 方法:

private static ClassLoader findClassLoader() {
            ClassLoader cl;
            if (System.getSecurityManager() == null) {
                cl = Thread.currentThread().getContextClassLoader();
            } else {
                cl = java.security.AccessController.doPrivileged(
                    new java.security.PrivilegedAction<ClassLoader>() {
                        public ClassLoader run() {
                            return Thread.currentThread().getContextClassLoader();
                        }
                    }
                );
            }
            if (cl == null) {
                cl = LogManager.class.getClassLoader();
            }

            return cl;
        }

在这两种情况下，由于 SecurityManager 不为空，它返回当前线程上下文类加载器。这对于您的 Java 类和 Scala 类是不同的。