Go to:
Gentoo Home
Documentation
Forums
Lists
Bugs
Planet
Store
Wiki
Get Gentoo!
Gentoo's Bugzilla – Attachment 675001 Details for
Bug 755944
net-im/signal-desktop-bin - Audio calling does not work with media-sound/apulse
Home
|
New
–
[Ex]
|
Browse
|
Search
|
Privacy Policy
|
[?]
|
Reports
|
Requests
|
Help
|
New Account
|
Log In
[x]
|
Forgot Password
Login:
[x]
signal desktop call log
signal-desktop.log (text/plain), 91.52 KB, created by
Daniel Pielmeier
on 2020-11-25 18:41:34 UTC
(
hide
)
Description:
signal desktop call log
Filename:
MIME Type:
Creator:
Daniel Pielmeier
Created:
2020-11-25 18:41:34 UTC
Size:
91.52 KB
patch
obsolete
>INFO 2020-11-25T18:34:01.267Z onOutgoingAudioCallInConversation: about to start an audio call >INFO 2020-11-25T18:34:01.268Z onOutgoingAudioCallInConversation: call is deemed "safe". Making call >INFO 2020-11-25T18:34:01.268Z CallingClass.startCallingLobby() >INFO 2020-11-25T18:34:01.268Z CallingClass.startCallingLobby(): Getting call settings >INFO 2020-11-25T18:34:01.268Z CallingClass.startCallingLobby(): Starting lobby >INFO 2020-11-25T18:34:01.279Z MediaDevice: available devices changed (from->to) {"availableMicrophones":[{"name":"default: default_source_name","uniqueId":"default: default_source_name-0","index":0}],"availableSpeakers":[{"name":"default: default and only sink","uniqueId":"default: default and only sink-0","index":0},{"name":"default and only sink","uniqueId":"default and only sink-0","index":1}],"selectedMicrophone":{"name":"default: default_source_name","uniqueId":"default: default_source_name-0","index":0},"selectedSpeaker":{"name":"default: default and only sink","uniqueId":"default: default and only sink-0","index":0},"availableCameras":[]} >INFO 2020-11-25T18:34:01.279Z MediaDevice: selecting microphone {"name":"default: default_source_name","uniqueId":"default: default_source_name-0","index":0} >INFO 2020-11-25T18:34:01.279Z MediaDevice: selecting speaker {"name":"default: default_source_name","uniqueId":"default: default_source_name-0","index":0} >INFO 2020-11-25T18:34:01.281Z onOutgoingAudioCallInConversation: started the call >INFO 2020-11-25T18:34:01.283Z src/webrtc/peer_connection_factory.rs:480 PeerConnectionFactory::set_audio_recording_device(0) >INFO 2020-11-25T18:34:01.283Z src/webrtc/peer_connection_factory.rs:379 PeerConnectionFactory::set_audio_playout_device(0) >INFO 2020-11-25T18:34:02.291Z SQL channel job 134 (updateConversations) succeeded in 12ms >INFO 2020-11-25T18:34:02.499Z CallingClass.startCallingLobby() >INFO 2020-11-25T18:34:02.504Z CallingClass.startOutgoingCall(): Getting call settings >INFO 2020-11-25T18:34:02.505Z GET https://textsecure-service.whispersystems.org/v1/accounts/turn >INFO 2020-11-25T18:34:02.693Z GET https://textsecure-service.whispersystems.org/v1/accounts/turn 200 Success >INFO 2020-11-25T18:34:02.694Z CallingClass.startOutgoingCall(): Starting in RingRTC >INFO 2020-11-25T18:34:02.744Z src/core/call_manager.rs:371 API:create_outgoing_call(0xdf332dcfad4e5e03): >INFO 2020-11-25T18:34:02.744Z src/core/call_manager.rs:855 ringrtc! 1606329242694 app -> cm: call() 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.744Z src/core/call.rs:227 new(): call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.744Z src/core/call_fsm.rs:258 syncing worker thread: ThreadId(3) >INFO 2020-11-25T18:34:02.744Z src/core/call_fsm.rs:258 syncing notify thread: ThreadId(4) >INFO 2020-11-25T18:34:02.744Z src/core/call_fsm.rs:215 state: NotYetStarted, event: (StartCall) >INFO 2020-11-25T18:34:02.744Z src/core/call_fsm.rs:365 handle_start_call(): >INFO 2020-11-25T18:34:02.744Z src/core/call_manager.rs:1748 ringrtc! 1606329242695 cm -> app: start() 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.744Z src/native.rs:423 NativePlatform::on_start_call(): remote_peer: [REDACTED]429, call_id: 0xdf332dcfad4e5e03, direction: OutGoing, call_media_type: Audio >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:402 API:CallManager::handle_proceed(): >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:938 ringrtc! 1606329242744 app -> cm: proceed() 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:944 handle_proceed(): active call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.794Z src/core/call_fsm.rs:215 state: WaitingToProceed, event: (Proceed) >INFO 2020-11-25T18:34:02.794Z src/core/call_fsm.rs:378 handle_proceed(): >INFO 2020-11-25T18:34:02.794Z src/core/call.rs:510 proceed(): >INFO 2020-11-25T18:34:02.794Z src/native.rs:356 NativePlatform::create_connection(): call: thread: ThreadId(3), direction: OutGoing, call_id: 0xdf332dcfad4e5e03, state: "ConnectingBeforeAccepted" remote_device_id: 0 signaling_version: V2 >INFO 2020-11-25T18:34:02.794Z src/webrtc/peer_connection_observer.rs:240 pc_observer_OnSignalingDataChannel(): 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.794Z src/webrtc/data_channel.rs:57 data channel is reliable: false >INFO 2020-11-25T18:34:02.794Z src/webrtc/data_channel.rs:57 data channel is reliable: false >INFO 2020-11-25T18:34:02.794Z src/webrtc/sdp_observer.rs:436 csd_observer_OnSuccess() >INFO 2020-11-25T18:34:02.794Z src/webrtc/sdp_observer.rs:361 on_create_success() >INFO 2020-11-25T18:34:02.794Z src/core/connection.rs:513 Using V4 signaling for outgoing connection offer: ConnectionParametersV4 { public_key: Some([90, 251, 198, 128, 194, 105, 93, 59, 15, 92, 172, 64, 57, 218, 16, 42, 58, 201, 35, 193, 225, 240, 189, 235, 99, 109, 134, 17, 71, 126, 84, 42]), ice_ufrag: Some("ZZNr"), ice_pwd: Some("Dk9PhcpaE767pmum/15t+e4w"), receive_video_codecs: [VideoCodec { r#type: Some(Vp8), level: None }] } SDP: v=0 >o=- 1 1 IN IP4 127.0.0.1 >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS >m=audio 9 RTP/SAVPF 102 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ZZNr >a=ice-pwd:Dk9PhcpaE767pmum/15t+e4w >a=ice-options:trickle renomination >a=mid:audio >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:102 opus/48000/2 >a=rtcp-fb:102 transport-cc >a=fmtp:102 cbr=1;maxaveragebitrate=40000;minptime=10;stereo=0;usedtx=0;useinbandfec=1 >a=maxptime:120 >a=ptime:20 >a=ssrc:1002 cname:CNAMECNAMECNAME! >a=ssrc:1002 msid:- audio1 >a=ssrc:1002 mslabel:- >a=ssrc:1002 label:audio1 >m=video 9 RTP/SAVPF 108 118 120 121 122 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ZZNr >a=ice-pwd:Dk9PhcpaE767pmum/15t+e4w >a=ice-options:trickle renomination >a=mid:video >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:4 urn:3gpp:video-orientation >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:13 urn:ietf:params:rtp-hdrext:toffset >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:108 VP8/90000 >a=rtcp-fb:108 transport-cc >a=rtcp-fb:108 ccm fir >a=rtcp-fb:108 nack >a=rtcp-fb:108 nack pli >a=rtcp-fb:108 goog-remb >a=rtpmap:118 rtx/90000 >a=fmtp:118 apt=108 >a=rtpmap:120 red/90000 >a=rtpmap:121 rtx/90000 >a=fmtp:121 apt=120 >a=rtpmap:122 ulpfec/90000 >a=ssrc-group:FID 1003 1013 >a=ssrc:1003 cname:CNAMECNAMECNAME! >a=ssrc:1003 msid:- video1 >a=ssrc:1003 mslabel:- >a=ssrc:1003 label:video1 >a=ssrc:1013 cname:CNAMECNAMECNAME! >a=ssrc:1013 msid:- video1 >a=ssrc:1013 mslabel:- >a=ssrc:1013 label:video1 >m=application 9 RTP/SAVPF 101 >c=IN IP4 0.0.0.0 >b=AS:30 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ZZNr >a=ice-pwd:Dk9PhcpaE767pmum/15t+e4w >a=ice-options:trickle renomination >a=mid:data >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:101 google-data/90000 >a=ssrc:1001 cname:CNAMECNAMECNAME! >a=ssrc:1001 msid:signaling signaling >a=ssrc:1001 mslabel:signaling >a=ssrc:1001 label:signaling > original SDP: v=0 >o=- 667395881565342527 2 IN IP4 127.0.0.1 >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS s >m=audio 9 UDP/TLS/RTP/SAVPF 111 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ZZNr >a=ice-pwd:Dk9PhcpaE767pmum/15t+e4w >a=ice-options:trickle >a=fingerprint:sha-256 C0:87:49:0F:C7:CC:BE:89:D3:AE:C7:DE:C3:67:BB:54:59:04:81:85:84:7D:B3:9E:82:E9:BF:F4:AA:6C:DD:86 >a=setup:actpass >a=mid:audio >a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=sendrecv >a=rtcp-mux >a=rtpmap:111 opus/48000/2 >a=rtcp-fb:111 transport-cc >a=fmtp:111 cbr=1;minptime=10;useinbandfec=1 >a=ssrc:165289590 cname:i8ReRR+GodOicH8m >a=ssrc:165289590 msid:s audio1 >a=ssrc:165289590 mslabel:s >a=ssrc:165289590 label:audio1 >m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 103 104 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ZZNr >a=ice-pwd:Dk9PhcpaE767pmum/15t+e4w >a=ice-options:trickle >a=fingerprint:sha-256 C0:87:49:0F:C7:CC:BE:89:D3:AE:C7:DE:C3:67:BB:54:59:04:81:85:84:7D:B3:9E:82:E9:BF:F4:AA:6C:DD:86 >a=setup:actpass >a=mid:video >a=extmap:14 urn:ietf:params:rtp-hdrext:toffset >a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:3 urn:3gpp:video-orientation >a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=rtpmap:96 VP8/90000 >a=rtcp-fb:96 goog-remb >a=rtcp-fb:96 transport-cc >a=rtcp-fb:96 ccm fir >a=rtcp-fb:96 nack >a=rtcp-fb:96 nack pli >a=rtpmap:97 rtx/90000 >a=fmtp:97 apt=96 >a=rtpmap:98 VP9/90000 >a=rtcp-fb:98 goog-remb >a=rtcp-fb:98 transport-cc >a=rtcp-fb:98 ccm fir >a=rtcp-fb:98 nack >a=rtcp-fb:98 nack pli >a=fmtp:98 profile-id=0 >a=rtpmap:99 rtx/90000 >a=fmtp:99 apt=98 >a=rtpmap:100 VP9/90000 >a=rtcp-fb:100 goog-remb >a=rtcp-fb:100 transport-cc >a=rtcp-fb:100 ccm fir >a=rtcp-fb:100 nack >a=rtcp-fb:100 nack pli >a=fmtp:100 profile-id=2 >a=rtpmap:101 rtx/90000 >a=fmtp:101 apt=100 >a=rtpmap:102 red/90000 >a=rtpmap:103 rtx/90000 >a=fmtp:103 apt=102 >a=rtpmap:104 ulpfec/90000 >a=ssrc-group:FID 448693351 3704574888 >a=ssrc:448693351 cname:i8ReRR+GodOicH8m >a=ssrc:448693351 msid:s video1 >a=ssrc:448693351 mslabel:s >a=ssrc:448693351 label:video1 >a=ssrc:3704574888 cname:i8ReRR+GodOicH8m >a=ssrc:3704574888 msid:s video1 >a=ssrc:3704574888 mslabel:s >a=ssrc:3704574888 label:video1 >m=application 9 UDP/TLS/RTP/SAVPF 109 >c=IN IP4 0.0.0.0 >b=AS:30 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ZZNr >a=ice-pwd:Dk9PhcpaE767pmum/15t+e4w >a=ice-options:trickle >a=fingerprint:sha-256 C0:87:49:0F:C7:CC:BE:89:D3:AE:C7:DE:C3:67:BB:54:59:04:81:85:84:7D:B3:9E:82:E9:BF:F4:AA:6C:DD:86 >a=setup:actpass >a=mid:data >a=sendrecv >a=rtcp-mux >a=rtpmap:109 google-data/90000 >a=ssrc:1873719275 cname:i8ReRR+GodOicH8m >a=ssrc:1873719275 msid:signaling signaling >a=ssrc:1873719275 mslabel:signaling >a=ssrc:1873719275 label:signaling > >INFO 2020-11-25T18:34:02.794Z src/webrtc/sdp_observer.rs:605 ssd_observer_OnSuccess() >INFO 2020-11-25T18:34:02.794Z src/webrtc/sdp_observer.rs:535 on_set_success() >INFO 2020-11-25T18:34:02.794Z src/core/connection.rs:466 Starting Connection FSM for 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:328 syncing worker thread: ThreadId(7) >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:328 syncing notify thread: ThreadId(8) >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:1894 send_offer(): call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:34:02.794Z src/core/call_manager.rs:1897 ringrtc! 1606329242749 cm -> app: send_offer() 0xdf332dcfad4e5e03 opaque=true/3083; sdp=true/2779 >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:282 state: IceGathering, event: (ReceivedSignalingDataChannel, dc: 0x3bca081c73c0) >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:951 ringrtc! 1606329242749 rtc -> conn: on_data_channel() >WARN 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:1006 Unexpected event ReceivedSignalingDataChannel, while in state IceGathering >INFO 2020-11-25T18:34:02.794Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:782 ringrtc! 1606329242750 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.794Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:02.794Z src/core/connection_fsm.rs:782 ringrtc! 1606329242750 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.795Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.795Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:02.795Z src/core/connection_fsm.rs:782 ringrtc! 1606329242750 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:02.795Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/124; sdp=true/120; opaque: ...; sdp: candidate:1967680543 1 udp 2122260223 [REDACTED] 59086 typ host generation 0 ufrag ZZNr network-id 1 network-cost 50 >INFO 2020-11-25T18:34:02.795Z src/core/call.rs:456 send_buffered_local_ice_candidates(): ConnectingBeforeAccepted >INFO 2020-11-25T18:34:02.795Z src/core/call_manager.rs:1903 call_id: 0xdf332dcfad4e5e03, TX offer: >opaque: ...; sdp: v=0 >o=- 667395881565342527 2 IN IP4 [REDACTED] >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS s >m=audio 9 UDP/TLS/RTP/SAVPF 111 >c=IN IP4 [REDACTED] >a=rtcp:9 IN IP4 [REDACTED] >a=ice-ufrag:ZZNr >a=ice-pwd:[ REDACTED ] >a=ice-options:trickle >a=fingerprint:sha-256 [REDACTED]:[REDACTED]:[REDACTED]:[REDACTED] >a=setup:actpass >a=mid:audio >a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=sendrecv >a=rtcp-mux >a=rtpmap:111 opus/48000/2 >a=rtcp-fb:111 transport-cc >a=fmtp:111 cbr=1;minptime=10;useinbandfec=1 >a=ssrc:165289590 cname:i8ReRR+GodOicH8m >a=ssrc:165289590 msid:s audio1 >a=ssrc:165289590 mslabel:s >a=ssrc:165289590 label:audio1 >m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 103 104 >c=IN IP4 [REDACTED] >a=rtcp:9 IN IP4 [REDACTED] >a=ice-ufrag:ZZNr >a=ice-pwd:[ REDACTED ] >a=ice-options:trickle >a=fingerprint:sha-256 [REDACTED]:[REDACTED]:[REDACTED]:[REDACTED] >a=setup:actpass >a=mid:video >a=extmap:14 urn:ietf:params:rtp-hdrext:toffset >a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:3 urn:3gpp:video-orientation >a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=rtpmap:96 VP8/90000 >a=rtcp-fb:96 goog-remb >a=rtcp-fb:96 transport-cc >a=rtcp-fb:96 ccm fir >a=rtcp-fb:96 nack >a=rtcp-fb:96 nack pli >a=rtpmap:97 rtx/90000 >a=fmtp:97 apt=96 >a=rtpmap:98 VP9/90000 >a=rtcp-fb:98 goog-remb >a=rtcp-fb:98 transport-cc >a=rtcp-fb:98 ccm fir >a=rtcp-fb:98 nack >a=rtcp-fb:98 nack pli >a=fmtp:98 profile-id=0 >a=rtpmap:99 rtx/90000 >a=fmtp:99 apt=98 >a=rtpmap:100 VP9/90000 >a=rtcp-fb:100 goog-remb >a=rtcp-fb:100 transport-cc >a=rtcp-fb:100 ccm fir >a=rtcp-fb:100 nack >a=rtcp-fb:100 nack pli >a=fmtp:100 profile-id=2 >a=rtpmap:101 rtx/90000 >a=fmtp:101 apt=100 >a=rtpmap:102 red/90000 >a=rtpmap:103 rtx/90000 >a=fmtp:103 apt=102 >a=rtpmap:104 ulpfec/90000 >a=ssrc-group:FID 448693351 3704574888 >a=ssrc:448693351 cname:i8ReRR+GodOicH8m >a=ssrc:448693351 msid:s video1 >a=ssrc:448693351 mslabel:s >a=ssrc:448693351 label:video1 >a=ssrc:3704574888 cname:i8ReRR+GodOicH8m >a=ssrc:3704574888 msid:s video1 >a=ssrc:3704574888 mslabel:s >a=ssrc:3704574888 label:video1 >m=application 9 UDP/DTLS/SCTP webrtc-datachannel >c=IN IP4 [REDACTED] >a=ice-ufrag:ZZNr >a=ice-pwd:[ REDACTED ] >a=ice-options:trickle >a=fingerprint:sha-256 [REDACTED]:[REDACTED]:[REDACTED]:[REDACTED] >a=setup:actpass >a=mid:data >a=sctp-port:5000 >a=max-message-size:262144 >INFO 2020-11-25T18:34:02.795Z src/native.rs:538 NativePlatform::on_send_offer(): remote_peer: [REDACTED]429, call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.795Z src/core/call_manager.rs:1984 send_ice_candidates(): call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:02.795Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:34:02.795Z src/core/call_manager.rs:1653 send_next_message(): messages are in flight already >INFO 2020-11-25T18:34:02.795Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/120; sdp=true/116; opaque: ...; sdp: candidate:1195588707 1 udp 2122194687 [REDACTED] 50216 typ host generation 0 ufrag ZZNr network-id 4 network-cost 50 >INFO 2020-11-25T18:34:02.795Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/108; sdp=true/104; opaque: ...; sdp: candidate:1195588707 1 udp 2113937151 [REDACTED] 51564 typ host generation 0 ufrag ZZNr network-cost 999 >INFO 2020-11-25T18:34:02.819Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:34:03.178Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:34:03.179Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:34:03.199Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:34:03.199Z src/core/call_manager.rs:956 ringrtc! 1606329243179 app -> cm: message_sent() 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:03.199Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:34:03.199Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:34:03.199Z src/core/connection.rs:1148 take_buffered_local_ice_candidates(): >INFO 2020-11-25T18:34:03.199Z src/core/connection.rs:1155 take_buffered_local_ice_candidates(): Local ICE candidates length: 3 >INFO 2020-11-25T18:34:03.199Z src/core/call_manager.rs:1993 ringrtc! 1606329243179 cm -> app: send_ice_candidates(3) 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:03.199Z src/native.rs:577 NativePlatform::on_send_ice(): remote_peer: [REDACTED]429, call_id: 0xdf332dcfad4e5e03, receiver_device_id: None, candidates: 3 >INFO 2020-11-25T18:34:03.208Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:34:03.249Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:782 ringrtc! 1606329243239 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/156; sdp=true/150; opaque: ...; sdp: candidate:2757933711 1 udp 1685987071 [REDACTED] 38725 typ srflx raddr [REDACTED] rport 50216 generation 0 ufrag ZZNr network-id 4 network-cost 50 >INFO 2020-11-25T18:34:03.249Z src/core/call.rs:456 send_buffered_local_ice_candidates(): ConnectingBeforeAccepted >INFO 2020-11-25T18:34:03.249Z src/core/call_manager.rs:1984 send_ice_candidates(): call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:03.249Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:34:03.249Z src/core/call_manager.rs:1653 send_next_message(): messages are in flight already >INFO 2020-11-25T18:34:03.249Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:782 ringrtc! 1606329243239 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/156; sdp=true/150; opaque: ...; sdp: candidate:192666441 1 udp 25042687 [REDACTED] 51771 typ relay raddr [REDACTED] rport 36544 generation 0 ufrag ZZNr network-id 4 network-cost 50 >INFO 2020-11-25T18:34:03.249Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:782 ringrtc! 1606329243239 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/144; sdp=true/138; opaque: ...; sdp: candidate:192666441 1 udp 16785151 [REDACTED] 56983 typ relay raddr [REDACTED] rport 42775 generation 0 ufrag ZZNr network-cost 999 >INFO 2020-11-25T18:34:03.249Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:03.249Z src/core/connection_fsm.rs:782 ringrtc! 1606329243239 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.249Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/143; sdp=true/137; opaque: ...; sdp: candidate:842163049 1 udp 1677729535 [REDACTED] 46783 typ srflx raddr [REDACTED] rport 51564 generation 0 ufrag ZZNr network-cost 999 >INFO 2020-11-25T18:34:03.350Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.350Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:03.350Z src/core/connection_fsm.rs:782 ringrtc! 1606329243342 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.350Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/157; sdp=true/151; opaque: ...; sdp: candidate:1173891001 1 udp 41820415 [REDACTED] 53063 typ relay raddr [REDACTED] rport 38725 generation 0 ufrag ZZNr network-id 4 network-cost 50 >INFO 2020-11-25T18:34:03.350Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.350Z src/core/connection_fsm.rs:282 state: IceGathering, event: (LocalIceCandidate) >INFO 2020-11-25T18:34:03.350Z src/core/connection_fsm.rs:782 ringrtc! 1606329243343 rtc -> conn: ice_candidate() 0xdf332dcfad4e5e03-0 >INFO 2020-11-25T18:34:03.350Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/145; sdp=true/139; opaque: ...; sdp: candidate:1173891001 1 udp 33562879 [REDACTED] 63449 typ relay raddr [REDACTED] rport 46783 generation 0 ufrag ZZNr network-cost 999 >INFO 2020-11-25T18:34:03.411Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:34:03.411Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:34:03.450Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:34:03.450Z src/core/call_manager.rs:956 ringrtc! 1606329243411 app -> cm: message_sent() 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:03.450Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:34:03.450Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:34:03.450Z src/core/connection.rs:1148 take_buffered_local_ice_candidates(): >INFO 2020-11-25T18:34:03.450Z src/core/connection.rs:1155 take_buffered_local_ice_candidates(): Local ICE candidates length: 6 >INFO 2020-11-25T18:34:03.450Z src/core/call_manager.rs:1993 ringrtc! 1606329243411 cm -> app: send_ice_candidates(6) 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:03.450Z src/native.rs:577 NativePlatform::on_send_ice(): remote_peer: [REDACTED]429, call_id: 0xdf332dcfad4e5e03, receiver_device_id: None, candidates: 6 >INFO 2020-11-25T18:34:03.456Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:34:03.968Z SQL channel job 135 (createOrUpdateSessions) succeeded in 15ms >INFO 2020-11-25T18:34:04.379Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:34:04.379Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:34:04.402Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:34:04.402Z src/core/call_manager.rs:956 ringrtc! 1606329244379 app -> cm: message_sent() 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:04.402Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:34:04.402Z src/core/call_manager.rs:1691 send_next_message(): no messages to send >INFO 2020-11-25T18:34:06.164Z SQL channel job 136 (saveUnprocesseds) succeeded in 15ms >INFO 2020-11-25T18:34:06.165Z queueing envelope d6fafa35356d426ca2cdbf66f036a19e >INFO 2020-11-25T18:34:06.165Z received unidentified sender message >INFO 2020-11-25T18:34:06.168Z New remote ephemeral key >INFO 2020-11-25T18:34:06.171Z Deleting chain closed at 1606324870908 >INFO 2020-11-25T18:34:06.171Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:34:06.172Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:34:06.206Z src/core/call_manager.rs:447 API:CallManager::handle_received_answer(): >INFO 2020-11-25T18:34:06.206Z src/core/call_manager.rs:1258 ringrtc! 1606329246172 app -> cm: received_answer() 0xdf332dcfad4e5e03 1 opaque=true/72; sdp=false >INFO 2020-11-25T18:34:06.206Z src/core/call_manager.rs:1269 handle_received_answer(): active call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:06.206Z src/core/call_fsm.rs:215 state: ConnectingBeforeAccepted, event: (ReceivedAnswer, device: 1 feature_level: MultiRing) >INFO 2020-11-25T18:34:06.206Z src/core/call.rs:584 id: 0xdf332dcfad4e5e03-1, RX answer: >opaque: ... >INFO 2020-11-25T18:34:06.207Z src/core/call.rs:603 received_answer from device 1; forking enabled, so inject into connection_map >INFO 2020-11-25T18:34:06.207Z src/native.rs:356 NativePlatform::create_connection(): call: thread: ThreadId(3), direction: OutGoing, call_id: 0xdf332dcfad4e5e03, state: "ConnectingBeforeAccepted" remote_device_id: 1 signaling_version: V4 >INFO 2020-11-25T18:34:06.207Z src/webrtc/stats_observer.rs:49 ringrtc_stats!,audio_packets_sent,audio_packets_sent_lost,audio_rtt,audio_packets_received,audio_packets_received_lost,audio_jitter_received,audio_expand_rate,audio_accelerate_rate,audio_preemptive_rate,audio_speech_expand_rate,audio_preferred_buffer_size_ms >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:240 pc_observer_OnSignalingDataChannel(): 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/data_channel.rs:57 data channel is reliable: false >INFO 2020-11-25T18:34:06.207Z src/webrtc/data_channel.rs:57 data channel is reliable: false >INFO 2020-11-25T18:34:06.207Z src/core/connection.rs:580 Using V4 signaling for outgoing connection answer: ConnectionParametersV4 { public_key: Some([58, 54, 139, 56, 230, 128, 232, 78, 108, 68, 2, 231, 253, 129, 18, 217, 174, 198, 15, 17, 146, 116, 141, 79, 214, 17, 140, 66, 204, 122, 69, 42]), ice_ufrag: Some("BpyZ"), ice_pwd: Some("WYehzNqyLk5IEPaianfpgJgr"), receive_video_codecs: [VideoCodec { r#type: Some(Vp8), level: None }] } SDP: v=0 >o=- 1 1 IN IP4 127.0.0.1 >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS >m=audio 9 RTP/SAVPF 102 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:BpyZ >a=ice-pwd:WYehzNqyLk5IEPaianfpgJgr >a=ice-options:trickle renomination >a=mid:audio >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:102 opus/48000/2 >a=rtcp-fb:102 transport-cc >a=fmtp:102 cbr=1;maxaveragebitrate=40000;minptime=10;stereo=0;usedtx=0;useinbandfec=1 >a=maxptime:120 >a=ptime:20 >a=ssrc:1002 cname:CNAMECNAMECNAME! >a=ssrc:1002 msid:- audio1 >a=ssrc:1002 mslabel:- >a=ssrc:1002 label:audio1 >m=video 9 RTP/SAVPF 108 118 120 121 122 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:BpyZ >a=ice-pwd:WYehzNqyLk5IEPaianfpgJgr >a=ice-options:trickle renomination >a=mid:video >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:4 urn:3gpp:video-orientation >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:13 urn:ietf:params:rtp-hdrext:toffset >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:108 VP8/90000 >a=rtcp-fb:108 transport-cc >a=rtcp-fb:108 ccm fir >a=rtcp-fb:108 nack >a=rtcp-fb:108 nack pli >a=rtcp-fb:108 goog-remb >a=rtpmap:118 rtx/90000 >a=fmtp:118 apt=108 >a=rtpmap:120 red/90000 >a=rtpmap:121 rtx/90000 >a=fmtp:121 apt=120 >a=rtpmap:122 ulpfec/90000 >a=ssrc-group:FID 1003 1013 >a=ssrc:1003 cname:CNAMECNAMECNAME! >a=ssrc:1003 msid:- video1 >a=ssrc:1003 mslabel:- >a=ssrc:1003 label:video1 >a=ssrc:1013 cname:CNAMECNAMECNAME! >a=ssrc:1013 msid:- video1 >a=ssrc:1013 mslabel:- >a=ssrc:1013 label:video1 >m=application 9 RTP/SAVPF 101 >c=IN IP4 0.0.0.0 >b=AS:30 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:BpyZ >a=ice-pwd:WYehzNqyLk5IEPaianfpgJgr >a=ice-options:trickle renomination >a=mid:data >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:101 google-data/90000 >a=ssrc:1001 cname:CNAMECNAMECNAME! >a=ssrc:1001 msid:signaling signaling >a=ssrc:1001 mslabel:signaling >a=ssrc:1001 label:signaling > >INFO 2020-11-25T18:34:06.207Z src/webrtc/sdp_observer.rs:605 ssd_observer_OnSuccess() >INFO 2020-11-25T18:34:06.207Z src/webrtc/sdp_observer.rs:535 on_set_success() >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:158 ringrtc! 1606329246193 rtc -> conn: ice_connection_change(Checking) 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:200 pc_observer_OnAddAudioRtpReceiver(): 0xdf332dcfad4e5e03-1, rffi_track: 0x3bca09768090 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:220 pc_observer_OnAddVideoRtpReceiver(): 0xdf332dcfad4e5e03-1, rffi_track: 0x3bca08b92d40 >INFO 2020-11-25T18:34:06.207Z src/webrtc/peer_connection_observer.rs:180 pc_observer_OnAddStream(): 0xdf332dcfad4e5e03-1, rffi_stream: 0x3bca0781b4e0 >INFO 2020-11-25T18:34:06.207Z src/webrtc/sdp_observer.rs:605 ssd_observer_OnSuccess() >INFO 2020-11-25T18:34:06.207Z src/webrtc/sdp_observer.rs:535 on_set_success() >INFO 2020-11-25T18:34:06.207Z src/core/connection.rs:466 Starting Connection FSM for 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/core/connection_fsm.rs:328 syncing worker thread: ThreadId(10) >INFO 2020-11-25T18:34:06.207Z src/core/connection_fsm.rs:328 syncing notify thread: ThreadId(11) >INFO 2020-11-25T18:34:06.207Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (ReceivedSignalingDataChannel, dc: 0x3bca0887c760) >INFO 2020-11-25T18:34:06.207Z src/core/connection_fsm.rs:951 ringrtc! 1606329246194 rtc -> conn: on_data_channel() >INFO 2020-11-25T18:34:06.207Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (ReceivedIncomingMedia, stream: rffi_media_stream: 0x3bca0781b4e0) >INFO 2020-11-25T18:34:06.207Z src/core/connection.rs:1322 handle_received_incoming_media(): id: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.207Z src/native.rs:390 NativePlatform::create_incoming_media() >INFO 2020-11-25T18:34:06.458Z src/webrtc/peer_connection_observer.rs:158 ringrtc! 1606329246441 rtc -> conn: ice_connection_change(Connected) 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.458Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (IceConnected) >INFO 2020-11-25T18:34:06.458Z src/core/call.rs:975 on_connection_observer_event(): call_id: 0xdf332dcfad4e5e03, remote_device_id: 1, event: ConnectedWithDataChannelBeforeAccepted >INFO 2020-11-25T18:34:06.458Z src/core/call_fsm.rs:215 state: ConnectingBeforeAccepted, event: (ConnectionObserverEvent, event: ConnectedWithDataChannelBeforeAccepted, device: 1) >INFO 2020-11-25T18:34:06.458Z src/core/call_manager.rs:1764 ringrtc! 1606329246442 cm -> app: event(RemoteRinging) >INFO 2020-11-25T18:34:06.458Z src/native.rs:438 NativePlatform::on_event(): remote_peer: [REDACTED]429, event: RemoteRinging >INFO 2020-11-25T18:34:06.670Z SQL channel job 137 (saveUnprocesseds) succeeded in 12ms >INFO 2020-11-25T18:34:06.671Z queueing envelope daf28dd424ba4aaea63afefb70ffe678 >INFO 2020-11-25T18:34:06.671Z received unidentified sender message >INFO 2020-11-25T18:34:06.675Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:34:06.675Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:34:06.686Z SQL channel job 138 (createOrUpdateSessions) succeeded in 15ms >INFO 2020-11-25T18:34:06.696Z SQL channel job 139 (updateUnprocessedsWithData) succeeded in 25ms >INFO 2020-11-25T18:34:06.711Z src/core/call_manager.rs:456 API:CallManager::handle_received_ice(): >INFO 2020-11-25T18:34:06.711Z src/core/call_manager.rs:1284 ringrtc! 1606329246675 app -> cm: received_ice_candidates(10) 0xdf332dcfad4e5e03 1 >INFO 2020-11-25T18:34:06.711Z src/core/call_manager.rs:1295 handle_received_ice(): active call_id: 0xdf332dcfad4e5e03 >INFO 2020-11-25T18:34:06.711Z src/core/call_fsm.rs:215 state: ConnectedWithDataChannelBeforeAccepted, event: (ReceivedIce, device: 1) >INFO 2020-11-25T18:34:06.711Z src/core/call.rs:631 received_ice(): id: 0xdf332dcfad4e5e03-1 >INFO 2020-11-25T18:34:06.711Z src/core/connection_fsm.rs:282 state: ConnectedBeforeAccepted, event: (RemoteIceCandidates) >INFO 2020-11-25T18:34:06.711Z src/core/connection.rs:1167 ringrtc! 1606329246676 conn -> rtc: ice_candidates(10) >INFO 2020-11-25T18:34:06.712Z SQL channel job 140 (removeUnprocessed) succeeded in 41ms >INFO 2020-11-25T18:34:07.191Z SQL channel job 141 (createOrUpdateSessions) succeeded in 17ms >INFO 2020-11-25T18:34:07.205Z SQL channel job 142 (updateUnprocessedsWithData) succeeded in 31ms >INFO 2020-11-25T18:34:07.221Z SQL channel job 143 (removeUnprocessed) succeeded in 46ms >INFO 2020-11-25T18:34:09.617Z src/core/connection_fsm.rs:282 state: ConnectedBeforeAccepted, event: (ReceivedAcceptedViaDataChannel, call_id: 0xdf332dcfad4e5e03) >INFO 2020-11-25T18:34:09.617Z src/core/connection_fsm.rs:511 ringrtc! 1606329249615 rtc -> conn: dc(accepted) >INFO 2020-11-25T18:34:19.803Z before-quit event {"shouldQuit":false} >INFO 2020-11-25T18:34:19.803Z close event {"shouldQuit":true} >INFO 2020-11-25T18:34:19.803Z requestShutdown: Requesting close of mainWindow... >INFO 2020-11-25T18:34:49.907Z app ready >INFO 2020-11-25T18:34:49.907Z starting version 1.38.2 >INFO 2020-11-25T18:34:49.918Z updateSchema: > Current user_version: 21; > Most recent db schema: 21; > SQLite version: 3.30.1; > SQLCipher version: 4.3.0 community; > (deprecated) schema_version: 60; > >INFO 2020-11-25T18:34:50.288Z Initializing BrowserWindow config: {"show":true,"width":1920,"height":994,"minWidth":680,"minHeight":550,"autoHideMenuBar":false,"backgroundColor":"#3a76f0","webPreferences":{"nodeIntegration":false,"nodeIntegrationInWorker":false,"contextIsolation":false,"preload":"[REDACTED]/app.asar/preload.js","nativeWindowOpen":true,"spellcheck":true,"backgroundThrottling":false},"icon":"[REDACTED]/app.asar/images/signal-logo-desktop-linux.png","x":0,"y":55} >INFO 2020-11-25T18:34:50.357Z spellcheck: user locale: de-DE >INFO 2020-11-25T18:34:50.357Z spellcheck: available spellchecker languages: ["af","bg","ca","cs","cy","da","de","el","en-AU","en-CA","en-GB","en-US","es","es-419","es-AR","es-ES","es-MX","es-US","et","fa","fo","fr","he","hi","hr","hu","hy","id","it","ko","lt","lv","nb","nl","pl","pt-BR","pt-PT","ro","ru","sh","sk","sl","sq","sr","sv","ta","tg","tr","uk","vi"] >INFO 2020-11-25T18:34:50.357Z spellcheck: setting languages to: ["de"] >INFO 2020-11-25T18:34:50.372Z Begin ensuring permissions >INFO 2020-11-25T18:34:50.383Z Ensuring file permissions for 2 files >INFO 2020-11-25T18:34:50.384Z Finish ensuring permissions in 11ms >INFO 2020-11-25T18:34:51.196Z x-attr dependency did not load successfully >INFO 2020-11-25T18:34:51.220Z preload complete >INFO 2020-11-25T18:34:51.388Z pre-main prep time: 1 ms >INFO 2020-11-25T18:34:51.454Z background page reloaded >INFO 2020-11-25T18:34:51.454Z environment: production >INFO 2020-11-25T18:34:51.526Z SQL channel job 1 (getItemById) succeeded in 71ms >INFO 2020-11-25T18:34:51.526Z Storage fetch >INFO 2020-11-25T18:34:51.599Z SQL channel job 2 (getAllItems) succeeded in 73ms >INFO 2020-11-25T18:34:51.618Z SQL channel job 3 (createOrUpdateItem) succeeded in 17ms >INFO 2020-11-25T18:34:51.640Z SQL channel job 4 (createOrUpdateItem) succeeded in 22ms >INFO 2020-11-25T18:34:51.650Z SQL channel job 5 (createOrUpdateItem) succeeded in 31ms >INFO 2020-11-25T18:34:51.650Z Starting background data migration. Target version: 10 >INFO 2020-11-25T18:34:51.653Z GET https://textsecure-service.whispersystems.org/v1/config >INFO 2020-11-25T18:34:51.659Z ConversationController: starting initial fetch >INFO 2020-11-25T18:34:51.681Z SQL channel job 7 (getAllStickerPacks) succeeded in 22ms >INFO 2020-11-25T18:34:51.681Z SQL channel job 8 (getAllStickers) succeeded in 22ms >INFO 2020-11-25T18:34:51.681Z SQL channel job 9 (getRecentStickers) succeeded in 22ms >INFO 2020-11-25T18:34:51.681Z SQL channel job 10 (getRecentEmojis) succeeded in 21ms >INFO 2020-11-25T18:34:51.682Z SQL channel job 12 (getItemById) succeeded in 22ms >INFO 2020-11-25T18:34:51.682Z SQL channel job 13 (getAllIdentityKeys) succeeded in 22ms >INFO 2020-11-25T18:34:51.682Z SignalProtocolStore: Finished caching identityKeys data >INFO 2020-11-25T18:34:51.683Z SQL channel job 14 (getAllSessions) succeeded in 23ms >INFO 2020-11-25T18:34:51.683Z SignalProtocolStore: Finished caching sessions data >INFO 2020-11-25T18:34:51.683Z SQL channel job 11 (getItemById) succeeded in 23ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 55 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 56 (getLastConversationActivity) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 57 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 61 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 59 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 60 (getLastConversationActivity) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 58 (getLastConversationActivity) succeeded in 12ms >INFO 2020-11-25T18:34:51.692Z SQL channel job 62 (getLastConversationActivity) succeeded in 12ms >INFO 2020-11-25T18:34:51.693Z SQL channel job 63 (getLastConversationPreview) succeeded in 13ms >INFO 2020-11-25T18:34:51.693Z SQL channel job 64 (getLastConversationActivity) succeeded in 13ms >INFO 2020-11-25T18:34:51.694Z SQL channel job 65 (getLastConversationPreview) succeeded in 14ms >INFO 2020-11-25T18:34:51.694Z SQL channel job 66 (getLastConversationActivity) succeeded in 14ms >INFO 2020-11-25T18:34:51.694Z SQL channel job 67 (getLastConversationPreview) succeeded in 14ms >INFO 2020-11-25T18:34:51.694Z SQL channel job 68 (getLastConversationActivity) succeeded in 14ms >INFO 2020-11-25T18:34:51.695Z SQL channel job 71 (getLastConversationPreview) succeeded in 15ms >INFO 2020-11-25T18:34:51.695Z SQL channel job 69 (getLastConversationPreview) succeeded in 15ms >INFO 2020-11-25T18:34:51.695Z SQL channel job 70 (getLastConversationActivity) succeeded in 15ms >INFO 2020-11-25T18:34:51.695Z SQL channel job 72 (getLastConversationActivity) succeeded in 15ms >INFO 2020-11-25T18:34:51.695Z SQL channel job 73 (getLastConversationPreview) succeeded in 15ms >INFO 2020-11-25T18:34:51.696Z SQL channel job 74 (getLastConversationActivity) succeeded in 16ms >INFO 2020-11-25T18:34:51.696Z SQL channel job 75 (getLastConversationPreview) succeeded in 16ms >INFO 2020-11-25T18:34:51.696Z SQL channel job 76 (getLastConversationActivity) succeeded in 16ms >INFO 2020-11-25T18:34:51.696Z SQL channel job 77 (getLastConversationPreview) succeeded in 16ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 78 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 79 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 80 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 81 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 82 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 83 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:34:51.697Z SQL channel job 84 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:34:51.698Z SQL channel job 85 (getLastConversationPreview) succeeded in 18ms >INFO 2020-11-25T18:34:51.698Z SQL channel job 86 (getLastConversationActivity) succeeded in 18ms >INFO 2020-11-25T18:34:51.698Z SQL channel job 87 (getLastConversationPreview) succeeded in 18ms >INFO 2020-11-25T18:34:51.698Z SQL channel job 88 (getLastConversationActivity) succeeded in 18ms >INFO 2020-11-25T18:34:51.698Z ConversationController: done with initial fetch >INFO 2020-11-25T18:34:51.698Z SQL channel job 89 (getAllPreKeys) succeeded in 15ms >INFO 2020-11-25T18:34:51.701Z SignalProtocolStore: Finished caching preKeys data >INFO 2020-11-25T18:34:51.701Z SQL channel job 90 (getAllSignedPreKeys) succeeded in 18ms >INFO 2020-11-25T18:34:51.701Z SignalProtocolStore: Finished caching signedPreKeys data >INFO 2020-11-25T18:34:51.701Z checkForConflicts: starting... >INFO 2020-11-25T18:34:51.701Z checkForConflicts: complete! >INFO 2020-11-25T18:34:51.717Z Cleanup: starting... >INFO 2020-11-25T18:34:51.717Z Initializing network observer every 5000ms >INFO 2020-11-25T18:34:51.717Z Build expires: 2021-02-21T18:41:50.000Z >INFO 2020-11-25T18:34:51.718Z SQL channel job 91 (updateConversations) succeeded in 27ms >INFO 2020-11-25T18:34:51.718Z Cleanup: Found 0 messages for cleanup >INFO 2020-11-25T18:34:51.718Z Cleanup: complete >INFO 2020-11-25T18:34:51.718Z listening for registration events >INFO 2020-11-25T18:34:51.722Z connect {"connectCount":0} >INFO 2020-11-25T18:34:51.722Z GET https://textsecure-service.whispersystems.org/v1/config >INFO 2020-11-25T18:34:51.723Z open inbox >INFO 2020-11-25T18:34:51.772Z SQL channel job 93 (getNextExpiringMessage) succeeded in 51ms >INFO 2020-11-25T18:34:51.773Z SQL channel job 94 (getNextTapToViewMessageToAgeOut) succeeded in 52ms >INFO 2020-11-25T18:34:52.220Z SQL channel job 95 (updateConversations) succeeded in 22ms >INFO 2020-11-25T18:34:52.385Z GET https://textsecure-service.whispersystems.org/v1/config 200 Success >INFO 2020-11-25T18:34:52.397Z SQL channel job 96 (createOrUpdateItem) succeeded in 12ms >INFO 2020-11-25T18:34:52.541Z GET https://textsecure-service.whispersystems.org/v1/config 200 Success >INFO 2020-11-25T18:34:52.542Z Initializing socket and listening for messages >INFO 2020-11-25T18:34:52.542Z getAllFromCache >INFO 2020-11-25T18:34:52.542Z opening message socket https://textsecure-service.whispersystems.org >INFO 2020-11-25T18:34:52.544Z initializeGroupCredentialFetcher: starting... >INFO 2020-11-25T18:34:52.544Z maybeFetchCredentials: no new credentials needed >INFO 2020-11-25T18:34:52.544Z attachment_downloads/start: enabling >INFO 2020-11-25T18:34:52.545Z Start idle detector >INFO 2020-11-25T18:34:52.550Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]372 >INFO 2020-11-25T18:34:52.552Z SQL channel job 97 (createOrUpdateItem) succeeded in 11ms >INFO 2020-11-25T18:34:52.553Z SQL channel job 98 (getUnprocessedCount) succeeded in 11ms >INFO 2020-11-25T18:34:52.553Z getAllFromCache loaded 0 saved envelopes >INFO 2020-11-25T18:34:52.693Z SQL channel job 102 (createOrUpdateItem) succeeded in 15ms >INFO 2020-11-25T18:34:52.736Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]372 200 Success >INFO 2020-11-25T18:34:53.067Z SQL channel job 103 (createOrUpdateSessions) succeeded in 17ms >INFO 2020-11-25T18:34:54.127Z websocket open >INFO 2020-11-25T18:34:54.364Z SQL channel job 104 (saveUnprocesseds) succeeded in 17ms >INFO 2020-11-25T18:34:54.365Z queueing envelope cbf0e8468e724906aa7299474c2a706a >INFO 2020-11-25T18:34:54.365Z received unidentified sender message >INFO 2020-11-25T18:34:54.366Z queueing envelope [REDACTED]372.1 1606329294311 (5030f55b645140779c1dc7120103e830) >INFO 2020-11-25T18:34:54.371Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:34:54.372Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:34:54.372Z message from [REDACTED]372.1 1606329294311 (5030f55b645140779c1dc7120103e830) >INFO 2020-11-25T18:34:54.372Z New remote ephemeral key >INFO 2020-11-25T18:34:54.374Z Deleting chain closed at 1606288512159 >INFO 2020-11-25T18:34:54.375Z got keys sync message >INFO 2020-11-25T18:34:54.375Z onKeysSync: received keys >INFO 2020-11-25T18:34:54.382Z src/core/call_manager.rs:465 API:CallManager::handle_received_hangup(): >INFO 2020-11-25T18:34:54.382Z src/core/call_manager.rs:1310 ringrtc! 1606329294372 app -> cm: received_hangup(Normal/None) 0xdf332dcfad4e5e03 1 >INFO 2020-11-25T18:34:54.382Z src/core/call_manager.rs:1319 ringrtc! 1606329294372 cm -x app: inactive >INFO 2020-11-25T18:34:54.386Z SQL channel job 105 (createOrUpdateItem) succeeded in 11ms >INFO 2020-11-25T18:34:54.535Z got request PUT /api/v1/queue/empty >INFO 2020-11-25T18:34:54.535Z MessageReceiver: finished processing messages after 'empty', now waiting for application >INFO 2020-11-25T18:34:54.535Z MessageReceiver: emitting 'empty' event >INFO 2020-11-25T18:34:54.548Z SQL channel job 106 (createOrUpdateSessions) succeeded in 13ms >INFO 2020-11-25T18:34:54.559Z SQL channel job 107 (updateUnprocessedsWithData) succeeded in 23ms >INFO 2020-11-25T18:34:54.573Z SQL channel job 108 (removeUnprocessed) succeeded in 37ms >INFO 2020-11-25T18:34:54.573Z onEmpty: All outstanding database requests complete >INFO 2020-11-25T18:34:54.574Z Next signed key rotation scheduled for 2020-11-27T16:46:46.838Z >INFO 2020-11-25T18:34:54.574Z refreshSenderCertificate: Getting new certificate... >INFO 2020-11-25T18:34:54.574Z GET https://textsecure-service.whispersystems.org/v1/certificate/delivery?includeUuid=true >INFO 2020-11-25T18:34:54.575Z updater/start: Updates disabled - not starting new version checks >INFO 2020-11-25T18:34:54.735Z GET https://textsecure-service.whispersystems.org/v1/certificate/delivery?includeUuid=true 200 Success >INFO 2020-11-25T18:34:54.736Z Next sender certificate refresh scheduled for 2020-11-26T18:34:54.736Z >INFO 2020-11-25T18:34:54.736Z refreshOurProfile >INFO 2020-11-25T18:34:54.739Z GET https://textsecure-service.whispersystems.org/v1/profile/[REDACTED]372/[REDACTED]e81 (unauth) >INFO 2020-11-25T18:34:54.757Z SQL channel job 109 (createOrUpdateItem) succeeded in 20ms >INFO 2020-11-25T18:34:54.876Z storageService.sync: starting... >INFO 2020-11-25T18:34:54.876Z storageService.fetchManifest >INFO 2020-11-25T18:34:54.876Z GET https://textsecure-service.whispersystems.org/v1/storage/auth >INFO 2020-11-25T18:34:55.025Z GET https://textsecure-service.whispersystems.org/v1/storage/auth 200 Success >INFO 2020-11-25T18:34:55.026Z GET https://storage.signal.org/v1/storage/manifest/version/64 >INFO 2020-11-25T18:34:55.036Z SQL channel job 110 (createOrUpdateItem) succeeded in 11ms >INFO 2020-11-25T18:34:55.513Z GET https://storage.signal.org/v1/storage/manifest/version/64 204 Success >INFO 2020-11-25T18:34:55.513Z storageService.fetchManifest: nothing changed >INFO 2020-11-25T18:34:55.513Z storageService.sync: no new manifest >INFO 2020-11-25T18:34:55.536Z GET https://textsecure-service.whispersystems.org/v1/profile/[REDACTED]372/[REDACTED]e81 200 Success >INFO 2020-11-25T18:34:55.536Z Setting sealedSender to ENABLED for conversation [REDACTED]372 ([REDACTED]34d) >INFO 2020-11-25T18:34:55.537Z GET https://cdn.signal.org/[REDACTED]yaA >INFO 2020-11-25T18:34:55.553Z SQL channel job 111 (createOrUpdateItem) succeeded in 16ms >INFO 2020-11-25T18:34:55.912Z GET https://cdn.signal.org/[REDACTED]yaA 200 Success >INFO 2020-11-25T18:34:56.424Z SQL channel job 112 (updateConversations) succeeded in 11ms >INFO 2020-11-25T18:34:57.594Z SQL channel job 113 (getMessagesNeedingUpgrade) succeeded in 44ms >INFO 2020-11-25T18:34:57.594Z Upgrade message schema (with index): {"done":true,"numProcessed":0,"fetchDuration":45,"upgradeDuration":0,"saveDuration":0,"totalDuration":45} >INFO 2020-11-25T18:34:57.594Z Background migration complete. Stopping idle detector. >INFO 2020-11-25T18:34:57.594Z Stop idle detector >INFO 2020-11-25T18:35:05.105Z SQL channel job 114 (saveUnprocesseds) succeeded in 11ms > > > > > > > > >INFO 2020-11-25T18:35:05.105Z queueing envelope c273a42a46a74264aaf6b127d0e8ed45 >INFO 2020-11-25T18:35:05.106Z received unidentified sender message >INFO 2020-11-25T18:35:05.112Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:35:05.112Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:35:05.137Z src/core/call_manager.rs:427 API:received_offer(): >INFO 2020-11-25T18:35:05.137Z src/core/call_manager.rs:1066 ringrtc! 1606329305113 app -> cm: received_offer() 0xe22308f78e94abe3 1 feature=MultiRing primary=false opaque=true/3204; sdp=true/2894 >INFO 2020-11-25T18:35:05.137Z src/core/call.rs:227 new(): call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.137Z src/core/call_fsm.rs:258 syncing worker thread: ThreadId(3) >INFO 2020-11-25T18:35:05.137Z src/core/call_fsm.rs:258 syncing notify thread: ThreadId(4) >INFO 2020-11-25T18:35:05.137Z src/core/call.rs:340 id: 0xe22308f78e94abe3-1, RX offer: >opaque: ...; sdp: v=0 >o=- 7094760962030869736 2 IN IP4 [REDACTED] >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS ARDAMS >m=audio 9 UDP/TLS/RTP/SAVPF 111 >c=IN IP4 [REDACTED] >a=rtcp:9 IN IP4 [REDACTED] >a=ice-ufrag:+OXH >a=ice-pwd:[ REDACTED ] >a=ice-options:trickle renomination >a=fingerprint:sha-256 [REDACTED]:[REDACTED]:[REDACTED]:[REDACTED] >a=setup:actpass >a=mid:audio >a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=sendrecv >a=rtcp-mux >a=rtpmap:111 opus/48000/2 >a=rtcp-fb:111 transport-cc >a=fmtp:111 cbr=1;minptime=10;useinbandfec=1 >a=ssrc:192472430 cname:llxdgW8FZxr3xtSw >a=ssrc:192472430 msid:ARDAMS audio1 >a=ssrc:192472430 mslabel:ARDAMS >a=ssrc:192472430 label:audio1 >m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 103 104 >c=IN IP4 [REDACTED] >a=rtcp:9 IN IP4 [REDACTED] >a=ice-ufrag:+OXH >a=ice-pwd:[ REDACTED ] >a=ice-options:trickle renomination >a=fingerprint:sha-256 [REDACTED]:[REDACTED]:[REDACTED]:[REDACTED] >a=setup:actpass >a=mid:video >a=extmap:14 urn:ietf:params:rtp-hdrext:toffset >a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:3 urn:3gpp:video-orientation >a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=rtpmap:96 VP8/90000 >a=rtcp-fb:96 goog-remb >a=rtcp-fb:96 transport-cc >a=rtcp-fb:96 ccm fir >a=rtcp-fb:96 nack >a=rtcp-fb:96 nack pli >a=rtpmap:97 rtx/90000 >a=fmtp:97 apt=96 >a=rtpmap:98 VP9/90000 >a=rtcp-fb:98 goog-remb >a=rtcp-fb:98 transport-cc >a=rtcp-fb:98 ccm fir >a=rtcp-fb:98 nack >a=rtcp-fb:98 nack pli >a=rtpmap:99 rtx/90000 >a=fmtp:99 apt=98 >a=rtpmap:100 H264/90000 >a=rtcp-fb:100 goog-remb >a=rtcp-fb:100 transport-cc >a=rtcp-fb:100 ccm fir >a=rtcp-fb:100 nack >a=rtcp-fb:100 nack pli >a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f >a=rtpmap:101 rtx/90000 >a=fmtp:101 apt=100 >a=rtpmap:102 red/90000 >a=rtpmap:103 rtx/90000 >a=fmtp:103 apt=102 >a=rtpmap:104 ulpfec/90000 >a=ssrc-group:FID 2988255262 3014281156 >a=ssrc:2988255262 cname:llxdgW8FZxr3xtSw >a=ssrc:2988255262 msid:ARDAMS video1 >a=ssrc:2988255262 mslabel:ARDAMS >a=ssrc:2988255262 label:video1 >a=ssrc:3014281156 cname:llxdgW8FZxr3xtSw >a=ssrc:3014281156 msid:ARDAMS video1 >a=ssrc:3014281156 mslabel:ARDAMS >a=ssrc:3014281156 label:video1 >m=application 9 UDP/DTLS/SCTP webrtc-datachannel >c=IN IP4 [REDACTED] >a=ice-ufrag:+OXH >a=ice-pwd:[ REDACTED ] >a=ice-options:trickle renomination >a=fingerprint:sha-256 [REDACTED]:[REDACTED]:[REDACTED]:[REDACTED] >a=setup:actpass >a=mid:data >a=sctp-port:5000 >a=max-message-size:262144 >INFO 2020-11-25T18:35:05.137Z src/core/call_fsm.rs:215 state: NotYetStarted, event: (StartCall) >INFO 2020-11-25T18:35:05.137Z src/core/call_fsm.rs:365 handle_start_call(): >INFO 2020-11-25T18:35:05.137Z src/core/call_manager.rs:1748 ringrtc! 1606329305114 cm -> app: start() 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.137Z src/native.rs:423 NativePlatform::on_start_call(): remote_peer: [REDACTED]429, call_id: 0xe22308f78e94abe3, direction: InComing, call_media_type: Audio >INFO 2020-11-25T18:35:05.137Z CallingClass.handleIncomingCall() >INFO 2020-11-25T18:35:05.138Z CallingClass.handleIncomingCall(): Proceeding >INFO 2020-11-25T18:35:05.138Z GET https://textsecure-service.whispersystems.org/v1/accounts/turn >INFO 2020-11-25T18:35:05.333Z GET https://textsecure-service.whispersystems.org/v1/accounts/turn 200 Success >INFO 2020-11-25T18:35:05.338Z src/core/call_manager.rs:402 API:CallManager::handle_proceed(): >INFO 2020-11-25T18:35:05.338Z src/core/call_manager.rs:938 ringrtc! 1606329305334 app -> cm: proceed() 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.338Z src/core/call_manager.rs:944 handle_proceed(): active call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.338Z src/core/call_fsm.rs:215 state: WaitingToProceed, event: (Proceed) >INFO 2020-11-25T18:35:05.338Z src/core/call_fsm.rs:378 handle_proceed(): >INFO 2020-11-25T18:35:05.338Z src/core/call.rs:510 proceed(): >INFO 2020-11-25T18:35:05.338Z src/core/call.rs:520 proceed(): incoming: remote_device: 1 >INFO 2020-11-25T18:35:05.338Z src/native.rs:356 NativePlatform::create_connection(): call: thread: ThreadId(3), direction: InComing, call_id: 0xe22308f78e94abe3, state: "ConnectingBeforeAccepted" remote_device_id: 1 signaling_version: V4 >INFO 2020-11-25T18:35:05.338Z src/webrtc/stats_observer.rs:49 ringrtc_stats!,audio_packets_sent,audio_packets_sent_lost,audio_rtt,audio_packets_received,audio_packets_received_lost,audio_jitter_received,audio_expand_rate,audio_accelerate_rate,audio_preemptive_rate,audio_speech_expand_rate,audio_preferred_buffer_size_ms >INFO 2020-11-25T18:35:05.338Z src/core/connection.rs:671 Using V4 signaling for incoming connection offer: ConnectionParametersV4 { public_key: Some([42, 117, 144, 198, 36, 0, 190, 33, 170, 13, 45, 195, 144, 27, 128, 244, 42, 201, 254, 227, 20, 154, 34, 172, 91, 173, 42, 57, 67, 30, 174, 79]), ice_ufrag: Some("+OXH"), ice_pwd: Some("CPLFEkDMpW5gcIzL7rFdDU6C"), receive_video_codecs: [VideoCodec { r#type: Some(Vp8), level: None }, VideoCodec { r#type: Some(H264ConstrainedBaseline), level: Some(31) }] } SDP: v=0 >o=- 1 1 IN IP4 127.0.0.1 >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS >m=audio 9 RTP/SAVPF 102 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:+OXH >a=ice-pwd:CPLFEkDMpW5gcIzL7rFdDU6C >a=ice-options:trickle renomination >a=mid:audio >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:102 opus/48000/2 >a=rtcp-fb:102 transport-cc >a=fmtp:102 cbr=1;maxaveragebitrate=40000;minptime=10;stereo=0;usedtx=0;useinbandfec=1 >a=maxptime:120 >a=ptime:20 >a=ssrc:1002 cname:CNAMECNAMECNAME! >a=ssrc:1002 msid:- audio1 >a=ssrc:1002 mslabel:- >a=ssrc:1002 label:audio1 >m=video 9 RTP/SAVPF 108 118 103 113 120 121 122 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:+OXH >a=ice-pwd:CPLFEkDMpW5gcIzL7rFdDU6C >a=ice-options:trickle renomination >a=mid:video >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:4 urn:3gpp:video-orientation >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:13 urn:ietf:params:rtp-hdrext:toffset >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:108 VP8/90000 >a=rtcp-fb:108 transport-cc >a=rtcp-fb:108 ccm fir >a=rtcp-fb:108 nack >a=rtcp-fb:108 nack pli >a=rtcp-fb:108 goog-remb >a=rtpmap:118 rtx/90000 >a=fmtp:118 apt=108 >a=rtpmap:103 H264/90000 >a=rtcp-fb:103 transport-cc >a=rtcp-fb:103 ccm fir >a=rtcp-fb:103 nack >a=rtcp-fb:103 nack pli >a=rtcp-fb:103 goog-remb >a=fmtp:103 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f >a=rtpmap:113 rtx/90000 >a=fmtp:113 apt=103 >a=rtpmap:120 red/90000 >a=rtpmap:121 rtx/90000 >a=fmtp:121 apt=120 >a=rtpmap:122 ulpfec/90000 >a=ssrc-group:FID 1003 1013 >a=ssrc:1003 cname:CNAMECNAMECNAME! >a=ssrc:1003 msid:- video1 >a=ssrc:1003 mslabel:- >a=ssrc:1003 label:video1 >a=ssrc:1013 cname:CNAMECNAMECNAME! >a=ssrc:1013 msid:- video1 >a=ssrc:1013 mslabel:- >a=ssrc:1013 label:video1 >m=application 9 RTP/SAVPF 101 >c=IN IP4 0.0.0.0 >b=AS:30 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:+OXH >a=ice-pwd:CPLFEkDMpW5gcIzL7rFdDU6C >a=ice-options:trickle renomination >a=mid:data >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:101 google-data/90000 >a=ssrc:1001 cname:CNAMECNAMECNAME! >a=ssrc:1001 msid:signaling signaling >a=ssrc:1001 mslabel:signaling >a=ssrc:1001 label:signaling > >INFO 2020-11-25T18:35:05.338Z src/webrtc/peer_connection_observer.rs:200 pc_observer_OnAddAudioRtpReceiver(): 0xe22308f78e94abe3-1, rffi_track: 0x36b5adc98f30 >INFO 2020-11-25T18:35:05.338Z src/webrtc/peer_connection_observer.rs:220 pc_observer_OnAddVideoRtpReceiver(): 0xe22308f78e94abe3-1, rffi_track: 0x36b5adcab1c0 >INFO 2020-11-25T18:35:05.338Z src/webrtc/peer_connection_observer.rs:240 pc_observer_OnSignalingDataChannel(): 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.338Z src/webrtc/data_channel.rs:57 data channel is reliable: false >INFO 2020-11-25T18:35:05.338Z src/webrtc/peer_connection_observer.rs:180 pc_observer_OnAddStream(): 0xe22308f78e94abe3-1, rffi_stream: 0x36b5adc98f60 >INFO 2020-11-25T18:35:05.338Z src/webrtc/sdp_observer.rs:605 ssd_observer_OnSuccess() >INFO 2020-11-25T18:35:05.338Z src/webrtc/sdp_observer.rs:535 on_set_success() >INFO 2020-11-25T18:35:05.338Z src/webrtc/sdp_observer.rs:436 csd_observer_OnSuccess() >INFO 2020-11-25T18:35:05.338Z src/webrtc/sdp_observer.rs:361 on_create_success() >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:722 Using V4 signaling for incoming connection answer: ConnectionParametersV4 { public_key: Some([127, 88, 154, 97, 173, 212, 164, 231, 193, 13, 16, 55, 181, 139, 183, 59, 105, 115, 197, 4, 186, 39, 248, 26, 204, 113, 118, 93, 143, 162, 184, 71]), ice_ufrag: Some("ttdj"), ice_pwd: Some("iJfGup6G/ZSMUgZJHE84TUGx"), receive_video_codecs: [VideoCodec { r#type: Some(Vp8), level: None }] } SDP: v=0 >o=- 1 1 IN IP4 127.0.0.1 >s=- >t=0 0 >a=group:BUNDLE audio video data >a=msid-semantic: WMS >m=audio 9 RTP/SAVPF 102 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ttdj >a=ice-pwd:iJfGup6G/ZSMUgZJHE84TUGx >a=ice-options:trickle renomination >a=mid:audio >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:102 opus/48000/2 >a=rtcp-fb:102 transport-cc >a=fmtp:102 cbr=1;maxaveragebitrate=40000;minptime=10;stereo=0;usedtx=0;useinbandfec=1 >a=maxptime:120 >a=ptime:20 >a=ssrc:1002 cname:CNAMECNAMECNAME! >a=ssrc:1002 msid:- audio1 >a=ssrc:1002 mslabel:- >a=ssrc:1002 label:audio1 >m=video 9 RTP/SAVPF 108 118 120 121 122 >c=IN IP4 0.0.0.0 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ttdj >a=ice-pwd:iJfGup6G/ZSMUgZJHE84TUGx >a=ice-options:trickle renomination >a=mid:video >a=extmap:1 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01 >a=extmap:4 urn:3gpp:video-orientation >a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time >a=extmap:13 urn:ietf:params:rtp-hdrext:toffset >a=sendrecv >a=rtcp-mux >a=rtcp-rsize >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:108 VP8/90000 >a=rtcp-fb:108 transport-cc >a=rtcp-fb:108 ccm fir >a=rtcp-fb:108 nack >a=rtcp-fb:108 nack pli >a=rtcp-fb:108 goog-remb >a=rtpmap:118 rtx/90000 >a=fmtp:118 apt=108 >a=rtpmap:120 red/90000 >a=rtpmap:121 rtx/90000 >a=fmtp:121 apt=120 >a=rtpmap:122 ulpfec/90000 >a=ssrc-group:FID 1003 1013 >a=ssrc:1003 cname:CNAMECNAMECNAME! >a=ssrc:1003 msid:- video1 >a=ssrc:1003 mslabel:- >a=ssrc:1003 label:video1 >a=ssrc:1013 cname:CNAMECNAMECNAME! >a=ssrc:1013 msid:- video1 >a=ssrc:1013 mslabel:- >a=ssrc:1013 label:video1 >m=application 9 RTP/SAVPF 101 >c=IN IP4 0.0.0.0 >b=AS:30 >a=rtcp:9 IN IP4 0.0.0.0 >a=ice-ufrag:ttdj >a=ice-pwd:iJfGup6G/ZSMUgZJHE84TUGx >a=ice-options:trickle renomination >a=mid:data >a=sendrecv >a=rtcp-mux >a=crypto:0 AEAD_AES_256_GCM inline: >a=rtpmap:101 google-data/90000 >a=ssrc:1001 cname:CNAMECNAMECNAME! >a=ssrc:1001 msid:signaling signaling >a=ssrc:1001 mslabel:signaling >a=ssrc:1001 label:signaling > >INFO 2020-11-25T18:35:05.389Z src/webrtc/sdp_observer.rs:605 ssd_observer_OnSuccess() >INFO 2020-11-25T18:35:05.389Z src/webrtc/sdp_observer.rs:535 on_set_success() >INFO 2020-11-25T18:35:05.389Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:760 ringrtc! 1606329305359 conn -> rtc: ice_candidates(0) >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:466 Starting Connection FSM for 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:328 syncing worker thread: ThreadId(7) >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:328 syncing notify thread: ThreadId(8) >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1937 send_answer(): call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1940 ringrtc! 1606329305359 cm -> app: send_answer() 0xe22308f78e94abe3 opaque=true/72; sdp=false >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1950 call_id: 0xe22308f78e94abe3, TX answer: >opaque: ... >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (ReceivedSignalingDataChannel, dc: 0x36b5adc96d20) >INFO 2020-11-25T18:35:05.389Z src/native.rs:558 NativePlatform::on_send_answer(): remote_peer: [REDACTED]429, call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:951 ringrtc! 1606329305359 rtc -> conn: on_data_channel() >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (ReceivedIncomingMedia, stream: rffi_media_stream: 0x36b5adc98f60) >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:782 ringrtc! 1606329305359 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:1322 handle_received_incoming_media(): id: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/native.rs:390 NativePlatform::create_incoming_media() >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:782 ringrtc! 1606329305359 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.389Z src/core/connection_fsm.rs:782 ringrtc! 1606329305359 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/124; sdp=true/120; opaque: ...; sdp: candidate:1967680543 1 udp 2122260223 [REDACTED] 51125 typ host generation 0 ufrag ttdj network-id 1 network-cost 50 >INFO 2020-11-25T18:35:05.389Z src/core/call.rs:456 send_buffered_local_ice_candidates(): ConnectingBeforeAccepted >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1984 send_ice_candidates(): call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.389Z src/core/call_manager.rs:1653 send_next_message(): messages are in flight already >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/120; sdp=true/116; opaque: ...; sdp: candidate:1195588707 1 udp 2122194687 [REDACTED] 40539 typ host generation 0 ufrag ttdj network-id 4 network-cost 50 >INFO 2020-11-25T18:35:05.389Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/108; sdp=true/104; opaque: ...; sdp: candidate:1195588707 1 udp 2113937151 [REDACTED] 54643 typ host generation 0 ufrag ttdj network-cost 999 >INFO 2020-11-25T18:35:05.397Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:35:05.461Z SQL channel job 115 (saveUnprocesseds) succeeded in 12ms >INFO 2020-11-25T18:35:05.461Z queueing envelope 07233c4dd1fb485ebf0ae52a537feab1 >INFO 2020-11-25T18:35:05.461Z received unidentified sender message >INFO 2020-11-25T18:35:05.464Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:35:05.471Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:35:05.490Z src/core/call_manager.rs:456 API:CallManager::handle_received_ice(): >INFO 2020-11-25T18:35:05.490Z src/core/call_manager.rs:1284 ringrtc! 1606329305472 app -> cm: received_ice_candidates(10) 0xe22308f78e94abe3 1 >INFO 2020-11-25T18:35:05.490Z src/core/call_manager.rs:1295 handle_received_ice(): active call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.490Z src/core/call_fsm.rs:215 state: ConnectingBeforeAccepted, event: (ReceivedIce, device: 1) >INFO 2020-11-25T18:35:05.490Z src/core/call.rs:631 received_ice(): id: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.490Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (RemoteIceCandidates) >INFO 2020-11-25T18:35:05.490Z src/core/connection.rs:1167 ringrtc! 1606329305472 conn -> rtc: ice_candidates(10) >INFO 2020-11-25T18:35:05.490Z src/webrtc/peer_connection_observer.rs:158 ringrtc! 1606329305472 rtc -> conn: ice_connection_change(Checking) 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.540Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.540Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.540Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.540Z src/core/connection_fsm.rs:782 ringrtc! 1606329305507 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.540Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.540Z src/core/connection_fsm.rs:782 ringrtc! 1606329305507 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.540Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/156; sdp=true/150; opaque: ...; sdp: candidate:2757933711 1 udp 1685987071 [REDACTED] 24943 typ srflx raddr [REDACTED] rport 40539 generation 0 ufrag ttdj network-id 4 network-cost 50 >INFO 2020-11-25T18:35:05.540Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/142; sdp=true/136; opaque: ...; sdp: candidate:842163049 1 udp 1677729535 [REDACTED] 2234 typ srflx raddr [REDACTED] rport 54643 generation 0 ufrag ttdj network-cost 999 >INFO 2020-11-25T18:35:05.571Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:35:05.571Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:35:05.590Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:35:05.590Z src/core/call_manager.rs:956 ringrtc! 1606329305571 app -> cm: message_sent() 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.591Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.591Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:35:05.591Z src/core/connection.rs:1148 take_buffered_local_ice_candidates(): >INFO 2020-11-25T18:35:05.591Z src/core/connection.rs:1155 take_buffered_local_ice_candidates(): Local ICE candidates length: 5 >INFO 2020-11-25T18:35:05.591Z src/core/call_manager.rs:1993 ringrtc! 1606329305571 cm -> app: send_ice_candidates(5) 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.591Z src/native.rs:577 NativePlatform::on_send_ice(): remote_peer: [REDACTED]429, call_id: 0xe22308f78e94abe3, receiver_device_id: Some(1), candidates: 5 >INFO 2020-11-25T18:35:05.597Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:35:05.751Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:35:05.751Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:956 ringrtc! 1606329305751 app -> cm: message_sent() 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1691 send_next_message(): no messages to send >INFO 2020-11-25T18:35:05.791Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.791Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.791Z src/core/connection_fsm.rs:782 ringrtc! 1606329305753 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.791Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/157; sdp=true/151; opaque: ...; sdp: candidate:1173891001 1 udp 41820415 [REDACTED] 56280 typ relay raddr [REDACTED] rport 24943 generation 0 ufrag ttdj network-id 4 network-cost 50 >INFO 2020-11-25T18:35:05.791Z src/core/call.rs:456 send_buffered_local_ice_candidates(): ConnectingBeforeAccepted >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1984 send_ice_candidates(): call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:35:05.791Z src/core/connection.rs:1148 take_buffered_local_ice_candidates(): >INFO 2020-11-25T18:35:05.791Z src/core/connection.rs:1155 take_buffered_local_ice_candidates(): Local ICE candidates length: 1 >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1993 ringrtc! 1606329305753 cm -> app: send_ice_candidates(1) 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.791Z src/native.rs:577 NativePlatform::on_send_ice(): remote_peer: [REDACTED]429, call_id: 0xe22308f78e94abe3, receiver_device_id: Some(1), candidates: 1 >INFO 2020-11-25T18:35:05.791Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.791Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.791Z src/core/connection_fsm.rs:782 ringrtc! 1606329305753 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.791Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/144; sdp=true/138; opaque: ...; sdp: candidate:1173891001 1 udp 33562879 [REDACTED] 58409 typ relay raddr [REDACTED] rport 2234 generation 0 ufrag ttdj network-cost 999 >INFO 2020-11-25T18:35:05.791Z src/core/call.rs:456 send_buffered_local_ice_candidates(): ConnectingBeforeAccepted >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1984 send_ice_candidates(): call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.791Z src/core/call_manager.rs:1653 send_next_message(): messages are in flight already >INFO 2020-11-25T18:35:05.797Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:35:05.892Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.892Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.892Z src/core/connection_fsm.rs:782 ringrtc! 1606329305882 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.892Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/144; sdp=true/138; opaque: ...; sdp: candidate:192666441 1 udp 16785151 [REDACTED] 55621 typ relay raddr [REDACTED] rport 50914 generation 0 ufrag ttdj network-cost 999 >INFO 2020-11-25T18:35:05.942Z src/webrtc/peer_connection_observer.rs:128 pc_observer_OnIceCandidate: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.942Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (LocalIceCandidate) >INFO 2020-11-25T18:35:05.942Z src/core/connection_fsm.rs:782 ringrtc! 1606329305893 rtc -> conn: ice_candidate() 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:05.942Z src/core/connection.rs:1105 Local ICE candidate: opaque=true/156; sdp=true/150; opaque: ...; sdp: candidate:192666441 1 udp 25042687 [REDACTED] 56536 typ relay raddr [REDACTED] rport 27665 generation 0 ufrag ttdj network-id 4 network-cost 50 >INFO 2020-11-25T18:35:05.948Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:35:05.948Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:35:05.981Z SQL channel job 116 (updateUnprocessedsWithData) succeeded in 17ms >INFO 2020-11-25T18:35:05.992Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:35:05.992Z src/core/call_manager.rs:956 ringrtc! 1606329305948 app -> cm: message_sent() 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.992Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:05.992Z src/core/call_manager.rs:1659 send_next_message(): sending message, len: 0 >INFO 2020-11-25T18:35:05.992Z src/core/connection.rs:1148 take_buffered_local_ice_candidates(): >INFO 2020-11-25T18:35:05.992Z src/core/connection.rs:1155 take_buffered_local_ice_candidates(): Local ICE candidates length: 3 >INFO 2020-11-25T18:35:05.992Z src/core/call_manager.rs:1993 ringrtc! 1606329305948 cm -> app: send_ice_candidates(3) 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:05.992Z src/native.rs:577 NativePlatform::on_send_ice(): remote_peer: [REDACTED]429, call_id: 0xe22308f78e94abe3, receiver_device_id: Some(1), candidates: 3 >INFO 2020-11-25T18:35:05.999Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 (unauth) >INFO 2020-11-25T18:35:06.002Z SQL channel job 117 (removeUnprocessed) succeeded in 37ms >INFO 2020-11-25T18:35:06.181Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]429 200 Success >INFO 2020-11-25T18:35:06.181Z handleOutgoingSignaling() completed successfully >INFO 2020-11-25T18:35:06.193Z src/core/call_manager.rs:407 API:CallManager::handle_message_sent(): >INFO 2020-11-25T18:35:06.193Z src/core/call_manager.rs:956 ringrtc! 1606329306181 app -> cm: message_sent() 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:06.193Z src/core/call_manager.rs:1626 send_next_message(): >INFO 2020-11-25T18:35:06.193Z src/core/call_manager.rs:1691 send_next_message(): no messages to send >INFO 2020-11-25T18:35:06.294Z src/webrtc/peer_connection_observer.rs:158 ringrtc! 1606329306287 rtc -> conn: ice_connection_change(Connected) 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:06.294Z src/core/connection_fsm.rs:282 state: ConnectingBeforeAccepted, event: (IceConnected) >INFO 2020-11-25T18:35:06.294Z src/core/call.rs:975 on_connection_observer_event(): call_id: 0xe22308f78e94abe3, remote_device_id: 1, event: ConnectedWithDataChannelBeforeAccepted >INFO 2020-11-25T18:35:06.294Z src/core/call_fsm.rs:215 state: ConnectingBeforeAccepted, event: (ConnectionObserverEvent, event: ConnectedWithDataChannelBeforeAccepted, device: 1) >INFO 2020-11-25T18:35:06.294Z src/core/call_manager.rs:1764 ringrtc! 1606329306287 cm -> app: event(LocalRinging) >INFO 2020-11-25T18:35:06.294Z src/native.rs:438 NativePlatform::on_event(): remote_peer: [REDACTED]429, event: LocalRinging >INFO 2020-11-25T18:35:06.357Z Approving request for permission 'notifications' >INFO 2020-11-25T18:35:06.517Z SQL channel job 118 (createOrUpdateSessions) succeeded in 22ms >INFO 2020-11-25T18:35:07.051Z SQL channel job 119 (saveUnprocesseds) succeeded in 19ms >INFO 2020-11-25T18:35:07.051Z queueing envelope 0f7d1bcfeaa24b2ba4ee06fefed35a56 >INFO 2020-11-25T18:35:07.051Z received unidentified sender message >INFO 2020-11-25T18:35:07.053Z New remote ephemeral key >INFO 2020-11-25T18:35:07.055Z Deleting chain closed at 1606325194913 >INFO 2020-11-25T18:35:07.056Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:35:07.057Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:35:07.106Z src/core/call_manager.rs:456 API:CallManager::handle_received_ice(): >INFO 2020-11-25T18:35:07.106Z src/core/call_manager.rs:1284 ringrtc! 1606329307057 app -> cm: received_ice_candidates(1) 0xe22308f78e94abe3 1 >INFO 2020-11-25T18:35:07.106Z src/core/call_manager.rs:1295 handle_received_ice(): active call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:07.106Z src/core/call_fsm.rs:215 state: ConnectedWithDataChannelBeforeAccepted, event: (ReceivedIce, device: 1) >INFO 2020-11-25T18:35:07.106Z src/core/call.rs:631 received_ice(): id: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:07.106Z src/core/connection_fsm.rs:282 state: ConnectedBeforeAccepted, event: (RemoteIceCandidates) >INFO 2020-11-25T18:35:07.106Z src/core/connection.rs:1167 ringrtc! 1606329307057 conn -> rtc: ice_candidates(1) >INFO 2020-11-25T18:35:07.485Z SQL channel job 120 (saveUnprocesseds) succeeded in 12ms >INFO 2020-11-25T18:35:07.485Z queueing envelope ec52b2a991ba466b84611238187d87a9 >INFO 2020-11-25T18:35:07.485Z received unidentified sender message >INFO 2020-11-25T18:35:07.488Z CallingClass.handleCallingMessage() >INFO 2020-11-25T18:35:07.489Z CallingClass.handleCallingMessage(): Handling in RingRTC >INFO 2020-11-25T18:35:07.507Z src/core/call_manager.rs:456 API:CallManager::handle_received_ice(): >INFO 2020-11-25T18:35:07.507Z src/core/call_manager.rs:1284 ringrtc! 1606329307489 app -> cm: received_ice_candidates(4) 0xe22308f78e94abe3 1 >INFO 2020-11-25T18:35:07.508Z src/core/call_manager.rs:1295 handle_received_ice(): active call_id: 0xe22308f78e94abe3 >INFO 2020-11-25T18:35:07.508Z src/core/call_fsm.rs:215 state: ConnectedWithDataChannelBeforeAccepted, event: (ReceivedIce, device: 1) >INFO 2020-11-25T18:35:07.508Z src/core/call.rs:631 received_ice(): id: 0xe22308f78e94abe3-1 >INFO 2020-11-25T18:35:07.508Z src/core/connection_fsm.rs:282 state: ConnectedBeforeAccepted, event: (RemoteIceCandidates) >INFO 2020-11-25T18:35:07.508Z src/core/connection.rs:1167 ringrtc! 1606329307489 conn -> rtc: ice_candidates(4) >INFO 2020-11-25T18:35:08.004Z SQL channel job 121 (createOrUpdateSessions) succeeded in 16ms >INFO 2020-11-25T18:35:08.015Z SQL channel job 122 (updateUnprocessedsWithData) succeeded in 27ms >INFO 2020-11-25T18:35:08.035Z SQL channel job 123 (removeUnprocessed) succeeded in 47ms >INFO 2020-11-25T18:35:23.499Z before-quit event {"shouldQuit":false} >INFO 2020-11-25T18:35:23.499Z close event {"shouldQuit":true} >INFO 2020-11-25T18:35:23.499Z requestShutdown: Requesting close of mainWindow... >INFO 2020-11-25T18:35:36.810Z app ready >INFO 2020-11-25T18:35:36.810Z starting version 1.38.2 >INFO 2020-11-25T18:35:36.821Z updateSchema: > Current user_version: 21; > Most recent db schema: 21; > SQLite version: 3.30.1; > SQLCipher version: 4.3.0 community; > (deprecated) schema_version: 60; > >INFO 2020-11-25T18:35:37.192Z Initializing BrowserWindow config: {"show":false,"width":1920,"height":994,"minWidth":680,"minHeight":550,"autoHideMenuBar":false,"backgroundColor":"#3a76f0","webPreferences":{"nodeIntegration":false,"nodeIntegrationInWorker":false,"contextIsolation":false,"preload":"[REDACTED]/app.asar/preload.js","nativeWindowOpen":true,"spellcheck":true,"backgroundThrottling":false},"icon":"[REDACTED]/app.asar/images/signal-logo-desktop-linux.png","x":0,"y":55} >INFO 2020-11-25T18:35:37.236Z spellcheck: user locale: de-DE >INFO 2020-11-25T18:35:37.236Z spellcheck: available spellchecker languages: ["af","bg","ca","cs","cy","da","de","el","en-AU","en-CA","en-GB","en-US","es","es-419","es-AR","es-ES","es-MX","es-US","et","fa","fo","fr","he","hi","hr","hu","hy","id","it","ko","lt","lv","nb","nl","pl","pt-BR","pt-PT","ro","ru","sh","sk","sl","sq","sr","sv","ta","tg","tr","uk","vi"] >INFO 2020-11-25T18:35:37.236Z spellcheck: setting languages to: ["de"] >INFO 2020-11-25T18:35:37.262Z Begin ensuring permissions >INFO 2020-11-25T18:35:37.270Z Ensuring file permissions for 2 files >INFO 2020-11-25T18:35:37.271Z Finish ensuring permissions in 9ms >INFO 2020-11-25T18:35:38.067Z x-attr dependency did not load successfully >INFO 2020-11-25T18:35:38.091Z preload complete >INFO 2020-11-25T18:35:38.240Z pre-main prep time: 1 ms >INFO 2020-11-25T18:35:38.302Z background page reloaded >INFO 2020-11-25T18:35:38.302Z environment: production >INFO 2020-11-25T18:35:38.321Z SQL channel job 1 (getItemById) succeeded in 18ms >INFO 2020-11-25T18:35:38.321Z Storage fetch >INFO 2020-11-25T18:35:38.432Z SQL channel job 2 (getAllItems) succeeded in 111ms >INFO 2020-11-25T18:35:38.450Z SQL channel job 3 (createOrUpdateItem) succeeded in 16ms >INFO 2020-11-25T18:35:38.481Z SQL channel job 5 (createOrUpdateItem) succeeded in 30ms >INFO 2020-11-25T18:35:38.481Z Starting background data migration. Target version: 10 >INFO 2020-11-25T18:35:38.485Z GET https://textsecure-service.whispersystems.org/v1/config >INFO 2020-11-25T18:35:38.491Z ConversationController: starting initial fetch >INFO 2020-11-25T18:35:38.511Z SQL channel job 7 (getAllStickerPacks) succeeded in 20ms >INFO 2020-11-25T18:35:38.511Z SQL channel job 8 (getAllStickers) succeeded in 20ms >INFO 2020-11-25T18:35:38.511Z SQL channel job 9 (getRecentStickers) succeeded in 20ms >INFO 2020-11-25T18:35:38.512Z SQL channel job 10 (getRecentEmojis) succeeded in 21ms >INFO 2020-11-25T18:35:38.512Z SQL channel job 12 (getItemById) succeeded in 20ms >INFO 2020-11-25T18:35:38.512Z SQL channel job 13 (getAllIdentityKeys) succeeded in 20ms >INFO 2020-11-25T18:35:38.513Z SignalProtocolStore: Finished caching identityKeys data >INFO 2020-11-25T18:35:38.513Z SQL channel job 14 (getAllSessions) succeeded in 21ms >INFO 2020-11-25T18:35:38.513Z SignalProtocolStore: Finished caching sessions data >INFO 2020-11-25T18:35:38.513Z SQL channel job 11 (getItemById) succeeded in 21ms >INFO 2020-11-25T18:35:38.521Z SQL channel job 53 (getLastConversationPreview) succeeded in 11ms >INFO 2020-11-25T18:35:38.521Z SQL channel job 54 (getLastConversationActivity) succeeded in 11ms >INFO 2020-11-25T18:35:38.522Z SQL channel job 55 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:35:38.522Z SQL channel job 56 (getLastConversationActivity) succeeded in 12ms >INFO 2020-11-25T18:35:38.522Z SQL channel job 57 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:35:38.522Z SQL channel job 58 (getLastConversationActivity) succeeded in 12ms >INFO 2020-11-25T18:35:38.522Z SQL channel job 59 (getLastConversationPreview) succeeded in 12ms >INFO 2020-11-25T18:35:38.523Z SQL channel job 60 (getLastConversationActivity) succeeded in 13ms >INFO 2020-11-25T18:35:38.523Z SQL channel job 61 (getLastConversationPreview) succeeded in 13ms >INFO 2020-11-25T18:35:38.523Z SQL channel job 62 (getLastConversationActivity) succeeded in 13ms >INFO 2020-11-25T18:35:38.523Z SQL channel job 63 (getLastConversationPreview) succeeded in 13ms >INFO 2020-11-25T18:35:38.523Z SQL channel job 64 (getLastConversationActivity) succeeded in 13ms >INFO 2020-11-25T18:35:38.524Z SQL channel job 65 (getLastConversationPreview) succeeded in 14ms >INFO 2020-11-25T18:35:38.524Z SQL channel job 66 (getLastConversationActivity) succeeded in 14ms >INFO 2020-11-25T18:35:38.524Z SQL channel job 67 (getLastConversationPreview) succeeded in 14ms >INFO 2020-11-25T18:35:38.524Z SQL channel job 69 (getLastConversationPreview) succeeded in 14ms >INFO 2020-11-25T18:35:38.525Z SQL channel job 70 (getLastConversationActivity) succeeded in 15ms >INFO 2020-11-25T18:35:38.525Z SQL channel job 68 (getLastConversationActivity) succeeded in 15ms >INFO 2020-11-25T18:35:38.525Z SQL channel job 71 (getLastConversationPreview) succeeded in 15ms >INFO 2020-11-25T18:35:38.525Z SQL channel job 72 (getLastConversationActivity) succeeded in 15ms >INFO 2020-11-25T18:35:38.526Z SQL channel job 73 (getLastConversationPreview) succeeded in 16ms >INFO 2020-11-25T18:35:38.526Z SQL channel job 74 (getLastConversationActivity) succeeded in 16ms >INFO 2020-11-25T18:35:38.526Z SQL channel job 75 (getLastConversationPreview) succeeded in 16ms >INFO 2020-11-25T18:35:38.526Z SQL channel job 76 (getLastConversationActivity) succeeded in 16ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 77 (getLastConversationPreview) succeeded in 16ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 78 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 80 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 79 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 81 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 82 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 83 (getLastConversationPreview) succeeded in 16ms >INFO 2020-11-25T18:35:38.527Z SQL channel job 84 (getLastConversationActivity) succeeded in 16ms >INFO 2020-11-25T18:35:38.528Z SQL channel job 85 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:35:38.528Z SQL channel job 86 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:35:38.528Z SQL channel job 87 (getLastConversationPreview) succeeded in 17ms >INFO 2020-11-25T18:35:38.528Z SQL channel job 88 (getLastConversationActivity) succeeded in 17ms >INFO 2020-11-25T18:35:38.528Z ConversationController: done with initial fetch >INFO 2020-11-25T18:35:38.528Z SQL channel job 89 (getAllPreKeys) succeeded in 15ms >INFO 2020-11-25T18:35:38.531Z SignalProtocolStore: Finished caching preKeys data >INFO 2020-11-25T18:35:38.531Z SQL channel job 90 (getAllSignedPreKeys) succeeded in 18ms >INFO 2020-11-25T18:35:38.531Z SignalProtocolStore: Finished caching signedPreKeys data >INFO 2020-11-25T18:35:38.532Z checkForConflicts: starting... >INFO 2020-11-25T18:35:38.532Z checkForConflicts: complete! >INFO 2020-11-25T18:35:38.546Z Cleanup: starting... >INFO 2020-11-25T18:35:38.547Z Initializing network observer every 5000ms >INFO 2020-11-25T18:35:38.547Z Build expires: 2021-02-21T18:41:50.000Z >INFO 2020-11-25T18:35:38.547Z SQL channel job 91 (updateConversations) succeeded in 26ms >INFO 2020-11-25T18:35:38.547Z Cleanup: Found 0 messages for cleanup >INFO 2020-11-25T18:35:38.547Z Cleanup: complete >INFO 2020-11-25T18:35:38.547Z listening for registration events >INFO 2020-11-25T18:35:38.551Z connect {"connectCount":0} >INFO 2020-11-25T18:35:38.551Z GET https://textsecure-service.whispersystems.org/v1/config >INFO 2020-11-25T18:35:38.552Z open inbox >INFO 2020-11-25T18:35:39.051Z SQL channel job 95 (updateConversations) succeeded in 23ms >INFO 2020-11-25T18:35:39.321Z GET https://textsecure-service.whispersystems.org/v1/config 200 Success >INFO 2020-11-25T18:35:39.333Z SQL channel job 96 (createOrUpdateItem) succeeded in 12ms >INFO 2020-11-25T18:35:39.466Z GET https://textsecure-service.whispersystems.org/v1/config 200 Success >INFO 2020-11-25T18:35:39.467Z Initializing socket and listening for messages >INFO 2020-11-25T18:35:39.467Z getAllFromCache >INFO 2020-11-25T18:35:39.467Z opening message socket https://textsecure-service.whispersystems.org >INFO 2020-11-25T18:35:39.469Z initializeGroupCredentialFetcher: starting... >INFO 2020-11-25T18:35:39.469Z maybeFetchCredentials: no new credentials needed >INFO 2020-11-25T18:35:39.469Z attachment_downloads/start: enabling >INFO 2020-11-25T18:35:39.470Z Start idle detector >INFO 2020-11-25T18:35:39.475Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]372 >INFO 2020-11-25T18:35:39.483Z SQL channel job 97 (createOrUpdateItem) succeeded in 17ms >INFO 2020-11-25T18:35:39.483Z SQL channel job 98 (getUnprocessedCount) succeeded in 16ms >INFO 2020-11-25T18:35:39.483Z SQL channel job 99 (resetAttachmentDownloadPending) succeeded in 14ms >INFO 2020-11-25T18:35:39.484Z getAllFromCache loaded 0 saved envelopes >INFO 2020-11-25T18:35:39.653Z PUT https://textsecure-service.whispersystems.org/v1/messages/[REDACTED]372 200 Success >INFO 2020-11-25T18:35:39.993Z SQL channel job 103 (createOrUpdateSessions) succeeded in 18ms >INFO 2020-11-25T18:35:40.197Z websocket open >INFO 2020-11-25T18:35:40.199Z got request PUT /api/v1/queue/empty >INFO 2020-11-25T18:35:40.200Z MessageReceiver: finished processing messages after 'empty', now waiting for application >INFO 2020-11-25T18:35:40.200Z MessageReceiver: emitting 'empty' event >INFO 2020-11-25T18:35:40.201Z onEmpty: All outstanding database requests complete >INFO 2020-11-25T18:35:40.201Z Next signed key rotation scheduled for 2020-11-27T16:46:46.838Z >INFO 2020-11-25T18:35:40.201Z refreshSenderCertificate: Getting new certificate... >INFO 2020-11-25T18:35:40.202Z GET https://textsecure-service.whispersystems.org/v1/certificate/delivery?includeUuid=true >INFO 2020-11-25T18:35:40.203Z updater/start: Updates disabled - not starting new version checks >INFO 2020-11-25T18:35:40.368Z GET https://textsecure-service.whispersystems.org/v1/certificate/delivery?includeUuid=true 200 Success >INFO 2020-11-25T18:35:40.369Z Next sender certificate refresh scheduled for 2020-11-26T18:35:40.369Z >INFO 2020-11-25T18:35:40.369Z refreshOurProfile >INFO 2020-11-25T18:35:40.372Z GET https://textsecure-service.whispersystems.org/v1/profile/[REDACTED]372/[REDACTED]e81 (unauth) >INFO 2020-11-25T18:35:40.381Z SQL channel job 104 (createOrUpdateItem) succeeded in 11ms >INFO 2020-11-25T18:35:41.105Z SQL channel job 105 (saveUnprocesseds) succeeded in 12ms >INFO 2020-11-25T18:35:41.106Z queueing envelope [REDACTED]372.1 1606329341606 (060983cda3e744ab97d334d47e81e544) >INFO 2020-11-25T18:35:41.106Z message from [REDACTED]372.1 1606329341606 (060983cda3e744ab97d334d47e81e544) >INFO 2020-11-25T18:35:41.107Z New remote ephemeral key >INFO 2020-11-25T18:35:41.109Z Deleting chain closed at 1606316334030 >INFO 2020-11-25T18:35:41.110Z got keys sync message >INFO 2020-11-25T18:35:41.110Z onKeysSync: received keys >INFO 2020-11-25T18:35:41.128Z SQL channel job 106 (createOrUpdateItem) succeeded in 18ms >INFO 2020-11-25T18:35:41.585Z GET https://textsecure-service.whispersystems.org/v1/profile/[REDACTED]372/[REDACTED]e81 200 Success >INFO 2020-11-25T18:35:41.585Z Setting sealedSender to ENABLED for conversation [REDACTED]372 ([REDACTED]34d) >INFO 2020-11-25T18:35:41.586Z GET https://cdn.signal.org/[REDACTED]yaA >INFO 2020-11-25T18:35:41.600Z SQL channel job 107 (createOrUpdateItem) succeeded in 14ms >INFO 2020-11-25T18:35:41.611Z storageService.sync: starting... >INFO 2020-11-25T18:35:41.611Z storageService.fetchManifest >INFO 2020-11-25T18:35:41.611Z GET https://textsecure-service.whispersystems.org/v1/storage/auth >INFO 2020-11-25T18:35:41.622Z SQL channel job 108 (createOrUpdateSessions) succeeded in 12ms >INFO 2020-11-25T18:35:41.632Z SQL channel job 109 (updateUnprocessedsWithData) succeeded in 22ms >INFO 2020-11-25T18:35:41.643Z SQL channel job 110 (removeUnprocessed) succeeded in 33ms >INFO 2020-11-25T18:35:41.746Z GET https://textsecure-service.whispersystems.org/v1/storage/auth 200 Success >INFO 2020-11-25T18:35:41.747Z GET https://storage.signal.org/v1/storage/manifest/version/64 >INFO 2020-11-25T18:35:41.764Z SQL channel job 111 (createOrUpdateItem) succeeded in 18ms >INFO 2020-11-25T18:35:41.883Z GET https://cdn.signal.org/[REDACTED]yaA 200 Success >INFO 2020-11-25T18:35:42.396Z SQL channel job 112 (updateConversations) succeeded in 12ms >INFO 2020-11-25T18:35:42.550Z GET https://storage.signal.org/v1/storage/manifest/version/64 204 Success >INFO 2020-11-25T18:35:42.550Z storageService.fetchManifest: nothing changed >INFO 2020-11-25T18:35:42.550Z storageService.sync: no new manifest >INFO 2020-11-25T18:35:44.153Z Loaded this list of log files from logPath: log.log, log.log.0, log.log.1, log.log.2
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 755944
:
674998
| 675001