Openhab 3.1.0.M4 - userdata/log/openhab.log

Hi

I run on Windows 10 with 11.0.9.1 (Zulu11.43+55-CA).

Since upgrading to 3.1.0.M4 I am seeing an empty openhab.log file created when openHAB restarts in the log directory (instead of logs).

Anyone else seeing this?

Thanks
Mark

Which version did you upgrade from?

Hi.

From 3.1.0.M3

Mark

OK, then this is the first I seen anything like this reported. All I can suggest is look at userdata/etc/log4j2.xml and make sure that doesn’t look incorrect. Watch the output printed when starting OH and see if it’s complaining about the log file or something else.

I compared the log4j2.xml between M3 and M4 and they are identical - so don’t hink that is the issue.

Logs from a service restart:

2021-05-10 19:04:26.142 [WARN ] [org.apache.karaf.shell.ssh.Activator] - Error starting activator
java.lang.IllegalStateException: Service not tracked for class interface org.osgi.service.cm.ConfigurationAdmin
	at org.apache.karaf.util.tracker.BaseActivator.getTrackedService(BaseActivator.java:369) ~[bundleFile:?]
	at org.apache.karaf.util.tracker.BaseActivator.ensureStartupConfiguration(BaseActivator.java:154) ~[bundleFile:?]
	at org.apache.karaf.shell.ssh.Activator.doStart(Activator.java:96) ~[bundleFile:?]
	at org.apache.karaf.util.tracker.BaseActivator.run(BaseActivator.java:312) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:834) [?:?]
2021-05-10 19:04:34.674 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time zone set to 'Africa/Johannesburg'.
2021-05-10 19:04:34.674 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Location set to '-29.7745707,31.050459'.
2021-05-10 19:04:34.674 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Locale set to 'en_ZA'.
2021-05-10 19:04:34.674 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Measurement system set to 'SI'.
2021-05-10 19:04:46.122 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'caddx.items'
2021-05-10 19:04:46.771 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items'
2021-05-10 19:04:47.115 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'mapdb.persist'
2021-05-10 19:04:47.271 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist'
2021-05-10 19:04:47.728 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'alarm.sitemap'
2021-05-10 19:04:54.694 [INFO ] [.core.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2021-05-10 19:04:54.882 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'keypad_message.rules'
2021-05-10 19:04:56.235 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Partition4.rules'
2021-05-10 19:04:57.272 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Partition1.rules'
2021-05-10 19:04:57.935 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'presence.rules'
2021-05-10 19:04:58.622 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Partition3.rules'
2021-05-10 19:04:59.231 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'pin_input.rules'
2021-05-10 19:04:59.598 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'Partition2.rules'
2021-05-10 19:05:00.010 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'silence_alarm.rules'
2021-05-10 19:05:00.220 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'harmony.rules'
2021-05-10 19:05:04.928 [INFO ] [org.openhab.ui.internal.UIService   ] - Started UI on port 8080
2021-05-10 19:05:12.073 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule engine started.
2021-05-10 19:05:17.660 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = 66424fdc-7e29-4d6a-9301-61f364f08c09, base URL = http://localhost:8080)
2021-05-10 19:05:32.002 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Started HABPanel at /habpanel

The /userdata\log\openhab.log was created at 10 ‎May ‎2021, ‏‎19:03:53 I guess before the logs get written?

Not sure what the WARN is about?

When starting not as service

C:\openHAB>start_debug.bat
Launching the openHAB runtime...
Listening for transport dt_socket at address: 5005

                           _   _     _     ____
   ___   ___   ___   ___  | | | |   / \   | __ )
  / _ \ / _ \ / _ \ / _ \ | |_| |  / _ \  |  _ \
 | (_) | (_) |  __/| | | ||  _  | / ___ \ | |_) )
  \___/|  __/ \___/|_| |_||_| |_|/_/   \_\|____/
       |_|       3.1.0.M4 - Milestone Build

Use '<tab>' for a list of available commands
and '[cmd] --help' for help on a specific command.
To exit, use '<ctrl-d>' or 'logout'.

openhab>

Also, just noticed that the /log directory is only created when starting openHAB via the service?

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.