Comments (6)
Hmm... The message is logged when a pair of message and connection from the output-queue (outqueue
) contains a transport connection with a disconnected channel.
It looks like it is possible that a connection might be re-added to outqueue
when broadcasting the "NameOwnerChanged" signal to all remaining connections.
I changed the removeConnection
call a bit and improved logging. Maybe that allows better investigation.
I also addressed the re-adding of possibly broken connections when broadcasting.
from dbus-java.
Great, thanks a lot. I'll get a new build of our app to this user and tell him to keep an eye for the same thing. As far as I know, this version of the app has been in use for a good week or so, so it may be a little while before I hear about this again.
from dbus-java.
Sadly, we have had a couple of reports of this still happening. It does appear though that the additional debugging you added is now visible.
As before, this output continues until the JVM is killed.
10 Aug 2023 23:02:40,989 [DBus-MethodCall-Receiver-4] INFO ClientServiceImpl - Registered front-end :1.57 as XXXXXXX, supports auth, interactive
10 Aug 2023 23:12:35,606 [pool-1-thread-1] WARN ClientServiceImpl - Front-end with source :1.57 hasn't pinged for at least 45000ms
10 Aug 2023 23:12:35,606 [pool-1-thread-1] INFO Main - De-registered front-end :1.57.
10 Aug 2023 23:12:42,704 [DBus-MethodCall-Receiver-1] INFO VPNImpl - Register client :1.58
10 Aug 2023 23:12:42,704 [DBus-MethodCall-Receiver-1] INFO ClientServiceImpl - Registered front-end :1.58 as XXXXXXXX, supports auth, interactive
10 Aug 2023 23:55:45,654 [DBus-MethodCall-Receiver-3] INFO ClientServiceImpl - Scheduling connect for connection id 1/vpn.logonbox.com
10 Aug 2023 23:55:45,660 [DBus-MethodCall-Receiver-3] INFO FileSecurity - Setting permissions on conf/ini/1.conf to [OWNER_READ, OWNER_WRITE]
10 Aug 2023 23:55:45,666 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=3, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@2716e3e2, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@4fd3248c] broken
10 Aug 2023 23:55:45,666 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=3, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@2716e3e2, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@4fd3248c] broken
10 Aug 2023 23:55:45,666 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=4, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@2cc29bc5, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@686f3611] broken
10 Aug 2023 23:55:45,666 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=4, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@2cc29bc5, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@686f3611] broken
10 Aug 2023 23:55:45,666 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=5, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@7876c10d, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@2e577dcb] broken
10 Aug 2023 23:55:45,666 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=5, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@7876c10d, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@2e577dcb] broken
10 Aug 2023 23:55:45,667 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=6, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@16f3c777, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@73da406] broken
10 Aug 2023 23:55:45,667 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=6, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@16f3c777, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@73da406] broken
10 Aug 2023 23:55:45,667 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=7, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@498d7345, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@5c21f69a] broken
10 Aug 2023 23:55:45,667 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=7, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@498d7345, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@5c21f69a] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=8, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@54615a16, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@3e96d908] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=8, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@54615a16, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@3e96d908] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=9, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@1c2382c4, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@4f6532fc] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=9, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@1c2382c4, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@4f6532fc] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=10, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@118a70ec, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@19dca54c] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=10, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@118a70ec, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@19dca54c] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=11, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@19e92111, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@7ffd1202] broken
10 Aug 2023 23:55:45,668 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=11, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@19e92111, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@7ffd1202] broken
10 Aug 2023 23:55:45,669 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=12, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@3d320001, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@41b8242e] broken
10 Aug 2023 23:55:45,669 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=12, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@3d320001, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@41b8242e] broken
10 Aug 2023 23:55:45,669 [DBusDaemonSenderThread] WARN DBusDaemon$DBusDaemonSenderThread - Connection to TransportConnection [id=13, channel=java.nio.channels.SocketChannel[closed], writer=org.freedesktop.dbus.spi.message.OutputStreamMessageWriter@118974a1, reader=org.freedesktop.dbus.spi.message.InputStreamMessageReader@6731990] broken
from dbus-java.
That's too bad.
Any idea on how to reproduce this? Is this a client software and the machine was send to sleep (suspend to RAM/hibernation)?
I updated the code of DBusDaemon again. This change addresses the handling of messages in outqueue
addressed for a broken connection. It also takes care that the connection is removed from the registered names and signal destinations. I'm still not sure that this will fix it.
Is it possible that you increase the log level to DEBUG
for org.freedesktop.dbus.bin.DBusDaemon
, org.freedesktop.dbus.bin.DBusDaemon.DBusDaemonSenderThread
and org.freedesktop.dbus.bin.DBusDaemon.DBusDaemonReaderThread
, this would add some more valuable logging in case the issue shows up again.
from dbus-java.
I have not personally been able to reproduce, but my colleague @ludup who is having this problem seems to get it pretty regularly just by using the app day to day. This is internal testing of the next version we had hoped to release (a VPN client, not that it matters).
The things we do know ..
- Hibernate / Sleep is most certainly involved. Looking at the logs, the text
Front-end with source :1.57 hasn't pinged for at least 45000ms
is where I believe it came out of hibernate, that's the kind of message we see from our own application when this happens. Our application has its own "ping" that we use to try and detect when one side of the connection has gone bad. - It started happening with version 4.3.1-SNAPSHOT.
- It has only been seen to happen on Mac OS, although we have limited testers so it may happen, but not yet been caught on Windows.
- Mac OS (and Windows) uses EmbeddedDaemon so we are pretty sure the issue lay there.
- Our application is split into two parts, a privileged service that runs the embedded daemon and also exports services (in the same JVM), and a non-privileged GUI (and command line tool). On Linux, the setup is the same except Embedded daemon is not used.
Anyway, thanks for the changes. I'll get another build done and we can see how this one goes. I'll also ask for log level to be increased.
Edit: I've just realised the version we were using previously was 4.2.1. So it's possible that the issue started in 4.3.0 (which we have never used here). I notice there are some others changes to the embedded daemon in that.
from dbus-java.
Sorry it's been a while, but this does seem to have gone away now. It looks like your last changes did the trick, At least it has not been reported. Closing, will re-open if more it turns up again.
from dbus-java.
Related Issues (20)
- dbus-java's Maven site is difficult to discover
- Emit meaningful error message instead of IndexOutOfBoundsException when demarshalling FileDescriptor without prerequisites satisfied HOT 5
- DBusSignal constructor is package private HOT 1
- Kotlin Interface does not work HOT 3
- NPE in org.freedesktop.dbus.connections.AbstractConnection.internalDisconnect(IOException) prevents JVM shutdown HOT 4
- Documentation example does not work correctly. HOT 1
- In version 5, `withAutoConnect(false)` appears broken. HOT 2
- Cleanup AbstractConnection
- `default` interface methods. Or not. HOT 7
- problem to execute DBusViewer HOT 3
- How to export/publish Property in interface? HOT 2
- Properties Get method endless waiting HOT 1
- Cannot connect to DBUS HOT 11
- Sending signal with byte[] fails with verbose logging HOT 1
- [Feature request] Any plan in adding a module-info ? HOT 2
- Custom ITransportProvider cannot be found by ServiceLoader HOT 4
- Something a bit wrong with `@DBusBoundProperty` HOT 9
- Incorrect code generation for systemd StartTransientUnit HOT 3
- Add dbus-java-transport-junixsocket to dbus-java-bom
- Help with DBusConnectionBuilder HOT 2
Recommend Projects
-
React
A declarative, efficient, and flexible JavaScript library for building user interfaces.
-
Vue.js
🖖 Vue.js is a progressive, incrementally-adoptable JavaScript framework for building UI on the web.
-
Typescript
TypeScript is a superset of JavaScript that compiles to clean JavaScript output.
-
TensorFlow
An Open Source Machine Learning Framework for Everyone
-
Django
The Web framework for perfectionists with deadlines.
-
Laravel
A PHP framework for web artisans
-
D3
Bring data to life with SVG, Canvas and HTML. 📊📈🎉
-
Recommend Topics
-
javascript
JavaScript (JS) is a lightweight interpreted programming language with first-class functions.
-
web
Some thing interesting about web. New door for the world.
-
server
A server is a program made to process requests and deliver data to clients.
-
Machine learning
Machine learning is a way of modeling and interpreting data that allows a piece of software to respond intelligently.
-
Visualization
Some thing interesting about visualization, use data art
-
Game
Some thing interesting about game, make everyone happy.
Recommend Org
-
Facebook
We are working to build community through open source technology. NB: members must have two-factor auth.
-
Microsoft
Open source projects and samples from Microsoft.
-
Google
Google ❤️ Open Source for everyone.
-
Alibaba
Alibaba Open Source for everyone
-
D3
Data-Driven Documents codes.
-
Tencent
China tencent open source team.
from dbus-java.