Skip to content

receive times out on 0.13.14, leads to error on 0.13.11 #1758

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom
Open
Tsjippy opened this issue Apr 27, 2025 · 1 comment
Open

receive times out on 0.13.14, leads to error on 0.13.11 #1758

Tsjippy opened this issue Apr 27, 2025 · 1 comment

Comments

@Tsjippy
Copy link

Tsjippy commented Apr 27, 2025 •
edited
Loading 

signal-cli -v receive -t 30
2025-04-27T06:16:24.885+0100 [main] DEBUG org.asamk.signal.App - Starting signal-cli 0.13.14
2025-04-27T06:16:24.989+0100 [main] DEBUG org.asamk.signal.util.IOUtils - XDG_DATA_HOME not set, falling back to home dir
2025-04-27T06:16:27.676+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
2025-04-27T06:16:28.371+0100 [main] INFO  com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@f5bde6
2025-04-27T06:16:28.383+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
2025-04-27T06:16:33.059+0100 [main] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:1439262] connect()
2025-04-27T06:16:44.665+0100 [Thread-0] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:1439262] disconnect()
2025-04-27T06:16:45.312+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Shutdown initiated...
2025-04-27T06:16:45.325+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Shutdown completed.
Error while checking account MYPHONENUMBER: java.util.concurrent.TimeoutException: The source did not signal an event for 10 seconds and has been terminated.
org.whispersystems.signalservice.api.push.exceptions.PushNetworkException: java.util.concurrent.TimeoutException: The source did not signal an event for 10 seconds and has been terminated.
       at org.whispersystems.signalservice.api.NetworkResult$Companion.fromWebSocket(NetworkResult.kt:93)
       at org.whispersystems.signalservice.api.NetworkResult$Companion.fromWebSocketRequest-exY8QGI(NetworkResult.kt:145)
       at org.whispersystems.signalservice.api.NetworkResult$Companion.fromWebSocketRequest-Wn2Vu4Y(NetworkResult.kt:126)
       at org.whispersystems.signalservice.api.NetworkResult$Companion.fromWebSocketRequest-Wn2Vu4Y$default(NetworkResult.kt:119)
       at org.whispersystems.signalservice.api.NetworkResult$Companion.fromWebSocketRequest(NetworkResult.kt:109)
       at org.whispersystems.signalservice.api.account.AccountApi.setAccountAttributes(AccountApi.kt:76)
       at org.asamk.signal.manager.helper.AccountHelper.updateAccountAttributes(AccountHelper.java:517)
       at org.asamk.signal.manager.helper.AccountHelper.checkAccountState(AccountHelper.java:96)
       at org.asamk.signal.manager.internal.ManagerImpl.checkAccountState(ManagerImpl.java:232)
       at org.asamk.signal.manager.SignalAccountFiles.initManager(SignalAccountFiles.java:122)
       at org.asamk.signal.manager.SignalAccountFiles.initManager(SignalAccountFiles.java:84)
       at org.asamk.signal.App.loadManager(App.java:318)
       at org.asamk.signal.App.handleLocalCommand(App.java:280)
       at org.asamk.signal.App.handleCommand(App.java:182)
       at org.asamk.signal.App.init(App.java:147)
       at org.asamk.signal.Main.main(Main.java:56)
Caused by: java.util.concurrent.TimeoutException: The source did not signal an event for 10 seconds and has been terminated.
       at io.reactivex.rxjava3.internal.operators.single.SingleTimeout$TimeoutMainObserver.run(SingleTimeout.java:123)
       at io.reactivex.rxjava3.core.Scheduler$DisposeTask.run(Scheduler.java:644)
       at io.reactivex.rxjava3.internal.schedulers.ScheduledRunnable.run(ScheduledRunnable.java:65)
       at io.reactivex.rxjava3.internal.schedulers.ScheduledRunnable.call(ScheduledRunnable.java:56)
       at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:317)
       at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
       at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
       at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
       at java.base/java.lang.Thread.run(Thread.java:1583)

2025-04-27T06:22:43.893+0100 [main] DEBUG org.asamk.signal.App - Starting signal-cli 0.13.11
2025-04-27T06:22:43.984+0100 [main] DEBUG org.asamk.signal.util.IOUtils - XDG_DATA_HOME not set, falling back to home dir
2025-04-27T06:22:46.569+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
2025-04-27T06:22:47.266+0100 [main] INFO  com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection org.sqlite.jdbc4.JDBC4Connection@1c67247
2025-04-27T06:22:47.277+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
2025-04-27T06:23:06.541+0100 [main] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:19984264] connect()
2025-04-27T06:23:06.595+0100 [main] INFO  LibSignal - [OkHttpWebSocketConnection]: [unidentified:2595401] connect()
2025-04-27T06:23:06.920+0100 [main] DEBUG o.a.s.manager.helper.ReceiveHelper - Handling message actions
2025-04-27T06:23:06.941+0100 [main] DEBUG o.a.s.manager.helper.ReceiveHelper - Checking for new message from server
2025-04-27T06:23:07.473+0100 [OkHttp https://chat.signal.org/...] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:19984264] onOpen() connected
2025-04-27T06:23:07.476+0100 [RxComputationThreadPool-4] DEBUG o.a.s.m.i.SignalWebSocketHealthMonitor - WebSocket is now connected
2025-04-27T06:23:07.479+0100 [OkHttp https://chat.signal.org/...] INFO  LibSignal - [OkHttpWebSocketConnection]: [unidentified:2595401] onOpen() connected
2025-04-27T06:23:07.481+0100 [RxComputationThreadPool-3] DEBUG o.a.s.m.i.SignalWebSocketHealthMonitor - WebSocket is now connected

....

...
2025-04-27T06:23:15.716+0100 [main] WARN  o.a.signal.manager.helper.SyncHelper - Sync contacts contained invalid contact, ignoring: Missing contact address!
2025-04-27T06:23:15.908+0100 [main] WARN  o.a.signal.manager.helper.SyncHelper - Sync contacts contained invalid contact, ignoring: Missing contact address!
2025-04-27T06:23:16.056+0100 [main] WARN  o.a.signal.manager.helper.SyncHelper - Sync contacts contained invalid contact, ignoring: Missing contact address!
2025-04-27T06:23:16.100+0100 [main] WARN  o.a.signal.manager.helper.SyncHelper - Sync contacts contained invalid contact, ignoring: Missing contact address!
2025-04-27T06:23:16.174+0100 [main] WARN  o.a.signal.manager.helper.SyncHelper - Sync contacts contained invalid contact, ignoring: Missing contact address!
2025-04-27T06:23:16.631+0100 [main] WARN  o.a.signal.manager.helper.SyncHelper - Sync contacts contained invalid contact, ignoring: Missing contact address!
2025-04-27T06:23:17.046+0100 [main] DEBUG o.a.s.manager.helper.ReceiveHelper - Handling message actions
2025-04-27T06:23:17.047+0100 [main] DEBUG o.a.s.manager.helper.ReceiveHelper - Executing action SyncStorageDataAction
2025-04-27T06:23:17.051+0100 [main] DEBUG o.a.s.manager.internal.JobExecutor - Running SyncStorageJob job
2025-04-27T06:23:17.058+0100 [main] DEBUG o.a.s.manager.helper.ReceiveHelper - Executing action SendReceiptAction
2025-04-27T06:23:18.868+0100 [main] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:19984264] disconnect()
2025-04-27T06:23:18.870+0100 [main] INFO  LibSignal - [OkHttpWebSocketConnection]: [unidentified:2595401] disconnect()
2025-04-27T06:23:19.092+0100 [OkHttp https://chat.signal.org/...] INFO  LibSignal - [OkHttpWebSocketConnection]: [unidentified:2595401] onClosing()
2025-04-27T06:23:19.093+0100 [OkHttp https://chat.signal.org/...] INFO  LibSignal - [OkHttpWebSocketConnection]: [unidentified:2595401] onClose()
2025-04-27T06:23:19.100+0100 [OkHttp https://chat.signal.org/...] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:19984264] onClosing()
2025-04-27T06:23:19.102+0100 [OkHttp https://chat.signal.org/...] INFO  LibSignal - [OkHttpWebSocketConnection]: [normal:19984264] onClose()
2025-04-27T06:23:24.105+0100 [pool-2-thread-1] WARN  o.a.s.manager.helper.StorageHelper - Manifest couldn't be decrypted.
2025-04-27T06:23:24.395+0100 [pool-2-thread-1] INFO  LibSignal - [SignalServiceMessageSender]: [sendMessage][1745731404131] Pipe unavailable, falling back... (WebSocketUnavailableException: WebSocket not currently available.)
2025-04-27T06:23:24.695+0100 [pool-2-thread-1] DEBUG o.a.s.manager.helper.StorageHelper - Done syncing data with remote storage
2025-04-27T06:23:24.698+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Shutdown initiated...
2025-04-27T06:23:24.780+0100 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Shutdown completed.
2025-04-27T06:23:24.781+0100 [msl-cleanup] DEBUG o.a.s.m.s.s.MessageSendLogStore - Stopping msl cleanup thread
java.lang.OutOfMemoryError: Java heap space
        at org.whispersystems.signalservice.api.messages.multidevice.DeviceContactsInputStream.read(DeviceContactsInputStream.java:40)
        at org.asamk.signal.manager.helper.SyncHelper.handleSyncDeviceContacts(SyncHelper.java:366)
        at org.asamk.signal.manager.helper.IncomingMessageHandler$$Lambda/0x61f53c10.handle(Unknown Source)
        at org.asamk.signal.manager.helper.AttachmentHelper.retrieveAttachment(AttachmentHelper.java:119)
        at org.asamk.signal.manager.helper.IncomingMessageHandler.handleSyncMessage(IncomingMessageHandler.java:549)
        at org.asamk.signal.manager.helper.IncomingMessageHandler.handleMessage(IncomingMessageHandler.java:379)
        at org.asamk.signal.manager.helper.IncomingMessageHandler.checkAndHandleMessage(IncomingMessageHandler.java:271)
        at org.asamk.signal.manager.helper.IncomingMessageHandler.handleEnvelope(IncomingMessageHandler.java:189)
        at org.asamk.signal.manager.helper.ReceiveHelper.receiveMessagesInternal(ReceiveHelper.java:216)
        at org.asamk.signal.manager.helper.ReceiveHelper.receiveMessages(ReceiveHelper.java:107)
        at org.asamk.signal.manager.internal.ManagerImpl.receiveMessages(ManagerImpl.java:1326)
        at org.asamk.signal.manager.internal.ManagerImpl.receiveMessages(ManagerImpl.java:1295)
        at org.asamk.signal.commands.ReceiveCommand.handleCommand(ReceiveCommand.java:88)
        at org.asamk.signal.commands.CommandHandler.handleLocalCommand(CommandHandler.java:37)
        at org.asamk.signal.App.handleLocalCommand(App.java:278)
        at org.asamk.signal.App.handleCommand(App.java:179)
        at org.asamk.signal.App.init(App.java:144)
        at org.asamk.signal.Main.main(Main.java:56)
2025-04-27T06:23:24.796+0100 [Thread-0] DEBUG org.asamk.signal.Shutdown - JVM is shutting down
2025-04-27T06:23:24.797+0100 [Thread-0] DEBUG org.asamk.signal.Shutdown - Triggering shutdown.
2025-04-27T06:23:24.799+0100 [Thread-0] DEBUG org.asamk.signal.Shutdown - Waiting for app to shut down
2025-04-27T06:23:24.800+0100 [Thread-0] DEBUG org.asamk.signal.Shutdown - Exiting
@Tsjippy
Copy link
Author

Tsjippy commented Apr 30, 2025

I used the command signal-cli deleteLocalAccountData --ignore-registered to delete the account and linked it again using signal-cli link. But it running signal-cli receive or signal-cli updateAccount leads to the same error:

INFO  AccountHelper - The Signal protocol expects that incoming messages are regularly received.
Error while checking account +MYNUMBER: java.util.concurrent.TimeoutException: The source did not signal an event for 10 seconds and has been terminated.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@Tsjippy