ok, jetzt habe ich das Format der json angepasst wie im anderen thread - und ja das sieht wirklich nach einem crash des backends aus, wenn ich mir die pids ansehe.
ich sehe aber nach wie vor keinen grund, weshalb das backend crasht und kein logeintrag, obwohl beide edge und backend- sockets im debug laufen. Ist hier ein Fehler im Programm?
ich füge nochmal die vollständigen Logs bei:
Apr 30 11:42:54 obel1x systemd[1]: Started OpenEMS Backend.
Apr 30 11:42:55 obel1x java[24016]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Initializing Log4j2 using default configuration
Apr 30 11:42:56 obel1x java[24016]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Scanning for classes in '/org/ops4j/pax/logging/log4j2/internal/bridges/' matching criteria annotated with @Plugin
Apr 30 11:42:56 obel1x java[24016]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Log4J2 configured using default configuration.
Apr 30 11:42:56 obel1x java[24016]: io.openems.backend.application [io.openems.backend.application.BackendApp] INFO : ==========================================
Apr 30 11:42:56 obel1x java[24016]: io.openems.backend.application [io.openems.backend.application.BackendApp] INFO : OpenEMS Backend version [2022.4.0] started
Apr 30 11:42:56 obel1x java[24016]: io.openems.backend.application [io.openems.backend.application.BackendApp] INFO : ==========================================
Apr 30 11:42:56 obel1x java[24016]: io.openems.backend.metadata.file [io.openems.backend.metadata.file.FileMetadata] INFO : Activate [path=/etc/openems-backend.d/metadata.json]
Apr 30 11:42:56 obel1x java[24016]: io.openems.backend.timedata.dummy [io.openems.backend.timedata.dummy.TimedataDummy] INFO : [Timedata.Dummy] Activate
Apr 30 11:42:56 obel1x java[24016]: io.openems.common [io.openems.common.websocket.AbstractWebsocketServer] INFO : Starting [Edge.Websocket] websocket server [port=8081]
Apr 30 11:42:56 obel1x java[24016]: io.openems.common [io.openems.common.websocket.AbstractWebsocketServer] INFO : Starting [Ui.Websocket] websocket server [port=8082]
Apr 30 11:42:56 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.util.log] INFO : Logging initialized @2416ms to org.eclipse.jetty.util.log.Slf4jLog
Apr 30 11:42:56 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.Server] INFO : jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 11.0.14.1+1-suse-3.77.5-x8664
Apr 30 11:42:56 obel1x java[24016]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Log4J2 configured using configuration from passed properties
Apr 30 11:42:56 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : DefaultSessionIdManager workerName=node0
Apr 30 11:42:56 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : No SessionScavenger set, using defaults
Apr 30 11:42:56 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : node0 Scavenging every 600000ms
Apr 30 11:42:57 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.handler.ContextHandler] INFO : Started o.e.j.s.ServletContextHandler@13ea3ca3{/,null,AVAILABLE}
Apr 30 11:42:57 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.Server] INFO : Started @2595ms
Apr 30 11:42:57 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : node0 Scavenging every 660000ms
Apr 30 11:42:57 obel1x java[24016]: org.apache.felix.http.jetty [org.eclipse.jetty.server.AbstractConnector] INFO : Started ServerConnector@688b0d20{HTTP/1.1, (http/1.1)}{0.0.0.0:8079}
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,045 [artLevel] INFO [org.apache.felix.http.jetty ] Started Jetty 9.4.44.v20210927 at port(s) HTTP:8079 on context path / [minThreads=8,maxThreads=200,acceptors=1,selectors=1]
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,271 [artLevel] INFO [pax.logging.internal.Activator] Enabling Java Util Logging API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,272 [artLevel] INFO [pax.logging.internal.Activator] Enabling SLF4J API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,273 [artLevel] INFO [pax.logging.internal.Activator] Enabling Apache Commons Logging API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,275 [artLevel] INFO [pax.logging.internal.Activator] Enabling JULI Logger API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,276 [artLevel] INFO [pax.logging.internal.Activator] Enabling Avalon Logger API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,283 [artLevel] INFO [pax.logging.internal.Activator] Enabling JBoss Logging API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,286 [artLevel] INFO [pax.logging.internal.Activator] Enabling Log4J v1 API support.
Apr 30 11:42:57 obel1x java[24016]: 2022-04-30T11:42:57,296 [artLevel] INFO [pax.logging.internal.Activator] Enabling Log4J v2 API support.
Apr 30 11:43:06 obel1x java[24016]: 2022-04-30T11:43:06,592 [thread-1] INFO [socket.AbstractWebsocketServer] [Edge.Websocket] [monitor] Pool: 0, Active: 0, Pending: 0, Completed: 0
Apr 30 11:43:06 obel1x java[24016]: 2022-04-30T11:43:06,635 [thread-1] INFO [dgewebsocket.EdgeWebsocketImpl] [monitor] Edge-Connections: 0
Apr 30 11:43:06 obel1x java[24016]: 2022-04-30T11:43:06,693 [thread-1] INFO [socket.AbstractWebsocketServer] [Ui.Websocket] [monitor] Pool: 1, Active: 0, Pending: 0, Completed: 1
Apr 30 11:43:06 obel1x java[24016]: 2022-04-30T11:43:06,697 [thread-1] INFO [common.uiwebsocket.UiWebsocket] [monitor] UI-Connections: 1
Apr 30 11:43:16 obel1x java[24016]: 2022-04-30T11:43:16,594 [thread-1] INFO [socket.AbstractWebsocketServer] [Edge.Websocket] [monitor] Pool: 0, Active: 0, Pending: 0, Completed: 0
Apr 30 11:43:16 obel1x java[24016]: 2022-04-30T11:43:16,636 [thread-1] INFO [dgewebsocket.EdgeWebsocketImpl] [monitor] Edge-Connections: 0
Apr 30 11:43:16 obel1x java[24016]: 2022-04-30T11:43:16,694 [thread-1] INFO [socket.AbstractWebsocketServer] [Ui.Websocket] [monitor] Pool: 1, Active: 0, Pending: 0, Completed: 1
Apr 30 11:43:16 obel1x java[24016]: 2022-04-30T11:43:16,698 [thread-1] INFO [common.uiwebsocket.UiWebsocket] [monitor] UI-Connections: 1
Apr 30 11:43:26 obel1x java[24016]: 2022-04-30T11:43:26,595 [thread-1] INFO [socket.AbstractWebsocketServer] [Edge.Websocket] [monitor] Pool: 0, Active: 0, Pending: 0, Completed: 0
Apr 30 11:43:26 obel1x java[24016]: 2022-04-30T11:43:26,636 [thread-1] INFO [dgewebsocket.EdgeWebsocketImpl] [monitor] Edge-Connections: 0
Apr 30 11:43:26 obel1x java[24016]: 2022-04-30T11:43:26,694 [thread-1] INFO [socket.AbstractWebsocketServer] [Ui.Websocket] [monitor] Pool: 1, Active: 0, Pending: 0, Completed: 1
Apr 30 11:43:26 obel1x java[24016]: 2022-04-30T11:43:26,698 [thread-1] INFO [common.uiwebsocket.UiWebsocket] [monitor] UI-Connections: 1
Apr 30 11:43:28 obel1x systemd[1]: Starting OpenEMS Edge...
Apr 30 11:43:31 obel1x java[24073]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Initializing Log4j2 using default configuration
Apr 30 11:43:31 obel1x java[24073]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Scanning for classes in '/org/ops4j/pax/logging/log4j2/internal/bridges/' matching criteria annotated with @Plugin
Apr 30 11:43:31 obel1x java[24073]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Log4J2 configured using default configuration.
Apr 30 11:43:31 obel1x java[24073]: org.ops4j.pax.logging.pax-logging-log4j2 [org.ops4j.pax.logging.spi.support.EventAdminConfigurationNotifier] INFO : Logging configuration changed. (Event Admin service unavailable - no notification sent).
Apr 30 11:43:31 obel1x java[24073]: io.openems.edge.application [io.openems.edge.application.EdgeApp] INFO : ==================================
Apr 30 11:43:31 obel1x java[24073]: io.openems.edge.application [io.openems.edge.application.EdgeApp] INFO : OpenEMS version [2022.4.0] started
Apr 30 11:43:31 obel1x java[24073]: io.openems.edge.application [io.openems.edge.application.EdgeApp] INFO : ==================================
Apr 30 11:43:31 obel1x systemd[1]: Started OpenEMS Edge.
Apr 30 11:43:32 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_power] Activate Ess.Power
Apr 30 11:43:32 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [ctrlDebugLog0] Activate DISABLED Controller.Debug.Log
Apr 30 11:43:32 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_evcsSlowPowerIncreaseFilter] Activate Evcs.SlowPowerIncreaseFilter
Apr 30 11:43:32 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_host] Activate Core.Host
Apr 30 11:43:32 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_meta] Activate Core.Meta
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.util.log] INFO : Logging initialized @4157ms to org.eclipse.jetty.util.log.Slf4jLog
Apr 30 11:43:33 obel1x java[24073]: org.ops4j.pax.logging.pax-logging-log4j2 [log4j2] INFO : Log4J2 configured using configuration from passed properties
Apr 30 11:43:33 obel1x java[24073]: org.ops4j.pax.logging.pax-logging-log4j2 [org.ops4j.pax.logging.spi.support.EventAdminConfigurationNotifier] INFO : Sending Event Admin notification (configuration successful) to org/ops4j/pax/logging/Configuration
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.Server] INFO : jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 11.0.14.1+1-suse-3.77.5-x8664
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : DefaultSessionIdManager workerName=node0
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : No SessionScavenger set, using defaults
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : node0 Scavenging every 660000ms
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.handler.ContextHandler] INFO : Started o.e.j.s.ServletContextHandler@39389cf3{/,null,AVAILABLE}
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.Server] INFO : Started @4352ms
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.session] INFO : node0 Scavenging every 600000ms
Apr 30 11:43:33 obel1x java[24073]: org.apache.felix.http.jetty [org.eclipse.jetty.server.AbstractConnector] INFO : Started ServerConnector@2eb6b162{HTTP/1.1, (http/1.1)}{0.0.0.0:8080}
Apr 30 11:43:33 obel1x java[24073]: 2022-04-30T11:43:33,452 [artLevel] INFO [org.apache.felix.http.jetty ] Started Jetty 9.4.44.v20210927 at port(s) HTTP:8080 on context path / [minThreads=8,maxThreads=200,acceptors=1,selectors=1]
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_componentManager] Activate Core.ComponentManager
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [ctrlBalancing0] Activate Controller.Symmetric.Balancing
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [scheduler0] Activate Scheduler.AllAlphabetically
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [datasource0] Activate Simulator.Datasource.CSV.Predefined
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [meter0] Activate Simulator.GridMeter.Acting
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [ess0] Activate Simulator.EssSymmetric.Reacting
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_appManager] Activate Core.AppManager
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_predictorManager] Activate Core.PredictorManager
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_sum] Activate Core.Sum
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [_cycle] Activate Core.Cycle
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.common [io.openems.edge.common.component.AbstractOpenemsComponent] INFO : [ctrlBackend0] Activate Controller.Api.Backend
Apr 30 11:43:33 obel1x java[24073]: io.openems.common [io.openems.common.websocket.AbstractWebsocketClient] INFO : Opening connection [Controller.Api.Backend:ctrlBackend0] to websocket server [ws://localhost:8081]
Apr 30 11:43:33 obel1x java[24073]: io.openems.edge.controller.api.backend [io.openems.edge.controller.api.backend.OnOpen] INFO : [ctrlBackend0] Connected to OpenEMS Backend
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,900 [socket-0] INFO [socket.AbstractWebsocketServer] Starting [Ui.Websocket] websocket server [port=8082]
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,924 [socket-0] INFO [socket.AbstractWebsocketServer] Starting [Edge.Websocket] websocket server [port=8081]
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,939 [ector-55] ERROR [bsocket.server.WebSocketServer] Shutdown due to fatal error
Apr 30 11:43:33 obel1x java[24016]: java.net.BindException: Address already in use
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.Net.bind0(Native Method) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.Net.bind(Net.java:459) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.Net.bind(Net.java:448) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:227) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:80) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at org.java_websocket.server.WebSocketServer.doSetupSelectorAndServerThread(WebSocketServer.java:562) [Java-WebSocket-1.5.2.jar:?]
Apr 30 11:43:33 obel1x java[24016]: at org.java_websocket.server.WebSocketServer.run(WebSocketServer.java:362) [Java-WebSocket-1.5.2.jar:?]
Apr 30 11:43:33 obel1x java[24016]: at java.lang.Thread.run(Thread.java:829) [?:?]
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,958 [socket-0] INFO [socket.AbstractWebsocketServer] Starting [Ui.Websocket] websocket server [port=8082]
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,966 [socket-0] INFO [socket.AbstractWebsocketServer] Starting [Edge.Websocket] websocket server [port=8081]
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,967 [socket-0] INFO [s.backend.edgewebsocket.OnOpen] [Edge.Websocket] Edge [edge0] connected.
Apr 30 11:43:33 obel1x java[24016]: 2022-04-30T11:43:33,982 [ector-51] ERROR [bsocket.server.WebSocketServer] Shutdown due to fatal error
Apr 30 11:43:33 obel1x java[24016]: java.net.BindException: Address already in use
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.Net.bind0(Native Method) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.Net.bind(Net.java:459) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.Net.bind(Net.java:448) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:227) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:80) ~[?:?]
Apr 30 11:43:33 obel1x java[24016]: at org.java_websocket.server.WebSocketServer.doSetupSelectorAndServerThread(WebSocketServer.java:562) [Java-WebSocket-1.5.2.jar:?]
Apr 30 11:43:33 obel1x java[24016]: at org.java_websocket.server.WebSocketServer.run(WebSocketServer.java:362) [Java-WebSocket-1.5.2.jar:?]
Apr 30 11:43:33 obel1x java[24016]: at java.lang.Thread.run(Thread.java:829) [?:?]