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