TLS connection timeout - Unjoinable peer when sending any file
I tried over LAN and over Internet, and I can't send any file. Chat and Audio call work perfectly. I don't test video call.
- Sender: IP: 192.168.1.115 - Android One 9
- Receiver: ring:223b41a7896172f8e413bc45e08bfd8cb5a6f152 / IP: 192.168.1.118 - LineageOS v16 Android 9
- File: 11111.jpg
- Jami version: 20191115-01
This test is over LAN.
Log - 1 months expire: Log
11-21 08:24:19.109 4391 4410 I cx.ring : Background concurrent copying GC freed 20538(1201KB) AllocSpace objects, 15(17MB) LOS objects, 49% free, 12MB/24MB, paused 204us total 113.400ms
11-21 08:24:20.295 4391 4410 I cx.ring : Background concurrent copying GC freed 5136(609KB) AllocSpace objects, 15(17MB) LOS objects, 49% free, 12MB/24MB, paused 232us total 125.868ms
11-21 08:24:21.302 4391 4410 I cx.ring : Background concurrent copying GC freed 5218(591KB) AllocSpace objects, 15(17MB) LOS objects, 49% free, 12MB/24MB, paused 210us total 118.501ms
11-21 08:24:23.638 12758 12816 I ProvidedOAuthMetricsTransport: Acquiring wake lock with timeout: 300000ms
11-21 08:24:23.638 12758 12817 I ProvidedOAuthMetricsTransport: Acquiring wake lock with timeout: 300000ms
11-21 08:24:23.640 12758 12816 I ProvidedOAuthMetricsTransport: Acquiring wifi lock
11-21 08:24:23.640 12758 12817 I ProvidedOAuthMetricsTransport: Acquiring wifi lock
11-21 08:24:24.006 12758 12816 I ProvidedOAuthMetricsTransport: Acquiring wake lock with timeout: 300000ms
11-21 08:24:24.008 12758 12816 I ProvidedOAuthMetricsTransport: Acquiring wifi lock
11-21 08:24:24.369 2885 9451 I ActivityManager: START u0 {act=android.intent.action.EDIT dat=content://cx.ring/accounts/2f57884026fb6691 cmp=cx.ring/.account.AccountEditionActivity} from uid 10467
11-21 08:24:39.634 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:24:45.212 17086 19173 I PTCommittedOperation: Receive new configuration for com.google.android.gms.learning.platformservices.sharing
11-21 08:24:45.503 17086 18967 I GCoreUlr: WorldUpdater:com.google.gservices.intent.action.GSERVICES_CHANGED: Ensuring that reporting is stopped because of reasons: {account#-13#=[InactiveReason{mIdentifier=6, mName='ReportingNotEnabled'}, InactiveReason{mIdentifier=14, mName='HistoryNotEnabled'}]}
11-21 08:24:45.536 17086 18967 I GCoreUlr: DispatchingService ignoring Intent { act=android.net.wifi.WIFI_STATE_CHANGED flg=0x4000010 (has extras) } because ULR inactive
11-21 08:24:45.824 18601 18639 I PlayCommon: [932] ajya.c(29): Preparing logs for uploading
11-21 08:24:52.281 17074 17074 W MediaRouter: Ignoring invalid provider descriptor: null
11-21 08:25:51.244 18437 18437 I CarrierServices: [2] cnv.onReceive: Received SERVICE_STATE intent, clearing cached cell info
11-21 08:25:51.302 18437 18437 I CarrierServices: [2] cnv.onReceive: Received SERVICE_STATE intent, clearing cached cell info
11-21 08:25:52.661 2885 5028 I ActivityManager: START u0 {act=android.intent.action.EDIT dat=content://cx.ring/accounts/2f57884026fb6691 cmp=cx.ring/.account.AccountEditionActivity} from uid 10467
11-21 08:26:08.294 17074 17074 W MediaRouter: Ignoring invalid provider descriptor: null
11-21 08:29:22.463 17074 17526 I PlayCommon: [8173] gch.c: Preparing logs for uploading
11-21 08:29:22.537 17074 17526 W PlayCommon: [8173] gch.c: Deferring log upload because couldn't retrieve auth token
11-21 08:29:25.484 17074 17528 I PlayCommon: [8175] gch.c: Preparing logs for uploading
11-21 08:29:25.523 17074 17528 W PlayCommon: [8175] gch.c: Deferring log upload because couldn't retrieve auth token
11-21 08:30:32.878 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:30:32.879 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:30:32.911 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:30:32.911 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:30:32.912 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:30:32.936 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:31:15.033 4391 4431 D libdring: [Account 2f57884026fb6691] tracking buddy 223b41a7896172f8e413bc45e08bfd8cb5a6f152
11-21 08:31:15.035 4391 4431 D libdring: [Account 2f57884026fb6691] tracking buddy 4f170adf2c535646cb144936615deab37c1pa490
11-21 08:31:15.036 4391 4431 D libdring: [Account 2f57884026fb6691] tracking buddy d48722aff1c6eebfc9640214819a98e7d557ab20
11-21 08:31:16.140 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:31:16.141 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:31:16.178 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:31:16.184 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:31:16.185 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:31:16.210 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:31:16.215 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:31:16.216 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:31:16.240 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:31:16.561 4391 14556 D libdring: Buddy 4f170adf2c535646cb144936615deab37c1pa490 online
11-21 08:31:16.576 4391 14556 D libdring: Buddy 223b41a7896172f8e413bc45e08bfd8cb5a6f152 online
11-21 08:31:16.705 4391 14556 D libdring: Buddy d48722aff1c6eebfc9640214819a98e7d557ab20 online
11-21 08:31:17.714 19322 19347 I ActivityThread: com.android.settings.suggestions.status acquiringCount 2
11-21 08:31:17.714 19322 19346 I ActivityThread: com.android.settings.suggestions.status acquiringCount 3
11-21 08:31:17.714 19322 19350 I ActivityThread: com.android.settings.suggestions.status acquiringCount 4
11-21 08:31:17.714 19322 19349 I ActivityThread: com.android.settings.suggestions.status acquiringCount 5
11-21 08:31:34.925 19405 19407 I adbd : registering usb transport
11-21 08:31:56.432 2885 3003 I DreamManagerService: Entering dreamland.
11-21 08:32:23.403 19405 19407 I adbd : registering usb transport
11-21 08:32:33.993 17074 17074 W MediaRouter: Ignoring invalid provider descriptor: null
11-21 08:32:41.071 2885 3412 I InputReader: Reconfiguring input devices. changes=0x00000004
11-21 08:32:41.177 18437 18437 I CarrierServices: [2] cnv.onReceive: Received SERVICE_STATE intent, clearing cached cell info
11-21 08:32:41.214 18437 18437 I CarrierServices: [2] cnv.onReceive: Received SERVICE_STATE intent, clearing cached cell info
11-21 08:32:51.024 2885 3003 I DreamManagerService: Entering dreamland.
11-21 08:33:13.380 2885 3003 I DreamManagerService: Entering dreamland.
11-21 08:33:13.586 3491 3491 W View : requestLayout() improperly called by com.android.keyguard.CarrierText{6956b23 V.ED..... ..S...ID 48,0-630,120 #7f0a0198 app:id/keyguard_carrier_text} during layout: running second layout pass
11-21 08:34:18.395 2885 3445 I ActivityManager: START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=cx.ring/.client.HomeActivity bnds=[289,1037][511,1293]} from uid 10024
11-21 08:34:18.442 2885 3445 D ActivityTrigger: activityResumeTrigger: The activity in ApplicationInfo{a9f85d9 cx.ring} is now in focus and seems to be in full-screen mode
11-21 08:34:18.442 2885 3445 E ActivityTrigger: activityResumeTrigger: not whiteListedcx.ring/cx.ring.client.HomeActivity/200
11-21 08:34:18.447 2885 3445 D ActivityTrigger: activityResumeTrigger: The activity in ApplicationInfo{a9f85d9 cx.ring} is now in focus and seems to be in full-screen mode
11-21 08:34:18.447 2885 3445 E ActivityTrigger: activityResumeTrigger: not whiteListedcx.ring/cx.ring.client.HomeActivity/200
11-21 08:34:18.455 2885 3445 D ActivityTrigger: activityResumeTrigger: The activity in ApplicationInfo{a9f85d9 cx.ring} is now in focus and seems to be in full-screen mode
11-21 08:34:18.455 2885 3445 E ActivityTrigger: activityResumeTrigger: not whiteListedcx.ring/cx.ring.client.HomeActivity/200
11-21 08:34:18.478 4391 4391 D HomeActivity: onNewIntent: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=cx.ring/.client.HomeActivity bnds=[289,1037][511,1293] }
11-21 08:34:20.735 2885 9137 I ActivityManager: START u0 {act=android.intent.action.VIEW dat=content://cx.ring/conversation/2f57884026fb6691/ring:223b41a7896172f8e413bc45e08bfd8cb5a6f152 cmp=cx.ring/.client.ConversationActivity} from uid 10467
11-21 08:34:20.742 2885 9137 D ActivityTrigger: activityStartTrigger: Activity is Triggerred in full screen ApplicationInfo{a9f85d9 cx.ring}
11-21 08:34:20.742 2885 9137 E ActivityTrigger: activityStartTrigger: not whiteListedcx.ring/cx.ring.client.ConversationActivity/200
11-21 08:34:20.744 2885 9137 D ActivityTrigger: activityResumeTrigger: The activity in ApplicationInfo{a9f85d9 cx.ring} is now in focus and seems to be in full-screen mode
11-21 08:34:20.744 2885 9137 E ActivityTrigger: activityResumeTrigger: not whiteListedcx.ring/cx.ring.client.ConversationActivity/200
11-21 08:34:20.755 2885 9137 D ActivityTrigger: activityResumeTrigger: The activity in ApplicationInfo{a9f85d9 cx.ring} is now in focus and seems to be in full-screen mode
11-21 08:34:20.755 2885 9137 E ActivityTrigger: activityResumeTrigger: not whiteListedcx.ring/cx.ring.client.ConversationActivity/200
11-21 08:34:20.795 4391 4391 W ConversationPresenter: init ring:223b41a7896172f8e413bc45e08bfd8cb5a6f152 2f57884026fb6691
11-21 08:34:20.798 4391 4391 W ConversationPresenter: resume null 2f57884026fb6691 ring:223b41a7896172f8e413bc45e08bfd8cb5a6f152
11-21 08:34:20.822 4391 4391 W View : requestLayout() improperly called by androidx.recyclerview.widget.RecyclerView{a179b02 VFED..... ......ID 0,240-1080,1920 #7f0a017b app:id/hist_list} during layout: running second layout pass
11-21 08:34:20.891 2885 2999 I ActivityManager: Displayed cx.ring/.client.ConversationActivity: +130ms
11-21 08:34:23.544 19817 19817 V NativeCrypto: Registering org/conscrypt/NativeCrypto's 286 native methods...
11-21 08:34:27.169 2885 10954 D ActivityTrigger: activityResumeTrigger: The activity in ApplicationInfo{a9f85d9 cx.ring} is now in focus and seems to be in full-screen mode
11-21 08:34:27.169 2885 10954 E ActivityTrigger: activityResumeTrigger: not whiteListedcx.ring/cx.ring.client.ConversationActivity/200
11-21 08:34:27.190 4391 4391 W ConversationPresenter: resume cx.ring.model.Conversation@6a55493 2f57884026fb6691 ring:223b41a7896172f8e413bc45e08bfd8cb5a6f152
11-21 08:34:27.217 4391 19865 D FileUtils: moveFile: moved /data/user/0/cx.ring/cache/11111.jpg to /data/user/0/cx.ring/files/conversation_data/223b41a7896172f8e413bc45e08bfd8cb5a6f152/77_98b9fd61901a6102fc10b47776caf4ff1ebce23e.jpg
11-21 08:34:27.218 4391 19865 I AccountService: sendFile() id=77 accountId=2f57884026fb6691, peer=223b41a7896172f8e413bc45e08bfd8cb5a6f152, filePath=/data/user/0/cx.ring/files/conversation_data/223b41a7896172f8e413bc45e08bfd8cb5a6f152/77_98b9fd61901a6102fc10b47776caf4ff1ebce23e.jpg
11-21 08:34:27.342 4391 4410 I cx.ring : Background concurrent copying GC freed 172616(6MB) AllocSpace objects, 34(9MB) LOS objects, 59% free, 8MB/20MB, paused 167us total 104.665ms
11-21 08:34:27.604 4391 19867 D libdring: [ice] added host stun server
11-21 08:34:27.604 4391 19867 D libdring: [ice] added host stun server
11-21 08:34:27.604 4391 19867 D libdring: Registered thread 0x6fc7348108 (0x1127)
11-21 08:34:27.608 4391 14556 D libdring: Found 1 devices for 223b41a7896172f8e413bc45e08bfd8cb5a6f152
11-21 08:34:27.709 4391 19867 D libdring: [ice] added turn server '192.252.140.235', port 3478
11-21 08:34:27.721 4391 19869 D libdring: Registered thread 0x6fde686d88 (0x1127)
11-21 08:34:28.062 4391 19869 D libdring: [ice:0x6fc68bc000] initialization success
11-21 08:34:28.062 4391 19869 D libdring: ICE as slave
11-21 08:34:28.066 4391 19869 D libdring: [ice:0x6fc68bc000] (local) ufrag=04575214, pwd=584f038d68e934487b4a1382
11-21 08:34:28.066 4391 19867 D libdring: [Account 2f57884026fb6691] using public IP: my.public.ip.98
11-21 08:34:28.069 4391 19867 D libdring: [ice:0x6fc68bc000] succeed to add candidate for comp_id=1 : 192.168.1.115 : my.public.ip.98
11-21 08:34:28.069 4391 19867 D libdring: [Account 2f57884026fb6691] [CNX] request connection to 83b62f7ef78656b34b52b1b2215ca7776df19070
11-21 08:34:31.215 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:34:31.216 17086 30673 E GCM : Failed to get app standby bucket for: cx.ring:0
11-21 08:34:31.235 4391 4431 W libdring: [Account 2f57884026fb6691] pushNotificationReceived: 46459832812
11-21 08:34:31.699 4391 4447 D libdring: [Account 2f57884026fb6691] [CNX] rx DHT reply from 83b62f7ef78656b34b52b1b2215ca7776df19070
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: He22a84ac 1 TCP 2130706431 2806:107e:13:5bda:acf4:bab4:d09e:d6b6 36565 typ host tcptype passive
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: He22a84ac 1 TCP 2130706431 2806:107e:13:5bda:acf4:bab4:d09e:d6b6 9 typ host tcptype active
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: Hc0a80176 1 TCP 2130706431 192.168.1.118 34677 typ host tcptype passive
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: Hc0a80176 1 TCP 2130706431 192.168.1.118 9 typ host tcptype active
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: R9e45cb33 1 TCP 16777215 158.69.203.51 14014 typ relay tcptype passive
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: Sc0a80176 1 TCP 1694498815 my.public.ip.98 34677 typ srflx tcptype passive
11-21 08:34:31.700 4391 19867 D libdring: [Account:2f57884026fb6691] add remote ICE candidate: Pe22a84ac 1 TCP 1862270975 2806:107e:13:5bda:acf4:bab4:d09e:d6b6 45788 typ prflx tcptype passive
11-21 08:34:31.700 4391 19867 D libdring: [ice:0x6fe525d6b8] negotiation starting (7 remote candidates)
11-21 08:34:31.834 4391 19869 D libdring: [ice:0x6fc68bc000] negotiation success
11-21 08:34:31.834 4391 19869 D libdring: [ice:0x6fc68bc000] connection pairs (local <-> remote):
11-21 08:34:31.834 4391 19869 D libdring: [0] [2806:107e:13:5bda:7c41:8a4d:2134:a0df]:39646 <-> [2806:107e:13:5bda:acf4:bab4:d09e:d6b6]:45788
11-21 08:34:31.835 4391 19867 D libdring: [Account:2f57884026fb6691] ICE negotiation succeed. Starting file transfer
11-21 08:34:31.835 4391 19867 D libdring: ICE as master
11-21 08:34:31.835 4391 19867 D libdring: [Account 2f57884026fb6691] [CNX] start TLS session
11-21 08:34:31.836 4391 19875 D libdring: [TLS] Start client session
11-21 08:34:31.938 4391 19875 D libdring: [TLS] User identity loaded
11-21 08:34:31.938 4391 19875 D libdring: [TLS] handshake
11-21 08:34:32.822 4391 19875 D libdring: Registered thread 0x6fc5828808 (0x1127)
11-21 08:34:34.852 4391 19867 W libdring: TLS connection timeout from peer 83b62f7ef78656b34b52b1b2215ca7776df19070: Invalid state in TlsSession::waitForReady
11-21 08:34:34.852 4391 19875 E libdring: [TLS] handshake failed: The TLS connection was non-properly terminated.
11-21 08:34:34.852 4391 19875 D libdring: [TLS] shutdown