My messages are not received after the conversation partner links her/his device to Signal Desktop
Setup
- Device: Xperia XA2
- SailfishOS version: 3.4.0.24
- Whisperfish version: v0.6.0-dev.b1340.906c5952
- Migration from 0.5
Summary
I see an issue I saw before regarding someone linking her device to Signal Desktop. She linked her device and after that my messages did not get through to her (which I did not notice because sent/received status is not shown correctly).
I have logs.
- 12:40:20 - a message sent from WF to her (not received)
- 12:41:22 - a message sent from Desktop to her (worked)
- 12:41:36 - a message from her to me (accidential
😉 ) - 12:41:41 - a message from WF to her (worked).
Trace log
click to see log
[2021-05-03T12:40:01Z TRACE harbour_whisperfish::store] Called mark_session_read(27)
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::actor::messageactor] MessageActor::handle(QueueMessage { e164: "+491111111111", message: "Test, test, 1, 2 ...", attachment: "" })
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Called fetch_or_insert_session_by_recipient_id(33)
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Called fetch__session_by_recipient_id(33)
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Called create_message(..) for session 27
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Inserted message id 32058
[2021-05-03T12:40:20Z INFO harbour_whisperfish::worker::client] ClientActor::SendMessage(32058)
[2021-05-03T12:40:20Z DEBUG rustls::anchors] add_pem_file processed 1 valid and 0 invalid certs
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Called fetch_message_by_id(32058)
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::worker::client] Sending for session: Session { id: 27, is_archived: false, is_pinned: false, is_silent: false, is_muted: false, expiring_message_timeout: None, draft: None, type: DirectMessage(Recipient { id: 33, e164: Some("+491111111111"), uuid: None, username: None, email: None, blocked: false, profile_key: None, profile_key_credential: None, profile_given_name: None, profile_family_name: None, profile_joined_name: None, signal_profile_avatar: None, profile_sharing: false, last_profile_fetch: None, unidentified_access_mode: false, storage_service_id: None, storage_proto: None, capabilities: 0, last_gv1_migrate_reminder: None, last_session_reset: None }) }
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::worker::client] Sending message: Message { id: 32058, session_id: 27, text: Some("Test, test, 1, 2 ..."), sender_recipient_id: Some(33), received_timestamp: None, sent_timestamp: None, server_timestamp: 2021-05-03T12:40:20.221, is_read: true, is_outbound: true, flags: 0, expires_in: None, expiry_started: None, schedule_send_time: None, is_bookmarked: false, use_unidentified: false, is_remote_deleted: false }
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::worker::client] Transmitting DataMessage { body: Some("Test, test, 1, 2 ..."), attachments: [], group: None, group_v2: None, flags: None, expire_timer: None, profile_key: Some([..]), timestamp: Some(1620045620221), quote: None, contact: [], preview: [], sticker: None, required_protocol_version: Some(0), is_view_once: None, reaction: None, delete: None, body_ranges: [] }
[2021-05-03T12:40:20Z TRACE libsignal_service::sender] sending message to default device
[2021-05-03T12:40:20Z TRACE libsignal_service::sender] encrypting message for Address { name: "+491111111111", device_id: 1 }
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "+491111111111", device_id: 1 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1", 688 bytes
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "+491111111111", device_id: 1 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1", 688 bytes
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "+491111111111", device_id: 1 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "+491111111111", device_id: 1 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1", 688 bytes
[2021-05-03T12:40:20Z TRACE harbour_whisperfish::store::protocol_store] Looking for sub_device sessions for +491111111111
[2021-05-03T12:40:20Z DEBUG libsignal_service_actix::push_service] HTTP request PUT https://textsecure-service.whispersystems.org/v1/messages/+491111111111
[2021-05-03T12:40:20Z TRACE actix_tls::connect::resolve] DNS resolver: resolving host "textsecure-service.whispersystems.org"
[2021-05-03T12:40:20Z TRACE actix_tls::connect::resolve] DNS resolver: host "textsecure-service.whispersystems.org" resolved to [76.223.92.165:443, 13.248.212.111:443]
[2021-05-03T12:40:20Z TRACE actix_tls::connect::connector] TCP connector: connecting to textsecure-service.whispersystems.org on port 443
[2021-05-03T12:40:20Z TRACE mio::poll] registering event source with poller: token=Token(16777219), interests=READABLE | WRITABLE
[2021-05-03T12:40:20Z TRACE actix_tls::connect::connector] TCP connector: successfully connected to "textsecure-service.whispersystems.org" - Ok(76.223.92.165:443)
[2021-05-03T12:40:20Z TRACE actix_tls::connect::ssl::rustls] SSL Handshake start for: "textsecure-service.whispersystems.org"
[2021-05-03T12:40:20Z DEBUG rustls::client::hs] No cached session for DNSNameRef("textsecure-service.whispersystems.org")
[2021-05-03T12:40:20Z DEBUG rustls::client::hs] Not resuming any session
[2021-05-03T12:40:20Z TRACE rustls::client::hs] Sending ClientHello Message {
typ: Handshake,
version: TLSv1_0,
payload: Handshake(
HandshakeMessagePayload {
typ: ClientHello,
payload: ClientHello(
ClientHelloPayload {
client_version: TLSv1_2,
random: Random(
[
32,
117,
163,
58,
243,
58,
173,
84,
213,
91,
182,
153,
241,
74,
29,
68,
143,
107,
171,
24,
98,
21,
31,
168,
175,
168,
79,
204,
36,
212,
177,
218,
],
),
session_id: SessionID(
245,
255,
56,
8,
212,
231,
36,
11,
21,
25,
148,
126,
87,
84,
195,
134,
140,
231,
78,
189,
136,
86,
121,
52,
89,
28,
205,
61,
130,
211,
141,
176,
),
cipher_suites: [
TLS13_CHACHA20_POLY1305_SHA256,
TLS13_AES_256_GCM_SHA384,
TLS13_AES_128_GCM_SHA256,
TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256,
TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305_SHA256,
TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384,
TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256,
TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384,
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256,
TLS_EMPTY_RENEGOTIATION_INFO_SCSV,
],
compression_methods: [
Null,
],
extensions: [
SupportedVersions(
[
TLSv1_3,
TLSv1_2,
],
),
ServerName(
[
ServerName {
typ: HostName,
payload: HostName(
DNSName(
"textsecure-service.whispersystems.org",
),
),
},
],
),
ECPointFormats(
[
Uncompressed,
],
),
NamedGroups(
[
X25519,
secp384r1,
secp256r1,
],
),
SignatureAlgorithms(
[
ECDSA_NISTP384_SHA384,
ECDSA_NISTP256_SHA256,
ED25519,
RSA_PSS_SHA512,
RSA_PSS_SHA384,
RSA_PSS_SHA256,
RSA_PKCS1_SHA512,
RSA_PKCS1_SHA384,
RSA_PKCS1_SHA256,
],
),
ExtendedMasterSecretRequest,
CertificateStatusRequest(
OCSP(
OCSPCertificateStatusRequest {
responder_ids: [],
extensions: PayloadU16(
[],
),
},
),
),
KeyShare(
[
KeyShareEntry {
group: X25519,
payload: PayloadU16(
[
141,
83,
217,
232,
112,
81,
84,
69,
90,
190,
17,
31,
36,
212,
203,
225,
169,
21,
26,
153,
174,
51,
89,
113,
239,
82,
201,
194,
26,
187,
180,
36,
],
),
},
],
),
PresharedKeyModes(
[
PSK_DHE_KE,
],
),
Protocols(
[
PayloadU8(
[
104,
116,
116,
112,
47,
49,
46,
49,
],
),
],
),
SessionTicketRequest,
],
},
),
},
),
}
[2021-05-03T12:40:20Z TRACE rustls::client::hs] We got ServerHello ServerHelloPayload {
legacy_version: TLSv1_2,
random: Random(
[
220,
115,
236,
178,
247,
106,
202,
158,
41,
129,
47,
106,
30,
157,
201,
19,
237,
211,
55,
204,
138,
141,
33,
148,
134,
228,
188,
114,
27,
170,
118,
6,
],
),
session_id: SessionID,
cipher_suite: TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256,
compression_method: Null,
extensions: [
RenegotiationInfo(
PayloadU8(
[],
),
),
ECPointFormats(
[
Uncompressed,
ANSIX962CompressedPrime,
ANSIX962CompressedChar2,
],
),
SessionTicketAck,
Protocols(
[
PayloadU8(
[
104,
116,
116,
112,
47,
49,
46,
49,
],
),
],
),
],
}
[2021-05-03T12:40:20Z DEBUG rustls::client::hs] ALPN protocol is Some(b"http/1.1")
[2021-05-03T12:40:20Z DEBUG rustls::client::hs] Using ciphersuite TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
[2021-05-03T12:40:20Z DEBUG rustls::client::hs] Server supports tickets
[2021-05-03T12:40:20Z DEBUG rustls::client::tls12] ECDHE curve is ECParameters { curve_type: NamedCurve, named_group: secp256r1 }
[2021-05-03T12:40:20Z TRACE rustls::client::tls12] Server cert is [Certificate(b"0\x82\x03\xe30\x82\x02\xcb\xa0\x03\x02\x01\x02\x02\x02\x10\x180\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\00\x81\x8d1\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x160\x14\x06\x03U\x04\x07\x0c\rSan Francisco1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1\x130\x11\x06\x03U\x04\x03\x0c\nTextSecure0\x1e\x17\r190215173817Z\x17\r290312182020Z0\x81\x901\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1.0,\x06\x03U\x04\x03\x0c%textsecure-service.whispersystems.org0\x82\x01\"0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x01\x0f\00\x82\x01\n\x02\x82\x01\x01\0\xac\xc8\x11\xb5\xd1E\xb7\xc0\xa2\xcb\xcf\x93\x89\x9a\x80u\xb3\xb0xp;;\xbb:~\xc4\x03\x8cq\xa9X\x87l\xde(\xc7\x88\xe5\xe4\xb8}\x9a\xee\xa7Um\x1e\x06\xfc\\\xf74\n<m\"\x8e\x04\t7\xf4\x95\xe7x\xc5\xc2:\xaa\xb6\xbe\ta;\x07\xcc\xab:n\x01\xe5`~\xac[u\xc7\xc5\xe3\xe3\xe4\x91JI%\xd6c\xb9J4\x04\x95\x97\x92\xa4JhZ\x89\x82-\x10\xf11\xbd*\x0f\x07\xd6\xcd\xc2\xeab\xc5\x9e@{H\x88\x13\xf3X@\x0e\xb2ezPM\x8d\x1d\x05M%\xd6\xad)A\xe5\xf3-z\xa0%\xebS\x874\x97\xaf\xa8\xf9\xb2\x83N\xd8\xac\x91\xa0\xc0Ml\x8e6|\xf5\x18\xbddB9G\xf2P\xd26}\xbd\xea\xb2\xe6C\xf9\x04;`\xace\xe6\x18\x03\x05O(4#M\xf3K\rZ8a\xe6qD9a\xa3\xe6\\e\xd6\xa6\x13\x92\x9d\xecX\x92F`>\x9c\xb9\0\xc9\xd4:\xd2(\xd6\xc3Qi\xec\x94\x87\xbe\xc2\xf6_O\x1f~\x92m\x02\x03\x01\0\x01\xa3H0F0D\x06\x03U\x1d\x11\x04=0;\x82%textsecure-service.whispersystems.org\x82\x12service.signal.org0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x01\x01\0)k.G\xbc\xf7\x0c?\xe1\xc4\xda\xf4\xb7X\xecK\xff(\x06\xd6\x8f\xea\xe37\x0e\xfdV\x0e|\x0e\xea\xdb(\x857aC\x17\xc9Ds\x01*p3\0\xcfo\xa1\x18\xaf\xdf^-\xa0h\x86*\xe9\xfaw|\xbfN\xb72+^e\r\x08\x1d\x88\xf4\xfan#\x88\xe3\xeb\x15-\x11\x17\x9c9t\x9a\x1cM\x82\xdd27\xb7\xb0\x88r\xf8[\x19MN\xac\x98\x83-\xb5E\x1cO\x11\x01\xebl$\x9c\x9d\xd9\x85\xae\xcc\x1c\x1d\xf5M\xe8Y.\x1978\xe5\xc1\x02\xd3:\"i\xa0\nR*c%C\xa3\x95~\x07k\xea\xb4S#Z\x1c\xc3\xea\xb8\xff\0\xf1q\x81!\x88\xba\x0c\x96<\xb8\xd8\x0f\xd6\xaf\x05\xf3\xc9C\x03\x91\xc0-dt\xe3\x91@.H\xb8\xaf\x9f\x8d\xf8\x9c\xb7\x18]\xa9LP\x85\xbc\xb1\x03K\x92\xc2\x93\xa9Q\xf6\xe8\xc2\x92\xda\xdd\x87E[8s\0\xdf\x9a\xd1\xb3\x99d\x8bn\x04A\x97\xff\x0e\xaa\xea\xb9\\\xb2\xcd\x11B\x0c\xdfSUp\x15\x05\xa4\xa8I"), Certificate(b"0\x82\x03\xef0\x82\x02\xd7\xa0\x03\x02\x01\x02\x02\t\0\x89\xba-\xabJ\xe4\xf3b0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x05\x05\00\x81\x8d1\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x160\x14\x06\x03U\x04\x07\x0c\rSan Francisco1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1\x130\x11\x06\x03U\x04\x03\x0c\nTextSecure0\x1e\x17\r130325221835Z\x17\r230323221835Z0\x81\x8d1\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x160\x14\x06\x03U\x04\x07\x0c\rSan Francisco1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1\x130\x11\x06\x03U\x04\x03\x0c\nTextSecure0\x82\x01\"0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x01\x0f\00\x82\x01\n\x02\x82\x01\x01\0\xc1I`i8 C\x17H\xb8\xabgx\x8c\x05\xe4Iu\x06\xa5\xb7\x96\xba\x05O@(\xda/\xaa\x83\xf5\0\xce\xc5\xe5\xb5i\xfb\xdf>\x8f:\xde.\x82\xae\xadJ\x0f\x11\xe9\x07~\x84\xc35\\\x8f\x02\xe4\x152\x0cz\x8e\xed\xf6\xcdEB\x89@\xb7y\x84\xad]\x94\xd2\xf8\x9e\xe9W\xac\xfb(\xfa\x82\xb4\x9f\xc7\x02\x03\x01\xc7l*b4kT\x90\t\x99k\xdd\rmpp$\0\xa1Q\xc5\x83Z\xc0\0\xf2\xa6\xceA\xf9\x99\xaar\x07\xfa\xe7t\xd7K\x9f\xf4 \xf3\xd0.\x87\x0b\x9f\xc1\x96/\x03\x986v\x1f\x06\xf2\x0b(3\x08\xb6fS\xb4F\xeeq\xd8\xa0\xc4@=\x99\xf2/\xbcp\x03\x11\xbb^.\xd5`\xc0\x19\xbdG\x9b\xd5\xbe\xdc+\x0f\xa0\x82\xc4\x8d\xe9\x80~\xc4\x1f\xb9\x0c?C\x9d\xf7|\x0b\x8a\xcb\xe7\xd7(Gc\xb1nbZ\xe4b\xa7d2\xf5\x94\xac\xaa\0I\\8\x8d\xbe\xbd\xbd\xa7\x89\tGT\xf8p\xa8\\\xe4\x8f\xd4\xf4\x1c/\xa5\x9e\x9c\xd0\x87\x02\x03\x01\0\x01\xa3P0N0\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14\x01\x8b\x18\xf1?\xfb9\x19Dn\x85\x86\xbe\x94e2\xa72<\x900\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14\x01\x8b\x18\xf1?\xfb9\x19Dn\x85\x86\xbe\x94e2\xa72<\x900\x0c\x06\x03U\x1d\x13\x04\x050\x03\x01\x01\xff0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x05\x05\0\x03\x82\x01\x01\0~\x1e\xbe!\x0b\x9e\xa6\xd0\xbb\xc9\x02\xedQ+\xa3n<6\xd30C{\xfe3(\x8cQ\xf1\x1f\x83\xf7\\\xf1_\xba\x98\xd1\xf9\xb9\x03\xabJO9N\x89\x8c\x8cg\xac=W\x0f\xf1\x15\x994\xdaMV9\x88\xe8^\xca\xfe\xd4\xcc\x93\x9e}\xbd\xa9,\xe7=\xaa\xcb\xaf#\x87\xe7hD\xa1\xf5\xb8s\x96\x7f\x8b_\x1bB\xad\x13\xc6\x86L@\xd4\x83\x08\xa3r\x7f\xb3e\x85\xfc\xa5Xg'\"\xbb\xa6\xf8\xb9\xb2}\xd5s\xc0\xb8\x13Y\xc0\x89\xae\x83\xbf\xec\xadk\xdb\xa6M!\xab\xa0:\xcf^4Y5R*\xd2F\xecC\xf8\xd6B^\xfe\x81x\xba\xc69'\xb2\xcf\x93\x12\xb7-l\xcc&v\xf9\x10\x82C\x99\x1b\xf2\x1f\xcarG\x05\xa0\xe9\t\xba\xaa!\x85h\x8e'/\xc4\x14\xe0\xb6\xb5\xc7g\xa7\xe01\x97E\xa2\xcc=\x1c#\x10\xb3`@\xfa\x1dG\x7f3\xa0#.\x03\xc8\x07e\xf6Xb\x86{\x0f\xb45\xc0\xd5\"Tw1\xb8Z\xc4x\x16\x98%\xb2\xc4\xaf\x02\xd5")]
[2021-05-03T12:40:20Z DEBUG rustls::client::tls12] Server DNS name is DNSName("textsecure-service.whispersystems.org")
[2021-05-03T12:40:20Z DEBUG rustls::client::tls12] Session saved
[2021-05-03T12:40:20Z TRACE actix_tls::connect::ssl::rustls] SSL Handshake success: "textsecure-service.whispersystems.org"
[2021-05-03T12:40:20Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:40:20Z TRACE actix_codec::framed] writing; remaining=355
[2021-05-03T12:40:20Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:40:20Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:40:20Z TRACE actix_codec::framed] writing; remaining=469
[2021-05-03T12:40:20Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:40:20Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:40:20Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:40:21Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:21Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:21Z DEBUG libsignal_service_actix::push_service] AwcPushService::put response:
ClientResponse HTTP/1.1 409 Conflict
headers:
"connection": "keep-alive"
"content-type": "application/json"
"date": "Mon, 03 May 2021 12:40:21 GMT"
"x-signal-timestamp": "1620045621063"
"content-length": "40"
[2021-05-03T12:40:21Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:21Z TRACE actix_http::h1::decoder] Length read: 40
[2021-05-03T12:40:21Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:21Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:21Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:21Z DEBUG libsignal_service::sender] MismatchedDevices { missing_devices: [2], extra_devices: [] }
[2021-05-03T12:40:21Z DEBUG libsignal_service::sender] creating session with missing device 2
[2021-05-03T12:40:21Z DEBUG libsignal_service_actix::push_service] HTTP request GET https://textsecure-service.whispersystems.org/v2/keys/+491111111111/2
[2021-05-03T12:40:21Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:40:21Z TRACE actix_codec::framed] writing; remaining=319
[2021-05-03T12:40:21Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:40:21Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:21Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:21Z DEBUG libsignal_service_actix::push_service] AwcPushService::get response:
ClientResponse HTTP/1.1 200 OK
headers:
"connection": "keep-alive"
"x-signal-timestamp": "1620045621189"
"vary": "Accept-Encoding"
"date": "Mon, 03 May 2021 12:40:21 GMT"
"content-type": "application/json"
"content-length": "383"
[2021-05-03T12:40:21Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:21Z TRACE actix_http::h1::decoder] Length read: 383
[2021-05-03T12:40:21Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:21Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:21Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:21Z DEBUG libsignal_service_actix::push_service] GET response: "{\"identityKey\":\"\",\"devices\":[{\"deviceId\":x,\"registrationId\":,\"signedPreKey\":{\"keyId\":xx,\"publicKey\":\"\",\"signature\":\"\"},\"preKey\":{\"keyId\":xx,\"publicKey\":\"\"}}]}"
[2021-05-03T12:40:21Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_491111111111"
[2021-05-03T12:40:21Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_491111111111", 96 bytes
[2021-05-03T12:40:21Z ERROR libsignal_service::sender] failed to create session: internal error: Untrusted identity
[2021-05-03T12:40:21Z ERROR harbour_whisperfish::worker::client] Error sending message: Untrusted identity key with +491111111111
[2021-05-03T12:40:21Z TRACE harbour_whisperfish::model::session] STUB: Mark sent called
[2021-05-03T12:40:21Z TRACE harbour_whisperfish::model::message] Mark message 32058 sent 'true'
[2021-05-03T12:40:21Z TRACE mio::poll] deregistering event source from poller
[2021-05-03T12:40:36Z TRACE libsignal_service_actix::websocket] Triggering keep-alive
[2021-05-03T12:40:36Z TRACE libsignal_service::messagepipe] Sending request WebSocketRequestMessage { verb: Some("GET"), path: Some("/v1/keepalive"), body: None, headers: [], id: Some(1620045636795) }
[2021-05-03T12:40:36Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:40:36Z TRACE actix_codec::framed] writing; remaining=37
[2021-05-03T12:40:36Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:40:36Z TRACE libsignal_service::messagepipe] request on route.
[2021-05-03T12:40:36Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:36Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:40:36Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:40:36Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Response), request: None, response: Some(WebSocketResponseMessage { id: Some(1620045636795), status: Some(200), message: Some("OK"), headers: ["Content-Length:0"], body: None }) }
[2021-05-03T12:41:22Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:22Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:22Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:22Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 1, 18, 13, 43, 52, 57, 49, 55, 51, 57, 51, 50, 50, 57, 57, 56, 40, 203, 161, 253, 145, 147, 47, 56, 3, 66, 179, 5, 51, 10, 33, 5, 27, 93, 86, 194, 200, 46, 2, 248, 118, 82, 217, 13, 102, 124, 130, 114, 225, 100, 89, 180, 179, 147, 58, 96, 203, 239, 65, 26, 117, 165, 159, 32, 16, 9, 24, 10, 34, 128, 5, 69, 26, 146, 241, 66, 200, 191, 145, 4, 57, 24, 253, 121, 111, 152, 179, 61, 73, 186, 122, 52, 109, 174, 148, 149, 111, 160, 228, 244, 35, 242, 197, 194, 215, 128, 198, 179, 60, 89, 252, 56, 171, 159, 104, 35, 239, 44, 46, 174, 251, 236, 115, 134, 74, 220, 101, 189, 69, 85, 32, 94, 55, 254, 14, 243, 91, 58, 192, 251, 38, 236, 22, 164, 169, 34, 231, 156, 53, 200, 163, 63, 74, 40, 39, 139, 102, 33, 33, 227, 150, 171, 52, 139, 184, 68, 166, 3, 177, 249, 114, 17, 3, 166, 66, 84, 37, 10, 169, 6, 97, 87, 209, 117, 151, 6, 234, 75, 132, 33, 51, 82, 117, 121, 28, 121, 169, 45, 125, 233, 239, 74, 92, 17, 186, 39, 241, 206, 141, 160, 160, 123, 41, 239, 244, 208, 234, 23, 127, 78, 13, 2, 125, 81, 46, 67, 91, 98, 233, 131, 44, 7, 229, 155, 65, 145, 180, 85, 148, 133, 116, 81, 121, 38, 178, 25, 36, 49, 222, 172, 156, 241, 151, 255, 162, 3, 63, 49, 90, 25, 211, 105, 247, 46, 140, 178, 35, 201, 159, 175, 202, 244, 72, 45, 226, 153, 114, 192, 182, 57, 213, 174, 169, 89, 230, 140, 99, 162, 182, 52, 225, 62, 123, 230, 141, 39, 17, 200, 54, 252, 83, 132, 193, 117, 124, 186, 149, 18, 207, 26, 201, 118, 147, 251, 44, 24, 14, 160, 42, 33, 184, 215, 32, 57, 226, 159, 35, 188, 255, 82, 237, 97, 73, 84, 50, 25, 164, 219, 48, 38, 53, 92, 164, 249, 128, 216, 60, 129, 201, 83, 35, 70, 207, 65, 109, 117, 25, 248, 93, 138, 91, 153, 4, 73, 236, 186, 50, 226, 165, 174, 201, 209, 9, 82, 174, 110, 57, 225, 21, 16, 70, 14, 71, 15, 208, 84, 255, 69, 176, 208, 47, 89, 118, 162, 199, 35, 24, 68, 48, 54, 187, 161, 213, 161, 91, 111, 149, 98, 178, 116, 204, 231, 86, 159, 79, 227, 84, 125, 45, 255, 164, 64, 47, 245, 70, 132, 246, 85, 31, 205, 106, 197, 111, 120, 10, 125, 100, 10, 72, 228, 88, 98, 172, 93, 14, 118, 137, 90, 64, 231, 225, 76, 161, 104, 12, 180, 43, 144, 64, 197, 228, 122, 225, 171, 252, 90, 48, 77, 210, 23, 190, 214, 66, 61, 184, 177, 14, 172, 206, 15, 0, 225, 172, 115, 14, 246, 18, 65, 151, 182, 116, 172, 69, 19, 88, 146, 244, 35, 62, 36, 129, 28, 88, 76, 225, 207, 67, 238, 205, 224, 86, 206, 75, 111, 148, 85, 112, 170, 209, 204, 249, 14, 129, 91, 251, 182, 148, 171, 133, 215, 34, 66, 0, 135, 60, 206, 80, 116, 5, 62, 254, 212, 110, 175, 0, 54, 221, 245, 201, 57, 107, 135, 31, 69, 238, 178, 129, 126, 99, 119, 111, 210, 121, 163, 230, 103, 79, 197, 158, 68, 158, 159, 135, 249, 223, 8, 50, 218, 59, 189, 140, 176, 66, 108, 111, 33, 74, 218, 220, 212, 230, 196, 205, 79, 55, 190, 77, 52, 131, 167, 106, 92, 162, 74, 59, 192, 131, 46, 25, 164, 196, 109, 199, 86, 141, 69, 6, 64, 60, 226, 59, 55, 233, 52, 26, 4, 43, 193, 105, 134, 167, 5, 152, 247, 125, 137, 163, 180, 167, 219, 220, 171, 7, 216, 17, 205, 188, 130, 138, 86, 81, 195, 10, 234, 31, 7, 79, 124, 58, 192, 126, 121, 65, 243, 235, 197, 139, 161, 7, 135, 249, 210, 210, 23, 26, 128, 243, 213, 206, 33, 148, 111, 185, 31, 104, 113, 215, 204, 222, 27, 226, 227, 249, 79, 81, 192, 75, 99, 150, 251, 72, 93, 115, 200, 100, 61, 116, 77, 227, 196, 122, 188, 155, 212, 178, 81, 174, 16, 221, 80, 162, 172, 253, 145, 147, 47, 90, 36, 100, 97, 100, 101, 56, 50, 99, 56, 45, 54, 50, 52, 101, 45, 52, 97, 49, 57, 45, 98, 100, 99, 50, 45, 56, 53, 100, 98, 52, 57, 49, 53, 52, 51, 49, 48]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045682211"], id: Some(5858480132553154030) }), response: None }
[2021-05-03T12:41:22Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:22Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:22Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:22Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::worker::client] Opened envelope Content { body: SynchronizeMessage(SyncMessage { sent: Some(Sent { destination_e164: Some("+491111111111"), destination_uuid: Some(".."), timestamp: Some(1620045680843), message: Some(DataMessage { body: Some("Test, Test, 3, 4 ..."), attachments: [], group: None, group_v2: None, flags: None, expire_timer: None, profile_key: None, timestamp: Some(1620045680843), quote: None, contact: [], preview: [], sticker: None, required_protocol_version: None, is_view_once: None, reaction: None, delete: None, body_ranges: [] }), expiration_start_timestamp: Some(1620045682087), unidentified_status: [UnidentifiedDeliveryStatus { destination_e164: Some("+491111111111"), destination_uuid: Some(".."), unidentified: Some(false) }], is_recipient_update: None }), contacts: None, groups: None, request: None, read: [], blocked: None, verified: None, configuration: None, padding: Some([107, 63, 114, 33, 71, 63, 193, 71, 118, 230, 103, 196, 133, 84, 74, 213, 56, 10, 180, 139, 167, 66, 34, 86, 156, 25, 159, 206, 26, 139, 80, 188, 42, 180, 249, 218, 43, 82, 238, 54, 61, 133, 255, 98, 133, 44, 89, 91, 122, 112, 201, 238, 164, 236, 141, 77, 234, 65, 167, 100, 226, 117, 192, 160, 184, 127, 31, 222, 218, 160, 70, 177, 66, 84, 223, 126, 65, 92, 248, 242, 180, 193, 154, 61, 13, 133, 111, 88, 157, 45, 22, 197, 32, 49, 30, 75, 57, 234, 28, 43, 12, 243, 142, 76, 128, 243, 241, 200, 181, 33, 248, 168, 235, 236, 28, 47, 155, 139, 18, 71, 197, 177, 23, 227, 25, 254, 91, 226, 187, 237, 66, 94, 151, 73, 130, 170, 190, 72, 56, 193, 37, 213, 175, 185, 100, 32, 110, 126, 164, 76, 17, 98, 183, 84, 7, 238, 64, 174, 214, 121, 129, 175, 128, 249, 182, 232, 6, 197, 252, 135, 204, 48, 236, 243, 135, 58, 224, 180, 57, 19, 51, 0, 113, 115, 23, 205, 186, 107, 87, 35, 81, 20, 121, 90, 106, 112, 2, 73, 204, 211, 209, 115, 234, 230, 155, 129, 143, 28, 250, 234, 27, 29, 66, 9, 145, 29, 119, 232, 7, 106, 14, 44, 220, 254, 117, 113, 7, 199, 113, 6, 224, 104, 247, 86, 62, 233, 241, 193, 247, 215, 234, 75, 90, 145, 137, 172, 136, 139, 76, 2, 24, 78, 161, 173, 238, 20, 137, 162, 106, 189, 168, 101, 18, 106, 241, 71, 214, 164, 198, 109, 201, 105, 86, 248, 68, 169, 159, 206, 40, 115, 139, 119, 253, 86, 61, 60, 75, 48, 51, 144, 164, 11, 198, 230, 104, 26, 35, 100, 21, 237, 91, 71, 254, 67, 123, 245, 13, 29, 29, 58, 212, 75, 227, 16, 18, 138, 68, 9, 50]), sticker_pack_operation: [], view_once_open: None, fetch_latest: None, keys: None, message_request_response: None }), metadata: Metadata { sender: ServiceAddress { uuid: Some(..), phonenumber: Some(PhoneNumber { code: Code { value: 49, source: Plus }, national: NationalNumber { value: 2222222222, zeros: 0 }, extension: None, carrier: None }), relay: None }, sender_device: 3, timestamp: 1620045680843, needs_receipt: false } }
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::worker::client] Sync sent message
[2021-05-03T12:41:22Z INFO harbour_whisperfish::store] Found UUID .. for contact +491111111111. High trust, so updating.
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Called fetch_recipient_by_id(33)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Called fetch_or_insert_session_by_recipient_id(33)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Called fetch__session_by_recipient_id(33)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Called create_message(..) for session 27
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Inserted message id 32059
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::model::session] Dispatched actor::FetchSession(27)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::model::message] Dispatched actor::FetchMessage(32059)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::model::session] Inserting the message back in qml
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Called fetch_message_by_id(32059)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::store] Called fetch_recipient_by_id(33)
[2021-05-03T12:41:22Z TRACE harbour_whisperfish::model::message] handle_fetch_message(32059)
[2021-05-03T12:41:22Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:22Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:22Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:22Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:22Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:22Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 5, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 203, 161, 253, 145, 147, 47, 56, 2, 80, 208, 172, 253, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045682381"], id: Some(5159707009043306827) }), response: None }
[2021-05-03T12:41:22Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:22Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:22Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:22Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:22Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 5, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 203, 161, 253, 145, 147, 47, 56, 1, 80, 147, 173, 253, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045682381"], id: Some(4594536830195184212) }), response: None }
[2021-05-03T12:41:22Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:22Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:22Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:22Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:22Z INFO harbour_whisperfish::worker::client] Empty envelope
[2021-05-03T12:41:22Z INFO harbour_whisperfish::worker::client] Empty envelope
[2021-05-03T12:41:31Z TRACE libsignal_service_actix::websocket] Triggering keep-alive
[2021-05-03T12:41:31Z TRACE libsignal_service::messagepipe] Sending request WebSocketRequestMessage { verb: Some("GET"), path: Some("/v1/keepalive"), body: None, headers: [], id: Some(1620045691795) }
[2021-05-03T12:41:31Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:31Z TRACE actix_codec::framed] writing; remaining=37
[2021-05-03T12:41:31Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:31Z TRACE libsignal_service::messagepipe] request on route.
[2021-05-03T12:41:31Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:31Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:31Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:31Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 3, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 217, 248, 253, 145, 147, 47, 56, 2, 66, 170, 2, 51, 40, 181, 84, 8, 248, 176, 241, 7, 48, 128, 128, 128, 8, 18, 33, 5, 168, 85, 2, 203, 59, 210, 114, 13, 232, 81, 239, 171, 79, 157, 211, 47, 102, 176, 188, 26, 40, 166, 83, 211, 46, 163, 0, 81, 237, 247, 189, 72, 26, 33, 5, 78, 244, 99, 25, 131, 216, 152, 125, 204, 213, 122, 166, 11, 120, 44, 84, 173, 99, 75, 160, 78, 85, 217, 203, 39, 196, 77, 137, 20, 140, 187, 53, 34, 211, 1, 51, 10, 33, 5, 51, 138, 39, 123, 125, 131, 211, 49, 159, 81, 223, 177, 86, 139, 199, 69, 181, 228, 80, 140, 199, 216, 54, 135, 121, 74, 162, 140, 210, 71, 220, 88, 16, 0, 24, 0, 34, 160, 1, 115, 7, 137, 6, 13, 62, 32, 38, 246, 124, 150, 160, 174, 35, 217, 140, 63, 6, 150, 215, 51, 59, 43, 20, 102, 105, 231, 142, 46, 58, 101, 124, 75, 154, 243, 200, 188, 153, 112, 199, 89, 177, 214, 97, 112, 19, 83, 144, 135, 71, 108, 18, 70, 62, 105, 247, 202, 64, 214, 34, 170, 191, 137, 139, 45, 51, 238, 129, 103, 27, 254, 57, 242, 55, 66, 117, 84, 15, 55, 180, 175, 234, 117, 100, 210, 241, 152, 250, 24, 123, 25, 231, 241, 251, 165, 219, 241, 27, 57, 230, 81, 220, 120, 33, 115, 151, 16, 204, 210, 220, 111, 207, 132, 215, 242, 13, 39, 23, 175, 204, 211, 35, 134, 142, 202, 217, 89, 172, 228, 166, 92, 172, 221, 154, 113, 210, 254, 46, 59, 235, 29, 129, 52, 50, 111, 5, 187, 166, 34, 139, 39, 155, 223, 161, 169, 214, 254, 138, 167, 99, 179, 112, 17, 139, 198, 245, 31, 178, 80, 130, 246, 253, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045691662"], id: Some(8752470630284760334) }), response: None }
[2021-05-03T12:41:31Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:31Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:31Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:31Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] Loading signed prekey 16777216
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/signed_prekeys/016777216"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/signed_prekeys/016777216", 208 bytes
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] identity_key_pair
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] Loading prekey 16537720
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/prekeys/016537720"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/prekeys/016537720", 128 bytes
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_.."
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "..", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] Removing prekey 16537720
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::worker::client] Opened envelope Content { body: ReceiptMessage(ReceiptMessage { r#type: Some(Read), timestamp: [1620045680843] }), metadata: Metadata { sender: ServiceAddress { uuid: Some(..), phonenumber: Some(PhoneNumber { code: Code { value: 49, source: Plus }, national: NationalNumber { value: 1111111111, zeros: 0 }, extension: None, carrier: None }), relay: None }, sender_device: 2, timestamp: 1620045691993, needs_receipt: false } }
[2021-05-03T12:41:31Z INFO harbour_whisperfish::worker::client] ServiceAddress(+491111111111) received a message.
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::store] Called fetch_message_by_id(32059)
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::model::message] Mark message 32059 received 'true'
[2021-05-03T12:41:31Z TRACE harbour_whisperfish::model::session] STUB: Mark received called
[2021-05-03T12:41:31Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:31Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:31Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:31Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Response), request: None, response: Some(WebSocketResponseMessage { id: Some(1620045691795), status: Some(200), message: Some("OK"), headers: ["Content-Length:0"], body: None }) }
[2021-05-03T12:41:34Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:34Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:34Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:34Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 3, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 214, 148, 254, 145, 147, 47, 56, 2, 66, 170, 2, 51, 40, 181, 84, 8, 248, 176, 241, 7, 48, 128, 128, 128, 8, 18, 33, 5, 168, 85, 2, 203, 59, 210, 114, 13, 232, 81, 239, 171, 79, 157, 211, 47, 102, 176, 188, 26, 40, 166, 83, 211, 46, 163, 0, 81, 237, 247, 189, 72, 26, 33, 5, 78, 244, 99, 25, 131, 216, 152, 125, 204, 213, 122, 166, 11, 120, 44, 84, 173, 99, 75, 160, 78, 85, 217, 203, 39, 196, 77, 137, 20, 140, 187, 53, 34, 211, 1, 51, 10, 33, 5, 51, 138, 39, 123, 125, 131, 211, 49, 159, 81, 223, 177, 86, 139, 199, 69, 181, 228, 80, 140, 199, 216, 54, 135, 121, 74, 162, 140, 210, 71, 220, 88, 16, 1, 24, 0, 34, 160, 1, 132, 126, 139, 191, 60, 46, 231, 101, 201, 194, 188, 32, 82, 243, 251, 68, 215, 109, 169, 238, 79, 76, 223, 205, 43, 105, 63, 169, 78, 38, 113, 116, 170, 168, 165, 232, 45, 138, 81, 3, 188, 139, 216, 209, 6, 230, 76, 165, 182, 135, 211, 175, 20, 77, 245, 132, 221, 123, 96, 252, 245, 157, 220, 134, 38, 246, 95, 251, 196, 249, 79, 166, 7, 99, 183, 30, 228, 150, 10, 188, 222, 19, 22, 19, 206, 188, 81, 147, 129, 93, 88, 19, 252, 162, 200, 0, 130, 88, 101, 242, 157, 203, 18, 56, 7, 117, 82, 133, 158, 212, 79, 205, 202, 105, 102, 173, 0, 98, 237, 102, 198, 235, 95, 25, 144, 197, 52, 48, 189, 147, 254, 72, 122, 14, 236, 35, 178, 131, 162, 141, 119, 150, 117, 228, 18, 7, 115, 118, 154, 244, 12, 55, 196, 8, 1, 192, 50, 6, 254, 146, 130, 43, 64, 8, 247, 163, 219, 17, 80, 157, 140, 254, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045694495"], id: Some(3895776810291071541) }), response: None }
[2021-05-03T12:41:34Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:34Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:34Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:34Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_.."
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_..", 96 bytes
[2021-05-03T12:41:34Z INFO libsignal_protocol::context] We've already setup a session for this V3 message, letting bundled message fall through...
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_.."
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "..", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:34Z TRACE harbour_whisperfish::worker::client] Opened envelope Content { body: TypingMessage(TypingMessage { timestamp: Some(1620045695574), action: Some(Started), group_id: None }), metadata: Metadata { sender: ServiceAddress { uuid: Some(..), phonenumber: Some(PhoneNumber { code: Code { value: 49, source: Plus }, national: NationalNumber { value: 1111111111, zeros: 0 }, extension: None, carrier: None }), relay: None }, sender_device: 2, timestamp: 1620045695574, needs_receipt: false } }
[2021-05-03T12:41:34Z INFO harbour_whisperfish::worker::client] ServiceAddress(+491111111111) is typing.
[2021-05-03T12:41:36Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:36Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:36Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:36Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 3, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 183, 164, 254, 145, 147, 47, 56, 2, 66, 170, 2, 51, 40, 181, 84, 8, 248, 176, 241, 7, 48, 128, 128, 128, 8, 18, 33, 5, 168, 85, 2, 203, 59, 210, 114, 13, 232, 81, 239, 171, 79, 157, 211, 47, 102, 176, 188, 26, 40, 166, 83, 211, 46, 163, 0, 81, 237, 247, 189, 72, 26, 33, 5, 78, 244, 99, 25, 131, 216, 152, 125, 204, 213, 122, 166, 11, 120, 44, 84, 173, 99, 75, 160, 78, 85, 217, 203, 39, 196, 77, 137, 20, 140, 187, 53, 34, 211, 1, 51, 10, 33, 5, 51, 138, 39, 123, 125, 131, 211, 49, 159, 81, 223, 177, 86, 139, 199, 69, 181, 228, 80, 140, 199, 216, 54, 135, 121, 74, 162, 140, 210, 71, 220, 88, 16, 2, 24, 0, 34, 160, 1, 205, 240, 130, 104, 115, 19, 77, 19, 174, 252, 250, 167, 251, 81, 99, 238, 189, 229, 204, 42, 121, 129, 94, 159, 202, 220, 39, 141, 120, 226, 84, 66, 200, 126, 7, 159, 82, 65, 32, 145, 52, 11, 41, 143, 207, 149, 45, 67, 228, 214, 179, 92, 255, 84, 135, 218, 84, 70, 180, 195, 111, 213, 35, 71, 215, 159, 77, 247, 8, 251, 184, 116, 140, 164, 157, 9, 20, 238, 85, 236, 142, 58, 1, 232, 155, 169, 90, 174, 156, 30, 102, 221, 5, 103, 178, 192, 17, 196, 234, 211, 87, 98, 107, 80, 121, 68, 251, 105, 74, 202, 231, 159, 55, 174, 112, 153, 215, 235, 31, 198, 116, 150, 203, 237, 175, 138, 128, 47, 32, 35, 124, 100, 91, 178, 15, 104, 255, 217, 83, 26, 59, 55, 158, 107, 94, 138, 42, 240, 25, 50, 230, 103, 165, 125, 1, 44, 100, 191, 112, 75, 169, 13, 132, 41, 54, 227, 112, 179, 80, 199, 156, 254, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045696577"], id: Some(4240408603249270) }), response: None }
[2021-05-03T12:41:36Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:36Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:36Z TRACE actix_codec::framed] writing; remaining=26
[2021-05-03T12:41:36Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_.."
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_..", 96 bytes
[2021-05-03T12:41:36Z INFO libsignal_protocol::context] We've already setup a session for this V3 message, letting bundled message fall through...
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/identity/remote_.."
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "..", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::worker::client] Opened envelope Content { body: DataMessage(DataMessage { body: Some("yes yes!"), attachments: [], group: None, group_v2: None, flags: None, expire_timer: None, profile_key: Some([..]), timestamp: Some(1620045697591), quote: None, contact: [], preview: [], sticker: None, required_protocol_version: None, is_view_once: None, reaction: None, delete: None, body_ranges: [] }), metadata: Metadata { sender: ServiceAddress { uuid: Some(..), phonenumber: Some(PhoneNumber { code: Code { value: 49, source: Plus }, national: NationalNumber { value: 1111111111, zeros: 0 }, extension: None, carrier: None }), relay: None }, sender_device: 2, timestamp: 1620045697591, needs_receipt: false } }
[2021-05-03T12:41:36Z INFO harbour_whisperfish::store] Updated profile key for +491111111111
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called fetch_recipient_by_id(33)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called fetch_or_insert_session_by_recipient_id(33)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called fetch__session_by_recipient_id(33)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called create_message(..) for session 27
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Inserted message id 32060
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::model::session] Dispatched actor::FetchSession(27)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::model::message] Dispatched actor::FetchMessage(32060)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::model::session] Dispatched actor::MarkSessionRead(27, true)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::model::session] Inserting the message back in qml
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called mark_session_read(27)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called fetch_message_by_id(32060)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::store] Called fetch_recipient_by_id(33)
[2021-05-03T12:41:36Z TRACE harbour_whisperfish::model::message] handle_fetch_message(32060)
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::actor::messageactor] MessageActor::handle(QueueMessage { e164: "+491111111111", message: "Hm", attachment: "" })
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Called fetch_or_insert_session_by_recipient_id(33)
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Called fetch__session_by_recipient_id(33)
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Called create_message(..) for session 27
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Inserted message id 32061
[2021-05-03T12:41:41Z INFO harbour_whisperfish::worker::client] ClientActor::SendMessage(32061)
[2021-05-03T12:41:41Z DEBUG rustls::anchors] add_pem_file processed 1 valid and 0 invalid certs
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Called fetch_message_by_id(32061)
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::worker::client] Sending for session: Session { id: 27, is_archived: false, is_pinned: false, is_silent: false, is_muted: false, expiring_message_timeout: None, draft: None, type: DirectMessage(Recipient { id: 33, e164: Some("+491111111111"), uuid: Some(..), username: None, email: None, blocked: false, profile_key: Some([..]), profile_key_credential: None, profile_given_name: None, profile_family_name: None, profile_joined_name: None, signal_profile_avatar: None, profile_sharing: false, last_profile_fetch: None, unidentified_access_mode: false, storage_service_id: None, storage_proto: None, capabilities: 0, last_gv1_migrate_reminder: None, last_session_reset: None }) }
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::worker::client] Sending message: Message { id: 32061, session_id: 27, text: Some("Hm"), sender_recipient_id: Some(33), received_timestamp: None, sent_timestamp: None, server_timestamp: 2021-05-03T12:41:41.646, is_read: true, is_outbound: true, flags: 0, expires_in: None, expiry_started: None, schedule_send_time: None, is_bookmarked: false, use_unidentified: false, is_remote_deleted: false }
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::worker::client] Transmitting DataMessage { body: Some("Hm"), attachments: [], group: None, group_v2: None, flags: None, expire_timer: None, profile_key: Some([..]), timestamp: Some(1620045701646), quote: None, contact: [], preview: [], sticker: None, required_protocol_version: Some(0), is_view_once: None, reaction: None, delete: None, body_ranges: [] }
[2021-05-03T12:41:41Z TRACE libsignal_service::sender] sending message to default device
[2021-05-03T12:41:41Z TRACE libsignal_service::sender] encrypting message for Address { name: "+491111111111", device_id: 1 }
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "+491111111111", device_id: 1 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1", 688 bytes
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "+491111111111", device_id: 1 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1", 688 bytes
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "+491111111111", device_id: 1 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "+491111111111", device_id: 1 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/491111111111_1", 688 bytes
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Looking for sub_device sessions for ..
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Looking for sub_device sessions for +491111111111
[2021-05-03T12:41:41Z TRACE libsignal_service::sender] sending message to device 2
[2021-05-03T12:41:41Z TRACE libsignal_service::sender] encrypting message for Address { name: "..", device_id: 2 }
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "..", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:41Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:41Z DEBUG libsignal_service_actix::push_service] HTTP request PUT https://textsecure-service.whispersystems.org/v1/messages/..
[2021-05-03T12:41:41Z TRACE actix_tls::connect::resolve] DNS resolver: resolving host "textsecure-service.whispersystems.org"
[2021-05-03T12:41:42Z TRACE actix_tls::connect::resolve] DNS resolver: host "textsecure-service.whispersystems.org" resolved to [76.223.92.165:443, 13.248.212.111:443]
[2021-05-03T12:41:42Z TRACE actix_tls::connect::connector] TCP connector: connecting to textsecure-service.whispersystems.org on port 443
[2021-05-03T12:41:42Z TRACE mio::poll] registering event source with poller: token=Token(33554435), interests=READABLE | WRITABLE
[2021-05-03T12:41:42Z TRACE actix_tls::connect::connector] TCP connector: successfully connected to "textsecure-service.whispersystems.org" - Ok(76.223.92.165:443)
[2021-05-03T12:41:42Z TRACE actix_tls::connect::ssl::rustls] SSL Handshake start for: "textsecure-service.whispersystems.org"
[2021-05-03T12:41:42Z DEBUG rustls::client::hs] No cached session for DNSNameRef("textsecure-service.whispersystems.org")
[2021-05-03T12:41:42Z DEBUG rustls::client::hs] Not resuming any session
[2021-05-03T12:41:42Z TRACE rustls::client::hs] Sending ClientHello Message {
typ: Handshake,
version: TLSv1_0,
payload: Handshake(
HandshakeMessagePayload {
typ: ClientHello,
payload: ClientHello(
ClientHelloPayload {
client_version: TLSv1_2,
random: Random(
[
30,
50,
158,
254,
26,
165,
174,
29,
111,
34,
132,
230,
150,
56,
201,
116,
48,
8,
48,
103,
42,
193,
238,
29,
229,
205,
22,
141,
189,
234,
142,
140,
],
),
session_id: SessionID(
112,
202,
4,
24,
98,
199,
179,
23,
201,
62,
69,
159,
175,
128,
65,
1,
201,
52,
82,
62,
82,
180,
26,
54,
155,
28,
16,
141,
220,
130,
241,
112,
),
cipher_suites: [
TLS13_CHACHA20_POLY1305_SHA256,
TLS13_AES_256_GCM_SHA384,
TLS13_AES_128_GCM_SHA256,
TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305_SHA256,
TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305_SHA256,
TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384,
TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256,
TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384,
TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256,
TLS_EMPTY_RENEGOTIATION_INFO_SCSV,
],
compression_methods: [
Null,
],
extensions: [
SupportedVersions(
[
TLSv1_3,
TLSv1_2,
],
),
ServerName(
[
ServerName {
typ: HostName,
payload: HostName(
DNSName(
"textsecure-service.whispersystems.org",
),
),
},
],
),
ECPointFormats(
[
Uncompressed,
],
),
NamedGroups(
[
X25519,
secp384r1,
secp256r1,
],
),
SignatureAlgorithms(
[
ECDSA_NISTP384_SHA384,
ECDSA_NISTP256_SHA256,
ED25519,
RSA_PSS_SHA512,
RSA_PSS_SHA384,
RSA_PSS_SHA256,
RSA_PKCS1_SHA512,
RSA_PKCS1_SHA384,
RSA_PKCS1_SHA256,
],
),
ExtendedMasterSecretRequest,
CertificateStatusRequest(
OCSP(
OCSPCertificateStatusRequest {
responder_ids: [],
extensions: PayloadU16(
[],
),
},
),
),
KeyShare(
[
KeyShareEntry {
group: X25519,
payload: PayloadU16(
[
228,
245,
71,
31,
107,
96,
108,
53,
212,
143,
33,
148,
235,
152,
99,
27,
10,
174,
133,
227,
87,
197,
93,
253,
143,
39,
43,
185,
114,
223,
186,
26,
],
),
},
],
),
PresharedKeyModes(
[
PSK_DHE_KE,
],
),
Protocols(
[
PayloadU8(
[
104,
116,
116,
112,
47,
49,
46,
49,
],
),
],
),
SessionTicketRequest,
],
},
),
},
),
}
[2021-05-03T12:41:42Z TRACE rustls::client::hs] We got ServerHello ServerHelloPayload {
legacy_version: TLSv1_2,
random: Random(
[
56,
164,
33,
69,
214,
253,
187,
254,
90,
45,
52,
47,
184,
162,
238,
118,
97,
25,
102,
194,
112,
19,
39,
182,
152,
78,
168,
119,
24,
164,
211,
112,
],
),
session_id: SessionID,
cipher_suite: TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256,
compression_method: Null,
extensions: [
RenegotiationInfo(
PayloadU8(
[],
),
),
ECPointFormats(
[
Uncompressed,
ANSIX962CompressedPrime,
ANSIX962CompressedChar2,
],
),
SessionTicketAck,
Protocols(
[
PayloadU8(
[
104,
116,
116,
112,
47,
49,
46,
49,
],
),
],
),
],
}
[2021-05-03T12:41:42Z DEBUG rustls::client::hs] ALPN protocol is Some(b"http/1.1")
[2021-05-03T12:41:42Z DEBUG rustls::client::hs] Using ciphersuite TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256
[2021-05-03T12:41:42Z DEBUG rustls::client::hs] Server supports tickets
[2021-05-03T12:41:42Z DEBUG rustls::client::tls12] ECDHE curve is ECParameters { curve_type: NamedCurve, named_group: secp256r1 }
[2021-05-03T12:41:42Z TRACE rustls::client::tls12] Server cert is [Certificate(b"0\x82\x03\xe30\x82\x02\xcb\xa0\x03\x02\x01\x02\x02\x02\x10\x180\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\00\x81\x8d1\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x160\x14\x06\x03U\x04\x07\x0c\rSan Francisco1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1\x130\x11\x06\x03U\x04\x03\x0c\nTextSecure0\x1e\x17\r190215173817Z\x17\r290312182020Z0\x81\x901\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1.0,\x06\x03U\x04\x03\x0c%textsecure-service.whispersystems.org0\x82\x01\"0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x01\x0f\00\x82\x01\n\x02\x82\x01\x01\0\xac\xc8\x11\xb5\xd1E\xb7\xc0\xa2\xcb\xcf\x93\x89\x9a\x80u\xb3\xb0xp;;\xbb:~\xc4\x03\x8cq\xa9X\x87l\xde(\xc7\x88\xe5\xe4\xb8}\x9a\xee\xa7Um\x1e\x06\xfc\\\xf74\n<m\"\x8e\x04\t7\xf4\x95\xe7x\xc5\xc2:\xaa\xb6\xbe\ta;\x07\xcc\xab:n\x01\xe5`~\xac[u\xc7\xc5\xe3\xe3\xe4\x91JI%\xd6c\xb9J4\x04\x95\x97\x92\xa4JhZ\x89\x82-\x10\xf11\xbd*\x0f\x07\xd6\xcd\xc2\xeab\xc5\x9e@{H\x88\x13\xf3X@\x0e\xb2ezPM\x8d\x1d\x05M%\xd6\xad)A\xe5\xf3-z\xa0%\xebS\x874\x97\xaf\xa8\xf9\xb2\x83N\xd8\xac\x91\xa0\xc0Ml\x8e6|\xf5\x18\xbddB9G\xf2P\xd26}\xbd\xea\xb2\xe6C\xf9\x04;`\xace\xe6\x18\x03\x05O(4#M\xf3K\rZ8a\xe6qD9a\xa3\xe6\\e\xd6\xa6\x13\x92\x9d\xecX\x92F`>\x9c\xb9\0\xc9\xd4:\xd2(\xd6\xc3Qi\xec\x94\x87\xbe\xc2\xf6_O\x1f~\x92m\x02\x03\x01\0\x01\xa3H0F0D\x06\x03U\x1d\x11\x04=0;\x82%textsecure-service.whispersystems.org\x82\x12service.signal.org0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x0b\x05\0\x03\x82\x01\x01\0)k.G\xbc\xf7\x0c?\xe1\xc4\xda\xf4\xb7X\xecK\xff(\x06\xd6\x8f\xea\xe37\x0e\xfdV\x0e|\x0e\xea\xdb(\x857aC\x17\xc9Ds\x01*p3\0\xcfo\xa1\x18\xaf\xdf^-\xa0h\x86*\xe9\xfaw|\xbfN\xb72+^e\r\x08\x1d\x88\xf4\xfan#\x88\xe3\xeb\x15-\x11\x17\x9c9t\x9a\x1cM\x82\xdd27\xb7\xb0\x88r\xf8[\x19MN\xac\x98\x83-\xb5E\x1cO\x11\x01\xebl$\x9c\x9d\xd9\x85\xae\xcc\x1c\x1d\xf5M\xe8Y.\x1978\xe5\xc1\x02\xd3:\"i\xa0\nR*c%C\xa3\x95~\x07k\xea\xb4S#Z\x1c\xc3\xea\xb8\xff\0\xf1q\x81!\x88\xba\x0c\x96<\xb8\xd8\x0f\xd6\xaf\x05\xf3\xc9C\x03\x91\xc0-dt\xe3\x91@.H\xb8\xaf\x9f\x8d\xf8\x9c\xb7\x18]\xa9LP\x85\xbc\xb1\x03K\x92\xc2\x93\xa9Q\xf6\xe8\xc2\x92\xda\xdd\x87E[8s\0\xdf\x9a\xd1\xb3\x99d\x8bn\x04A\x97\xff\x0e\xaa\xea\xb9\\\xb2\xcd\x11B\x0c\xdfSUp\x15\x05\xa4\xa8I"), Certificate(b"0\x82\x03\xef0\x82\x02\xd7\xa0\x03\x02\x01\x02\x02\t\0\x89\xba-\xabJ\xe4\xf3b0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x05\x05\00\x81\x8d1\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x160\x14\x06\x03U\x04\x07\x0c\rSan Francisco1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1\x130\x11\x06\x03U\x04\x03\x0c\nTextSecure0\x1e\x17\r130325221835Z\x17\r230323221835Z0\x81\x8d1\x0b0\t\x06\x03U\x04\x06\x13\x02US1\x130\x11\x06\x03U\x04\x08\x0c\nCalifornia1\x160\x14\x06\x03U\x04\x07\x0c\rSan Francisco1\x1d0\x1b\x06\x03U\x04\n\x0c\x14Open Whisper Systems1\x1d0\x1b\x06\x03U\x04\x0b\x0c\x14Open Whisper Systems1\x130\x11\x06\x03U\x04\x03\x0c\nTextSecure0\x82\x01\"0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x01\x05\0\x03\x82\x01\x0f\00\x82\x01\n\x02\x82\x01\x01\0\xc1I`i8 C\x17H\xb8\xabgx\x8c\x05\xe4Iu\x06\xa5\xb7\x96\xba\x05O@(\xda/\xaa\x83\xf5\0\xce\xc5\xe5\xb5i\xfb\xdf>\x8f:\xde.\x82\xae\xadJ\x0f\x11\xe9\x07~\x84\xc35\\\x8f\x02\xe4\x152\x0cz\x8e\xed\xf6\xcdEB\x89@\xb7y\x84\xad]\x94\xd2\xf8\x9e\xe9W\xac\xfb(\xfa\x82\xb4\x9f\xc7\x02\x03\x01\xc7l*b4kT\x90\t\x99k\xdd\rmpp$\0\xa1Q\xc5\x83Z\xc0\0\xf2\xa6\xceA\xf9\x99\xaar\x07\xfa\xe7t\xd7K\x9f\xf4 \xf3\xd0.\x87\x0b\x9f\xc1\x96/\x03\x986v\x1f\x06\xf2\x0b(3\x08\xb6fS\xb4F\xeeq\xd8\xa0\xc4@=\x99\xf2/\xbcp\x03\x11\xbb^.\xd5`\xc0\x19\xbdG\x9b\xd5\xbe\xdc+\x0f\xa0\x82\xc4\x8d\xe9\x80~\xc4\x1f\xb9\x0c?C\x9d\xf7|\x0b\x8a\xcb\xe7\xd7(Gc\xb1nbZ\xe4b\xa7d2\xf5\x94\xac\xaa\0I\\8\x8d\xbe\xbd\xbd\xa7\x89\tGT\xf8p\xa8\\\xe4\x8f\xd4\xf4\x1c/\xa5\x9e\x9c\xd0\x87\x02\x03\x01\0\x01\xa3P0N0\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14\x01\x8b\x18\xf1?\xfb9\x19Dn\x85\x86\xbe\x94e2\xa72<\x900\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14\x01\x8b\x18\xf1?\xfb9\x19Dn\x85\x86\xbe\x94e2\xa72<\x900\x0c\x06\x03U\x1d\x13\x04\x050\x03\x01\x01\xff0\r\x06\t*\x86H\x86\xf7\r\x01\x01\x05\x05\0\x03\x82\x01\x01\0~\x1e\xbe!\x0b\x9e\xa6\xd0\xbb\xc9\x02\xedQ+\xa3n<6\xd30C{\xfe3(\x8cQ\xf1\x1f\x83\xf7\\\xf1_\xba\x98\xd1\xf9\xb9\x03\xabJO9N\x89\x8c\x8cg\xac=W\x0f\xf1\x15\x994\xdaMV9\x88\xe8^\xca\xfe\xd4\xcc\x93\x9e}\xbd\xa9,\xe7=\xaa\xcb\xaf#\x87\xe7hD\xa1\xf5\xb8s\x96\x7f\x8b_\x1bB\xad\x13\xc6\x86L@\xd4\x83\x08\xa3r\x7f\xb3e\x85\xfc\xa5Xg'\"\xbb\xa6\xf8\xb9\xb2}\xd5s\xc0\xb8\x13Y\xc0\x89\xae\x83\xbf\xec\xadk\xdb\xa6M!\xab\xa0:\xcf^4Y5R*\xd2F\xecC\xf8\xd6B^\xfe\x81x\xba\xc69'\xb2\xcf\x93\x12\xb7-l\xcc&v\xf9\x10\x82C\x99\x1b\xf2\x1f\xcarG\x05\xa0\xe9\t\xba\xaa!\x85h\x8e'/\xc4\x14\xe0\xb6\xb5\xc7g\xa7\xe01\x97E\xa2\xcc=\x1c#\x10\xb3`@\xfa\x1dG\x7f3\xa0#.\x03\xc8\x07e\xf6Xb\x86{\x0f\xb45\xc0\xd5\"Tw1\xb8Z\xc4x\x16\x98%\xb2\xc4\xaf\x02\xd5")]
[2021-05-03T12:41:42Z DEBUG rustls::client::tls12] Server DNS name is DNSName("textsecure-service.whispersystems.org")
[2021-05-03T12:41:42Z DEBUG rustls::client::tls12] Session saved
[2021-05-03T12:41:42Z TRACE actix_tls::connect::ssl::rustls] SSL Handshake success: "textsecure-service.whispersystems.org"
[2021-05-03T12:41:42Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:42Z TRACE actix_codec::framed] writing; remaining=378
[2021-05-03T12:41:42Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:42Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:42Z TRACE actix_codec::framed] writing; remaining=878
[2021-05-03T12:41:42Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:42Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:42Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:42Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:42Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:42Z DEBUG libsignal_service_actix::push_service] AwcPushService::put response:
ClientResponse HTTP/1.1 200 OK
headers:
"connection": "keep-alive"
"x-signal-timestamp": "1620045702695"
"date": "Mon, 03 May 2021 12:41:42 GMT"
"content-length": "18"
"content-type": "application/json"
[2021-05-03T12:41:42Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:42Z TRACE actix_http::h1::decoder] Length read: 18
[2021-05-03T12:41:42Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:42Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:42Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:42Z DEBUG libsignal_service_actix::push_service] PUT response: "{\"needsSync\":true}"
[2021-05-03T12:41:42Z DEBUG libsignal_service::sender] message sent!
[2021-05-03T12:41:42Z DEBUG libsignal_service::sender] sending multi-device sync message
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Looking for sub_device sessions for dade82c8-624e-4a19-bdc2-85db49154310
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Looking for sub_device sessions for +492222222222
[2021-05-03T12:41:42Z TRACE libsignal_service::sender] sending message to device 3
[2021-05-03T12:41:42Z TRACE libsignal_service::sender] encrypting message for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 }
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:41:42Z TRACE libsignal_service::sender] sending message to device 2
[2021-05-03T12:41:42Z TRACE libsignal_service::sender] encrypting message for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 }
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2", 688 bytes
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2", 688 bytes
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2", 688 bytes
[2021-05-03T12:41:42Z TRACE libsignal_service::sender] sending message to device 2
[2021-05-03T12:41:42Z TRACE libsignal_service::sender] encrypting message for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 }
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2", 688 bytes
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2", 688 bytes
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2"
[2021-05-03T12:41:42Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_2", 688 bytes
[2021-05-03T12:41:42Z DEBUG libsignal_service_actix::push_service] HTTP request PUT https://textsecure-service.whispersystems.org/v1/messages/dade82c8-624e-4a19-bdc2-85db49154310
[2021-05-03T12:41:42Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:42Z TRACE actix_codec::framed] writing; remaining=379
[2021-05-03T12:41:42Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:42Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:42Z TRACE actix_codec::framed] writing; remaining=1266
[2021-05-03T12:41:42Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:42Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:42Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 5, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 142, 196, 254, 145, 147, 47, 56, 2, 80, 147, 206, 254, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045702936"], id: Some(7948251820950480283) }), response: None }
[2021-05-03T12:41:43Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:43Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:43Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:43Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:43Z INFO harbour_whisperfish::worker::client] Empty envelope
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:43Z DEBUG libsignal_service_actix::push_service] AwcPushService::put response:
ClientResponse HTTP/1.1 200 OK
headers:
"x-signal-timestamp": "1620045702980"
"date": "Mon, 03 May 2021 12:41:42 GMT"
"content-type": "application/json"
"content-length": "19"
"connection": "keep-alive"
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE actix_http::h1::decoder] Length read: 19
[2021-05-03T12:41:43Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:43Z DEBUG libsignal_service_actix::push_service] PUT response: "{\"needsSync\":false}"
[2021-05-03T12:41:43Z DEBUG libsignal_service::sender] message sent!
[2021-05-03T12:41:43Z TRACE harbour_whisperfish::model::session] STUB: Mark sent called
[2021-05-03T12:41:43Z TRACE harbour_whisperfish::model::message] Mark message 32061 sent 'true'
[2021-05-03T12:41:43Z TRACE mio::poll] deregistering event source from poller
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:43Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:43Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 5, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 142, 196, 254, 145, 147, 47, 56, 1, 80, 185, 209, 254, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045703355"], id: Some(4419971331217266430) }), response: None }
[2021-05-03T12:41:43Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:43Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:43Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:43Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:43Z INFO harbour_whisperfish::worker::client] Empty envelope
[2021-05-03T12:41:44Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:44Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:41:44Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:41:44Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 1, 18, 13, 43, 52, 57, 49, 55, 52, 52, 48, 53, 48, 50, 53, 50, 40, 226, 224, 254, 145, 147, 47, 56, 2, 66, 211, 1, 51, 10, 33, 5, 234, 49, 94, 146, 110, 10, 119, 251, 102, 235, 223, 159, 161, 130, 192, 135, 40, 162, 212, 140, 73, 221, 54, 221, 176, 227, 22, 228, 117, 226, 229, 27, 16, 0, 24, 2, 34, 160, 1, 5, 250, 196, 112, 66, 139, 202, 136, 112, 131, 155, 23, 148, 45, 177, 155, 241, 195, 15, 69, 175, 62, 194, 127, 107, 193, 38, 190, 101, 136, 112, 24, 19, 127, 23, 99, 93, 107, 179, 3, 81, 10, 218, 119, 203, 112, 56, 123, 13, 174, 180, 14, 234, 7, 163, 174, 67, 161, 193, 22, 74, 33, 50, 143, 220, 36, 241, 144, 162, 173, 91, 252, 161, 5, 184, 24, 6, 45, 89, 78, 179, 2, 180, 179, 102, 76, 5, 97, 229, 191, 42, 24, 71, 117, 87, 17, 252, 148, 23, 65, 171, 62, 240, 2, 99, 128, 179, 22, 198, 211, 53, 159, 127, 118, 235, 150, 32, 230, 251, 136, 66, 56, 189, 154, 192, 79, 132, 32, 43, 103, 42, 72, 238, 172, 44, 221, 174, 65, 167, 139, 116, 10, 71, 151, 166, 232, 4, 125, 14, 57, 48, 186, 147, 71, 191, 252, 34, 15, 43, 59, 46, 20, 58, 103, 28, 138, 42, 147, 80, 156, 216, 254, 145, 147, 47, 90, 36, 98, 52, 56, 50, 55, 56, 100, 51, 45, 100, 55, 49, 101, 45, 52, 100, 51, 55, 45, 97, 97, 100, 49, 45, 53, 52, 55, 55, 98, 100, 51, 98, 48, 98, 97, 98]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045704221"], id: Some(87099958277878155) }), response: None }
[2021-05-03T12:41:44Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:41:44Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:41:44Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:41:44Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 384 bytes
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "..", device_id: 2 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "..", device_id: 2 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2"
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/.._2", 464 bytes
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::worker::client] Opened envelope Content { body: ReceiptMessage(ReceiptMessage { r#type: Some(Read), timestamp: [1620045701646] }), metadata: Metadata { sender: ServiceAddress { uuid: Some(..), phonenumber: Some(PhoneNumber { code: Code { value: 49, source: Plus }, national: NationalNumber { value: 1111111111, zeros: 0 }, extension: None, carrier: None }), relay: None }, sender_device: 2, timestamp: 1620045705314, needs_receipt: false } }
[2021-05-03T12:41:44Z INFO harbour_whisperfish::worker::client] ServiceAddress(+491111111111) received a message.
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::store] Called fetch_message_by_id(32061)
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::model::message] Mark message 32061 received 'true'
[2021-05-03T12:41:44Z TRACE harbour_whisperfish::model::session] STUB: Mark received called
[2021-05-03T12:42:13Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:42:13Z TRACE actix_codec::framed] frame decoded from buffer
[2021-05-03T12:42:13Z TRACE actix_codec::framed] attempting to decode a frame
[2021-05-03T12:42:13Z TRACE libsignal_service::messagepipe] Decoded WebSocketMessage { r#type: Some(Request), request: Some(WebSocketRequestMessage { verb: Some("PUT"), path: Some("/api/v1/message"), body: Some([8, 1, 18, 13, 43, 52, 57, 49, 55, 51, 57, 51, 50, 50, 57, 57, 56, 40, 220, 187, 128, 146, 147, 47, 56, 3, 66, 179, 5, 51, 10, 33, 5, 120, 65, 56, 236, 213, 176, 44, 158, 213, 7, 48, 28, 238, 109, 194, 69, 167, 62, 60, 175, 107, 172, 27, 153, 152, 214, 255, 39, 141, 229, 61, 114, 16, 0, 24, 9, 34, 128, 5, 115, 59, 134, 181, 30, 198, 26, 230, 182, 124, 185, 230, 218, 55, 242, 29, 108, 30, 64, 172, 190, 129, 174, 20, 118, 153, 246, 142, 224, 9, 32, 141, 202, 87, 92, 153, 106, 223, 16, 39, 175, 127, 138, 109, 140, 68, 205, 88, 198, 29, 224, 57, 203, 90, 69, 159, 181, 28, 74, 54, 92, 41, 142, 217, 146, 99, 187, 212, 204, 212, 3, 43, 178, 191, 230, 152, 221, 155, 13, 218, 152, 223, 90, 21, 225, 196, 171, 74, 92, 38, 19, 191, 158, 165, 72, 131, 5, 250, 7, 206, 5, 34, 185, 49, 162, 28, 255, 219, 227, 122, 23, 226, 105, 33, 75, 143, 57, 177, 115, 146, 211, 163, 182, 88, 38, 26, 168, 136, 97, 63, 167, 190, 40, 229, 12, 133, 80, 98, 180, 176, 189, 171, 144, 12, 227, 215, 56, 253, 135, 46, 47, 227, 238, 104, 255, 114, 118, 58, 233, 49, 243, 127, 85, 182, 152, 74, 68, 120, 196, 211, 138, 206, 13, 139, 214, 99, 2, 218, 7, 130, 10, 120, 120, 168, 83, 151, 80, 75, 95, 9, 227, 201, 9, 126, 28, 238, 189, 89, 164, 141, 87, 32, 137, 8, 176, 178, 179, 9, 118, 173, 210, 35, 48, 17, 227, 139, 25, 143, 42, 220, 165, 209, 194, 239, 48, 116, 181, 120, 176, 127, 107, 103, 254, 130, 166, 150, 101, 184, 25, 128, 169, 107, 61, 39, 45, 164, 173, 242, 89, 26, 93, 16, 168, 88, 28, 198, 238, 229, 231, 188, 176, 38, 204, 206, 83, 241, 77, 115, 231, 177, 4, 77, 225, 157, 247, 156, 81, 43, 83, 117, 213, 190, 147, 92, 151, 173, 42, 18, 96, 85, 33, 198, 81, 169, 97, 216, 96, 166, 100, 129, 119, 127, 169, 124, 74, 5, 82, 192, 79, 73, 152, 25, 250, 54, 249, 166, 49, 253, 82, 54, 13, 239, 131, 199, 66, 178, 249, 202, 100, 232, 11, 200, 104, 169, 50, 44, 221, 138, 2, 87, 1, 158, 133, 152, 254, 168, 230, 225, 81, 72, 95, 249, 7, 205, 79, 116, 214, 48, 185, 34, 19, 176, 87, 0, 5, 51, 73, 65, 11, 79, 136, 243, 87, 189, 88, 249, 165, 68, 10, 65, 240, 78, 250, 35, 215, 191, 28, 249, 70, 78, 46, 7, 141, 74, 177, 60, 9, 125, 105, 76, 223, 112, 117, 138, 227, 19, 82, 72, 189, 190, 28, 243, 76, 93, 52, 87, 225, 177, 74, 180, 192, 103, 228, 19, 40, 72, 177, 3, 165, 89, 221, 178, 200, 113, 216, 230, 158, 36, 19, 18, 91, 16, 18, 136, 169, 169, 8, 86, 202, 242, 185, 167, 248, 135, 231, 189, 98, 54, 14, 83, 231, 80, 174, 182, 248, 233, 74, 143, 75, 81, 83, 171, 76, 100, 141, 185, 160, 236, 63, 76, 54, 16, 220, 181, 2, 31, 0, 93, 186, 169, 0, 44, 199, 236, 201, 235, 165, 195, 173, 211, 123, 125, 148, 152, 93, 226, 153, 117, 13, 220, 54, 188, 184, 207, 201, 242, 99, 39, 159, 155, 80, 218, 82, 78, 228, 225, 188, 170, 51, 98, 113, 20, 59, 168, 78, 43, 119, 185, 253, 217, 35, 63, 141, 89, 192, 218, 0, 225, 218, 127, 254, 124, 180, 134, 144, 91, 6, 18, 247, 239, 205, 112, 48, 206, 83, 116, 12, 159, 243, 230, 219, 114, 139, 116, 209, 7, 15, 32, 169, 84, 104, 121, 176, 199, 19, 202, 233, 8, 32, 143, 115, 25, 53, 206, 112, 219, 122, 199, 229, 14, 146, 197, 204, 93, 245, 196, 188, 52, 156, 22, 168, 46, 63, 192, 240, 103, 226, 189, 158, 58, 187, 125, 189, 222, 225, 129, 34, 126, 175, 29, 211, 42, 216, 57, 129, 50, 31, 92, 55, 100, 170, 104, 34, 47, 60, 165, 28, 42, 80, 202, 188, 128, 146, 147, 47, 90, 36, 100, 97, 100, 101, 56, 50, 99, 56, 45, 54, 50, 52, 101, 45, 52, 97, 49, 57, 45, 98, 100, 99, 50, 45, 56, 53, 100, 98, 52, 57, 49, 53, 52, 51, 49, 48]), headers: ["X-Signal-Key: false", "X-Signal-Timestamp:1620045733452"], id: Some(4174440470935409447) }), response: None }
[2021-05-03T12:42:13Z TRACE libsignal_service::envelope] Envelope::decrypt: not encrypted
[2021-05-03T12:42:13Z TRACE actix_codec::framed] flushing framed transport
[2021-05-03T12:42:13Z TRACE actix_codec::framed] writing; remaining=27
[2021-05-03T12:42:13Z TRACE actix_codec::framed] framed transport flushed
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store::protocol_store] Storing session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } at "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store] Writing encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store::protocol_store] Loading session for Address { name: "dade82c8-624e-4a19-bdc2-85db49154310", device_id: 3 } from "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store] Opening encrypted file "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3"
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::store] Read "/home/nemo/.local/share/harbour-whisperfish/storage/sessions/dade82c8-624e-4a19-bdc2-85db49154310_3", 688 bytes
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::worker::client] Opened envelope Content { body: SynchronizeMessage(SyncMessage { sent: None, contacts: None, groups: None, request: None, read: [Read { sender_e164: Some("+491111111111"), sender_uuid: Some(".."), timestamp: Some(1620045697591) }], blocked: None, verified: None, configuration: None, padding: Some([15, 227, 45, 245, 171, 8, 185, 53, 19, 155, 67, 16, 175, 27, 6, 200, 125, 248, 243, 184, 127, 153, 161, 206, 223, 2, 24, 189, 254, 73, 198, 198, 21, 210, 39, 25, 169, 24, 99, 147, 149, 197, 68, 236, 226, 178, 247, 174, 6, 102, 70, 73, 77, 80, 236, 117, 218, 141, 144, 227, 200, 50, 158, 66, 129, 11, 75, 172, 104, 78, 140, 232, 150, 128, 167, 179, 176, 78, 219, 172, 202, 238, 27, 169, 70, 102, 11, 251, 40, 129, 0, 245, 34, 253, 41, 202, 75, 252, 210, 143, 244, 94, 247, 67, 120, 26, 237, 52, 9, 249, 156, 42, 91, 164, 99, 72, 201, 78, 163, 9, 54, 83, 100, 244, 156, 215, 236, 94, 24, 5, 184, 61, 144, 137, 8, 252, 107, 133, 223, 58, 81, 221, 20, 148, 177, 0, 162, 146, 107, 33, 7, 78, 252, 211, 101, 192, 78, 104, 194, 42, 136, 180, 220, 46, 109, 126, 114, 254, 116, 56, 90, 205, 103, 174, 209, 26, 225, 212, 205, 221, 149, 111, 67, 178, 243, 237, 203, 124, 94, 99, 228, 19, 228, 177, 78, 52, 97, 46, 31, 249, 80, 99, 6, 225, 231, 58, 155, 239, 210, 158, 85, 6, 37, 216, 219, 173, 234, 79, 255, 101, 30, 43, 172, 29, 125, 113, 54, 230, 90, 181, 11, 115, 33, 196, 76, 71, 20, 156, 221, 57, 117, 64, 21, 186, 83, 0, 96, 100, 115, 55, 155, 150, 113, 215, 76, 188, 163, 225, 25, 175, 107, 25, 57, 34, 15, 80, 68, 122, 46, 42, 175, 253, 91, 52, 41, 40, 53, 201, 114, 42, 166, 80, 247, 106, 128, 227, 234, 239, 25, 156, 154, 50, 189, 45, 205, 255, 152, 49, 192, 244, 179, 151, 156, 137, 203, 198, 31, 170, 83, 220, 75, 130, 226, 226, 167, 113, 112, 157, 96, 122, 230, 253, 205, 58, 189, 83, 94, 73, 91, 204, 145, 240, 221, 223, 174, 126, 202, 83, 194, 215, 38, 66, 33, 49, 222, 250, 51, 151, 118, 246, 73, 26, 98, 247, 132, 32, 84, 255, 113, 230, 82, 205, 117, 67, 180, 138, 79, 40, 218, 4, 153, 175, 43, 130, 147, 157, 22, 64, 89, 22, 122, 140, 137, 48, 195, 188, 250, 137, 28, 223, 184, 37, 80, 212, 106, 3, 113, 255, 126, 94, 177, 8, 140, 116, 64, 226, 93, 201, 206, 131, 8, 180, 23, 181, 203, 145, 70, 151, 195, 60, 135, 76, 0]), sticker_pack_operation: [], view_once_open: None, fetch_latest: None, keys: None, message_request_response: None }), metadata: Metadata { sender: ServiceAddress { uuid: Some(dade82c8-624e-4a19-bdc2-85db49154310), phonenumber: Some(PhoneNumber { code: Code { value: 49, source: Plus }, national: NationalNumber { value: 2222222222, zeros: 0 }, extension: None, carrier: None }), relay: None }, sender_device: 3, timestamp: 1620045733340, needs_receipt: false } }
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::worker::client] Sync read message
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::worker::client] Marking message from +491111111111 at 2021-05-03 12:41:37.591 (1620045697591) as read.
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::model::message] Mark message 32060 received 'true'
[2021-05-03T12:42:13Z TRACE harbour_whisperfish::model::session] STUB: Mark received called
[2021-05-03T12:42:15Z ERROR harbour_whisperfish::sfos::tokio_qt] unregisterTimers
[2021-05-03T12:42:15Z INFO harbour_whisperfish] Shut down.
[2021-05-03T12:42:15Z TRACE mio::poll] deregistering event source from poller
Edited by Ruben De Smet