This morning I tried to set the logging for a binding from WARN to DEBUG. After the reboot, openHAB no longer writes to my log files (both openhab.log and events.log).
I already replaced my logback.xml with the default logback.xml that is supplied at installation, reset permissions in the /etc/openhab folder using $ sudo chown -R openhab:openhab /etc/openhab/* and renamed the log using $ sudo mv /var/log/openhab.log /var/log/openhab/openhab.log.old.
Several reboots later no change.
I looks like my openHAB is running as it should, but I have no way to check the log files, so can’t see any errors etc.
I just looked at syslog (didn’t know it existed, am sort of a Linux noob) and it show an error at restart. Does this make sense to anyone?
Nov 1 17:29:25 raspberrypi rsyslogd-2007: action 'action 17' suspended, next retry is Sun Nov 1 17:30:55 2015 [try http://www.rsyslog.com/e/2007 ]
Nov 1 17:29:25 raspberrypi systemd[1]: Configuration file /usr/lib/systemd/system/openhab.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Nov 1 17:29:25 raspberrypi systemd[1]: Stopping Starts and stops the openHAB Home Automation Bus...
Nov 1 17:29:31 raspberrypi systemd[1]: Starting Starts and stops the openHAB Home Automation Bus...
Nov 1 17:29:31 raspberrypi setpermissions.sh[15336]: setting permissions and owner openhab:openhab recursively for /var/lib/openhab
Nov 1 17:29:32 raspberrypi setpermissions.sh[15336]: setting permissions and owner openhab:openhab recursively for /var/log/openhab
Nov 1 17:29:33 raspberrypi setpermissions.sh[15336]: setting permissions and owner openhab:openhab for /usr/share/openhab/webapps/static
Nov 1 17:29:33 raspberrypi setpermissions.sh[15336]: setting permissions and owner openhab:openhab for /etc/openhab/jetty/etc/keystore
Nov 1 17:29:33 raspberrypi setpermissions.sh[15336]: setting permissions and owner openhab:openhab for /etc/openhab/configurations/users.cfg
Nov 1 17:29:33 raspberrypi systemd[1]: Started Starts and stops the openHAB Home Automation Bus.
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: Failed to auto configure default logger context
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: Reported exception:
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: ch.qos.logback.core.joran.spi.JoranException: Problem parsing XML document. See previously reported errors.
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.core.joran.event.SaxEventRecorder.recordEvents(SaxEventRecorder.java:65)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:142)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:106)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:56)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:75)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.classic.util.ContextInitializer.autoConfig(ContextInitializer.java:148)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.slf4j.impl.StaticLoggerBinder.init(StaticLoggerBinder.java:84)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.slf4j.impl.StaticLoggerBinder.<clinit>(StaticLoggerBinder.java:54)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.slf4j.LoggerFactory.bind(LoggerFactory.java:128)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.slf4j.LoggerFactory.performInitialization(LoggerFactory.java:107)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.slf4j.LoggerFactory.getILoggerFactory(LoggerFactory.java:295)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.slf4j.LoggerFactory.getLogger(LoggerFactory.java:269)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.jetty.util.log.Slf4jLog.<init>(Slf4jLog.java:33)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.jetty.util.log.Slf4jLog.<init>(Slf4jLog.java:27)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at java.lang.reflect.Constructor.newInstance(Constructor.java:408)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at java.lang.Class.newInstance(Class.java:433)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.jetty.util.log.Log.initialized(Log.java:156)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.jetty.util.log.Log.getLogger(Log.java:430)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.jetty.osgi.boot.internal.serverfactory.JettyServerServiceTracker.<clinit>(JettyServerServiceTracker.java:34)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.jetty.osgi.boot.JettyBootstrapActivator.start(JettyBootstrapActivator.java:100)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.BundleContextImpl$1.run(BundleContextImpl.java:711)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at java.security.AccessController.doPrivileged(Native Method)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.BundleContextImpl.startActivator(BundleContextImpl.java:702)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.BundleContextImpl.start(BundleContextImpl.java:683)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.BundleHost.startWorker(BundleHost.java:381)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.AbstractBundle.resume(AbstractBundle.java:390)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.Framework.resumeBundle(Framework.java:1176)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.StartLevelManager.resumeBundles(StartLevelManager.java:559)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.StartLevelManager.resumeBundles(StartLevelManager.java:544)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.StartLevelManager.incFWSL(StartLevelManager.java:457)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.StartLevelManager.doSetStartLevel(StartLevelManager.java:243)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.StartLevelManager.dispatchEvent(StartLevelManager.java:438)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.internal.core.StartLevelManager.dispatchEvent(StartLevelManager.java:1)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: Caused by: org.xml.sax.SAXParseException; lineNumber: 61; columnNumber: 3; The element type "logger" must be terminated by the matching end-tag "</logger>".
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(AbstractSAXParser.java:1239)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(SAXParserImpl.java:649)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl.parse(SAXParserImpl.java:333)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at ch.qos.logback.core.joran.event.SaxEventRecorder.recordEvents(SaxEventRecorder.java:59)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: ... 37 more
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: 17:29:42,653 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [/etc/openhab/logback.xml] at [file:/etc/openhab/logback.xml]
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: 17:29:42,782 |-ERROR in ch.qos.logback.core.joran.event.SaxEventRecorder@d4460a - XML_PARSING - Parsing fatal error on line 61 and column 3 org.xml.sax.SAXParseException; lineNumber: 61; columnNumber: 3; The element type "logger" must be terminated by the matching end-tag "</logger>".
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at org.xml.sax.SAXParseException: The element type "logger" must be terminated by the matching end-tag "</logger>".
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.createSAXParseException(ErrorHandlerWrapper.java:203)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.fatalError(ErrorHandlerWrapper.java:177)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:441)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLErrorReporter.reportError(XMLErrorReporter.java:368)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLScanner.reportFatalError(XMLScanner.java:1436)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(XMLDocumentFragmentScannerImpl.java:1748)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:2957)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(XMLDocumentScannerImpl.java:606)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(XMLNSDocumentScannerImpl.java:117)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(XMLDocumentFragmentScannerImpl.java:510)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:848)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:777)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.java:141)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(AbstractSAXParser.java:1213)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(SAXParserImpl.java:649)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl.parse(SAXParserImpl.java:333)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at ch.qos.logback.core.joran.event.SaxEventRecorder.recordEvents(SaxEventRecorder.java:59)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:142)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:106)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:56)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:75)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at ch.qos.logback.classic.util.ContextInitializer.autoConfig(ContextInitializer.java:148)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.slf4j.impl.StaticLoggerBinder.init(StaticLoggerBinder.java:84)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.slf4j.impl.StaticLoggerBinder.<clinit>(StaticLoggerBinder.java:54)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.slf4j.LoggerFactory.bind(LoggerFactory.java:128)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.slf4j.LoggerFactory.performInitialization(LoggerFactory.java:107)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.slf4j.LoggerFactory.getILoggerFactory(LoggerFactory.java:295)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.slf4j.LoggerFactory.getLogger(LoggerFactory.java:269)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.jetty.util.log.Slf4jLog.<init>(Slf4jLog.java:33)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.jetty.util.log.Slf4jLog.<init>(Slf4jLog.java:27)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at java.lang.reflect.Constructor.newInstance(Constructor.java:408)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at java.lang.Class.newInstance(Class.java:433)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.jetty.util.log.Log.initialized(Log.java:156)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.jetty.util.log.Log.getLogger(Log.java:430)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.jetty.osgi.boot.internal.serverfactory.JettyServerServiceTracker.<clinit>(JettyServerServiceTracker.java:34)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.jetty.osgi.boot.JettyBootstrapActivator.start(JettyBootstrapActivator.java:100)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.BundleContextImpl$1.run(BundleContextImpl.java:711)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at java.security.AccessController.doPrivileged(Native Method)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.BundleContextImpl.startActivator(BundleContextImpl.java:702)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.BundleContextImpl.start(BundleContextImpl.java:683)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.BundleHost.startWorker(BundleHost.java:381)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.AbstractBundle.resume(AbstractBundle.java:390)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.Framework.resumeBundle(Framework.java:1176)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.StartLevelManager.resumeBundles(StartLevelManager.java:559)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.StartLevelManager.resumeBundles(StartLevelManager.java:544)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.StartLevelManager.incFWSL(StartLevelManager.java:457)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.StartLevelManager.doSetStartLevel(StartLevelManager.java:243)
Nov 1 17:29:42 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.StartLevelManager.dispatchEvent(StartLevelManager.java:438)
Nov 1 17:29:43 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.internal.core.StartLevelManager.dispatchEvent(StartLevelManager.java:1)
Nov 1 17:29:43 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)
Nov 1 17:29:43 raspberrypi openhab.sh[15687]: at #011at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)
Nov 1 17:30:19 raspberrypi openhab.sh[15687]: RXTX Warning: Removing stale lock file. /var/lock/LCK..ttyUSB0
