Skip to content
New issue

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

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

Unable to send messages due to not being able to retrieve private keys because rate limiting. #2180

Closed
dmzpkts opened this issue Mar 24, 2018 · 4 comments
Closed

Unable to send messages due to not being able to retrieve private keys because rate limiting. #2180

dmzpkts opened this issue Mar 24, 2018 · 4 comments
Labels
Feature Request

Comments

@dmzpkts
Copy link

dmzpkts commented Mar 24, 2018 •
edited by scottnonnenberg-signal
Loading

  • [X ] I have searched open and closed issues for duplicates

Bug description

Unable to send messages after opening a client that had not synched in a month

Steps to reproduce

  1. Open old client with old keys, sync 2000 messages
  2. Try to send a message

Actual result:
Every message including (Secure session Reset) Fails.

Expected result:
The message sends

Screenshots

Debug log:

INFO  2018-03-24T23:45:18.630Z data message from +[REDACTED]657.6 1521935108562
INFO  2018-03-24T23:45:18.701Z adding notification
INFO  2018-03-24T23:45:18.702Z Update notifications: isFocused: false isEnabled: true numNotifications: 1 shouldPlayNotificationSound: false
INFO  2018-03-24T23:45:18.702Z draw attention
INFO  2018-03-24T23:45:18.702Z remove all notifications
INFO  2018-03-24T23:46:00.115Z sync timed out
INFO  2018-03-24T23:46:00.115Z open inbox
INFO  2018-03-24T23:46:07.688Z fetchMessages
INFO  2018-03-24T23:46:07.690Z GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]657
INFO  2018-03-24T23:46:07.729Z Conversation +[REDACTED]657 took 40 milliseconds to load
INFO  2018-03-24T23:46:07.740Z Sending 2 read receipts
INFO  2018-03-24T23:46:07.907Z GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]657 200 Success
INFO  2018-03-24T23:46:08.297Z GET https://cdn.signal.org/profiles/ym3mGywafHFPit7NKeCr0g
INFO  2018-03-24T23:46:08.382Z GET https://cdn.signal.org/profiles/ym3mGywafHFPit7NKeCr0g 403 Error
INFO  2018-03-24T23:46:08.394Z done with status fetch
INFO  2018-03-24T23:46:08.459Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]665
INFO  2018-03-24T23:46:08.650Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]665 409 Error
INFO  2018-03-24T23:46:08.650Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4
INFO  2018-03-24T23:46:08.839Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4 413 Error
INFO  2018-03-24T23:46:08.839Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4 413 Error
ERROR 2018-03-24T23:46:08.840Z Top-level unhandled promise rejection: [object Object]
INFO  2018-03-24T23:46:13.401Z fetchMessages
INFO  2018-03-24T23:46:13.498Z Sending a keepalive message
INFO  2018-03-24T23:46:13.822Z Updating BrowserWindow config: {"maximized":true,"autoHideMenuBar":false,"width":4112,"height":2136,"x":-8,"y":-8}
INFO  2018-03-24T23:46:25.993Z Send pre-checks took 8 milliseconds
INFO  2018-03-24T23:46:26.000Z Sending message to conversation +[REDACTED]657 with timestamp 1521935186000
INFO  2018-03-24T23:46:26.146Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]657
INFO  2018-03-24T23:46:26.378Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]657 409 Error
INFO  2018-03-24T23:46:26.378Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/1
INFO  2018-03-24T23:46:26.575Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/1 200 Success
INFO  2018-03-24T23:46:26.760Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/4
INFO  2018-03-24T23:46:26.940Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/4 413 Error
INFO  2018-03-24T23:46:26.941Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/4 413 Error
INFO  2018-03-24T23:46:26.942Z Message.saveErrors: Failed to retrieve new device keys for number +[REDACTED]657 OutgoingMessageError: Rate limit exceeded, please try again later.
    at OutgoingMessageError.ReplayableError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:47:19)
    at new OutgoingMessageError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:88:25)
    at OutgoingMessage.registerError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:39544:21)
    at OutgoingMessage.<anonymous> (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:39749:30)
    at <anonymous>
    at process._tickCallback (internal/process/next_tick.js:188:7)
Original stack:
HTTPError: promise_ajax: invalid response; code: 413
    at HTTPError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37619:17)
    at file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37563:26
    at <anonymous>
    at process._tickCallback (internal/process/next_tick.js:188:7)
Original stack:
Error
    at ajax (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37611:25)
    at TextSecureServer.ajax (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37655:20)
    at TextSecureServer.getKeysForNumber (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37823:25)
    at OutgoingMessage.<anonymous> (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:39591:40)
    at <anonymous>
INFO  2018-03-24T23:46:57.219Z theme-setting changed to android-dark
INFO  2018-03-24T23:47:01.960Z SyncRequest created. Sending contact sync message...
INFO  2018-03-24T23:47:02.025Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]665
INFO  2018-03-24T23:47:02.241Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]665 409 Error
INFO  2018-03-24T23:47:02.242Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4
INFO  2018-03-24T23:47:02.428Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4 413 Error
INFO  2018-03-24T23:47:02.428Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4 413 Error
INFO  2018-03-24T23:47:02.429Z SyncRequest error: {"successfulNumbers":[],"errors":[{"name":"OutgoingMessageError","message":"Rate limit exceeded, please try again later.","functionCode":1,"args":["+[REDACTED]665",{},1521935221959],"code":413,"number":"+[REDACTED]665","reason":"Failed to retrieve new device keys for number +[REDACTED]665"}]}
INFO  2018-03-24T23:47:08.561Z Sending a keepalive message
INFO  2018-03-24T23:47:41.440Z resetting secure session
INFO  2018-03-24T23:47:41.516Z deleting sessions for +[REDACTED]657.1
INFO  2018-03-24T23:47:41.516Z deleting sessions for +[REDACTED]657.6
INFO  2018-03-24T23:47:41.609Z finished closing local sessions, now sending to contact
INFO  2018-03-24T23:47:41.612Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/1
INFO  2018-03-24T23:47:41.664Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]665
INFO  2018-03-24T23:47:41.894Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/1 200 Success
INFO  2018-03-24T23:47:42.011Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]665 409 Error
INFO  2018-03-24T23:47:42.011Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4
INFO  2018-03-24T23:47:42.079Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/6
INFO  2018-03-24T23:47:42.197Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4 413 Error
INFO  2018-03-24T23:47:42.197Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]665/4 413 Error
INFO  2018-03-24T23:47:42.198Z resetSession/sendSync error: {"successfulNumbers":[],"errors":[{"name":"OutgoingMessageError","message":"Rate limit exceeded, please try again later.","functionCode":1,"args":["+[REDACTED]665",{},1521935261441],"code":413,"number":"+[REDACTED]665","reason":"Failed to retrieve new device keys for number +[REDACTED]665"}]}
INFO  2018-03-24T23:47:42.199Z Message.saveErrors: Failed to retrieve new device keys for number +[REDACTED]665 OutgoingMessageError: Rate limit exceeded, please try again later.
    at OutgoingMessageError.ReplayableError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:47:19)
    at new OutgoingMessageError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:88:25)
    at OutgoingMessage.registerError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:39544:21)
    at OutgoingMessage.<anonymous> (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:39749:30)
    at <anonymous>
    at process._tickCallback (internal/process/next_tick.js:188:7)
Original stack:
HTTPError: promise_ajax: invalid response; code: 413
    at HTTPError (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37619:17)
    at file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37563:26
    at <anonymous>
    at process._tickCallback (internal/process/next_tick.js:188:7)
Original stack:
Error
    at ajax (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37611:25)
    at TextSecureServer.ajax (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37655:20)
    at TextSecureServer.getKeysForNumber (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:37823:25)
    at OutgoingMessage.<anonymous> (file:///C:/Users/coin/AppData/Local/Programs/signal-desktop/resources/app.asar/js/libtextsecure.js:39591:40)
    at <anonymous>
    at process._tickCallback (internal/process/next_tick.js:188:7)
INFO  2018-03-24T23:47:42.278Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/6 200 Success
INFO  2018-03-24T23:47:42.486Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]657
INFO  2018-03-24T23:47:42.674Z PUT https://textsecure-service.whispersystems.org/v1/messages/+[REDACTED]657 409 Error
INFO  2018-03-24T23:47:42.674Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/4
INFO  2018-03-24T23:47:42.856Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/4 413 Error
INFO  2018-03-24T23:47:42.856Z GET https://textsecure-service.whispersystems.org/v2/keys/+[REDACTED]657/4 413 Error
INFO  2018-03-24T23:47:42.857Z resetSession/sendToContact error: {"successfulNumbers":[],"errors":[{"name":"OutgoingMessageError","message":"Rate limit exceeded, please try again later.","functionCode":1,"args":["+[REDACTED]657",{},1521935261435],"code":413,"number":"+[REDACTED]657","reason":"Failed to retrieve new device keys for number +[REDACTED]657"}]}
INFO  2018-03-24T23:48:01.962Z sync timed out
INFO  2018-03-24T23:48:03.623Z Sending a keepalive message
INFO  2018-03-24T23:48:58.682Z Sending a keepalive message
INFO  2018-03-24T23:49:37.606Z Loaded this list of log files from logPath: log.log

Platform info

Android & Desktop on windows

Signal version:
4.16.9 on android 8.0
1.6.1 on Windows 10

Link to debug log

Android log: hastebin.com/uhoqoxivoq
Windows desktop log above.
@dmzpkts
Copy link
Author

dmzpkts commented Mar 25, 2018 •
edited by scottnonnenberg-signal
Loading

5-10 minutes later, I see that it retreieved the keys, and I can send messages again.

Update: from the desktop, phone is still wonky. The messages from the phone are being received, but on the phone they show as not sent.

Phone debug log:

03-24 18:05:05.477 20882 20915 W SignalServiceEnvelope: Thr MAC: 9a 1d f3 5e 73 ba 9c cf 5c 2d 
03-24 18:05:05.478 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: java.io.IOException: Non-successful response: 409
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessagePipe.send(SignalServiceMessagePipe.java:122)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:532)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: Falling back to new connection...
03-24 18:05:05.478 20882 20902 W SignalServiceMessageSender: Not transmitting over pipe...
03-24 18:05:05.479 20882 20902 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:05.479 20882 20902 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v1/messages/+*********65
03-24 18:05:05.480 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936297107)
03-24 18:05:05.485 20882 20902 D NativeCrypto: jniThrowException: java/net/SocketTimeoutException: Read timed out
03-24 18:05:05.518 20882 20915 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:05.540 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:05.541 20882 20915 W EarlyReceiptCache: Early receipt: (1521936297107, +*********57)
03-24 18:05:05.543 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:05.543 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:05.551 20882 21006 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:05.598 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:05.623 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:05.626 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:05.627 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:05.629 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:05.632 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:05.634 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: org.whispersystems.signalservice.internal.push.exceptions.MismatchedDevicesException
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:698)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.sendMessage(PushServiceSocket.java:184)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:540)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:05.955 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:05.955 20882 20902 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:05.955 20882 20902 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v2/keys/+*********65/4
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 
03-24 18:05:06.372 20882 20902 W PushTextSendJob: org.whispersystems.signalservice.api.push.exceptions.RateLimitException: Rate limit exceeded: 413
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:680)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.getPreKey(PushServiceSocket.java:286)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.handleMismatchedDevices(SignalServiceMessageSender.java:683)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:543)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:06.372 20882 20902 W PushTextSendJob: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:06.374 20882 20902 W JobConsumer: 
03-24 18:05:06.374 20882 20902 W JobConsumer: org.thoughtcrime.securesms.transport.RetryLaterException: org.whispersystems.signalservice.api.push.exceptions.RateLimitException: Rate limit exceeded: 413
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:117)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:06.374 20882 20902 W JobConsumer: Caused by: org.whispersystems.signalservice.api.push.exceptions.RateLimitException: Rate limit exceeded: 413
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:680)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.getPreKey(PushServiceSocket.java:286)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.handleMismatchedDevices(SignalServiceMessageSender.java:683)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:543)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:06.374 20882 20902 W JobConsumer: 	... 6 more
03-24 18:05:06.379 20882 20902 W PushTextSendJob: Sending message: 22040
03-24 18:05:06.380 20882 20902 W SignalServiceMessageSender: No attachments present...
03-24 18:05:06.412 20882 20902 W SignalServiceMessageSender: Transmitting over pipe...
03-24 18:05:07.407 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:07.407 20882 23240 W WebSocketConnection: Message Type: 2
03-24 18:05:07.408 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:07.408 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:07.408 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:07.410 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:07.412 20882 20915 W SignalServiceEnvelope: Our MAC: 9d 8f e2 bd 65 7a 60 48 e4 94 
03-24 18:05:07.413 20882 20915 W SignalServiceEnvelope: Thr MAC: 9d 8f e2 bd 65 7a 60 48 e4 94 
03-24 18:05:07.413 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:07.415 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936297107)
03-24 18:05:07.456 20882 20902 W SignalServiceMessageSender: Transmitting over pipe...
03-24 18:05:07.457 20882 20915 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:07.490 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:07.490 20882 20915 W EarlyReceiptCache: Early receipt: (1521936297107, +*********57)
03-24 18:05:07.493 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:07.495 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:07.496 20882 20915 W SignalServiceEnvelope: Our MAC: d5 59 1e 5a f9 1f df c5 7d c6 
03-24 18:05:07.496 20882 20915 W SignalServiceEnvelope: Thr MAC: d5 59 1e 5a f9 1f df c5 7d c6 
03-24 18:05:07.497 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:07.498 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936297107)
03-24 18:05:07.499 20882 21034 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:07.565 20882 20915 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:07.577 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:07.607 20882 20882 W ConversationAdapter: Bind time: 3
03-24 18:05:07.612 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:07.613 20882 20915 W EarlyReceiptCache: Early receipt: (1521936297107, +*********57)
03-24 18:05:07.616 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:07.616 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:07.617 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:07.620 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:07.626 20882 20882 I chatty  : uid=10105(org.thoughtcrime.securesms) identical 1 line
03-24 18:05:07.632 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:07.635 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:07.643 20882 20935 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:07.693 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:07.714 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:07.718 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:07.728 20882 20882 I chatty  : uid=10105(org.thoughtcrime.securesms) identical 3 lines
03-24 18:05:07.731 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:07.909 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:07.909 20882 23240 W WebSocketConnection: Message Type: 2
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: java.io.IOException: Non-successful response: 409
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessagePipe.send(SignalServiceMessagePipe.java:122)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:532)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: Falling back to new connection...
03-24 18:05:07.910 20882 20902 W SignalServiceMessageSender: Not transmitting over pipe...
03-24 18:05:07.911 20882 20902 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:07.911 20882 20902 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v1/messages/+*********65
03-24 18:05:07.914 20882 20902 D NativeCrypto: jniThrowException: java/net/SocketTimeoutException: Read timed out
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: org.whispersystems.signalservice.internal.push.exceptions.MismatchedDevicesException
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:698)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.sendMessage(PushServiceSocket.java:184)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:540)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:08.876 20882 20902 W SignalServiceMessageSender: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:08.877 20882 20902 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:08.877 20882 20902 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v2/keys/+*********65/4
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 
03-24 18:05:09.266 20882 20902 W PushTextSendJob: org.whispersystems.signalservice.api.push.exceptions.RateLimitException: Rate limit exceeded: 413
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:680)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.getPreKey(PushServiceSocket.java:286)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.handleMismatchedDevices(SignalServiceMessageSender.java:683)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:543)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:09.266 20882 20902 W PushTextSendJob: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:09.268 20882 20902 W JobConsumer: 
03-24 18:05:09.268 20882 20902 W JobConsumer: org.thoughtcrime.securesms.transport.RetryLaterException: org.whispersystems.signalservice.api.push.exceptions.RateLimitException: Rate limit exceeded: 413
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:117)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onPushSend(PushTextSendJob.java:56)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushSendJob.onSend(PushSendJob.java:62)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.SendJob.onRun(SendJob.java:40)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.MasterSecretJob.onRun(MasterSecretJob.java:18)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.jobqueue.JobConsumer.runJob(JobConsumer.java:76)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:46)
03-24 18:05:09.268 20882 20902 W JobConsumer: Caused by: org.whispersystems.signalservice.api.push.exceptions.RateLimitException: Rate limit exceeded: 413
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.makeServiceRequest(PushServiceSocket.java:680)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.internal.push.PushServiceSocket.getPreKey(PushServiceSocket.java:286)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.handleMismatchedDevices(SignalServiceMessageSender.java:683)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:543)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.whispersystems.signalservice.api.SignalServiceMessageSender.sendMessage(SignalServiceMessageSender.java:170)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.deliver(PushTextSendJob.java:111)
03-24 18:05:09.268 20882 20902 W JobConsumer: 	... 6 more
03-24 18:05:09.270 20882 20902 W MessageDatabase: Updating ID: 22040 to base type: 24
03-24 18:05:09.301 20882 20902 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:09.333 20882 20902 I MediaPlayer: constructor
03-24 18:05:09.339 20882 20957 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:09.388 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:09.389 20882 20902 D Ringtone: Successfully created local player
03-24 18:05:09.392 20882 20882 E MediaPlayerNative: invoke failed: wrong state 1, mPlayer(0x0)
03-24 18:05:09.392 20882 20902 V MediaPlayer: resetDrmState:  mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
03-24 18:05:09.392 20882 20902 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
03-24 18:05:09.393 20882 20902 V MediaPlayer: resetDrmState:  mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
03-24 18:05:09.393 20882 20902 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
03-24 18:05:09.398 20882 20902 I MediaPlayer: constructor
03-24 18:05:09.408 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:09.414 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:09.416 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:09.420 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:09.423 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:09.427 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:09.433 20882 20882 W MediaPlayer: mediaplayer went away with unhandled events
03-24 18:05:09.443 20882 23245 W WebSocketConnection: Sending keep alive...
03-24 18:05:09.453 20882 20902 D Ringtone: Successfully created local player
03-24 18:05:09.454 20882 20902 I Ringtone: play java.lang.Throwable
03-24 18:05:09.454 20882 20902 I Ringtone: 	at android.media.Ringtone.play(Ringtone.java:360)
03-24 18:05:09.454 20882 20902 I Ringtone: 	at org.thoughtcrime.securesms.notifications.MessageNotifier.sendInThreadNotification(MessageNotifier.java:402)
03-24 18:05:09.454 20882 20902 I Ringtone: 	at org.thoughtcrime.securesms.notifications.MessageNotifier.notifyMessageDeliveryFailed(MessageNotifier.java:104)
03-24 18:05:09.454 20882 20902 I Ringtone: 	at org.thoughtcrime.securesms.jobs.PushTextSendJob.onCanceled(PushTextSendJob.java:92)
03-24 18:05:09.454 20882 20902 I Ringtone: 	at org.whispersystems.jobqueue.JobConsumer.run(JobConsumer.java:52)
03-24 18:05:09.461 20882 20902 I MediaPlayer: start
03-24 18:05:09.855 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:09.855 20882 23240 W WebSocketConnection: Message Type: 2
03-24 18:05:14.671 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:14.671 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:14.672 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:14.672 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:14.674 20882 20915 W SignalServiceEnvelope: Our MAC: 1b d1 ca 9c 0c 87 7f 3e 6b cd 
03-24 18:05:14.674 20882 20915 W SignalServiceEnvelope: Thr MAC: 1b d1 ca 9c 0c 87 7f 3e 6b cd 
03-24 18:05:14.675 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:14.678 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936312830)
03-24 18:05:14.682 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@7d00093
03-24 18:05:14.683 20882 20915 W EarlyReceiptCache: Early receipt: (1521936312830, +*********57)
03-24 18:05:14.685 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:14.685 20882 20915 W EarlyReceiptCache: Early receipt: (1521936312830, +*********57)
03-24 18:05:14.689 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:14.691 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:14.692 20882 20915 W SignalServiceEnvelope: Our MAC: b7 3f bd f1 14 68 7f 6f a4 59 
03-24 18:05:14.692 20882 20915 W SignalServiceEnvelope: Thr MAC: b7 3f bd f1 14 68 7f 6f a4 59 
03-24 18:05:14.693 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:14.695 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936312830)
03-24 18:05:14.699 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@7d00093
03-24 18:05:14.700 20882 20915 W EarlyReceiptCache: Early receipt: (1521936312830, +*********57)
03-24 18:05:14.707 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:14.707 20882 20915 W EarlyReceiptCache: Early receipt: (1521936312830, +*********57)
03-24 18:05:14.713 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:14.715 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:15.068 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:15.069 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:15.070 20882 20915 W SignalServiceEnvelope: Our MAC: c3 f5 9f 11 43 c8 23 be cd 76 
03-24 18:05:15.070 20882 20915 W SignalServiceEnvelope: Thr MAC: c3 f5 9f 11 43 c8 23 be cd 76 
03-24 18:05:15.072 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********65
03-24 18:05:15.106 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:15.109 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:15.125 20882 20904 W SessionBuilder: We've already setup a session for this V3 message, letting bundled message fall through...
03-24 18:05:15.142 20882 20904 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@7d00093
03-24 18:05:15.143 20882 20904 W EarlyReceiptCache: Checking early receipts (1521936312830): 1
03-24 18:05:15.143 20882 20904 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@8f195df
03-24 18:05:15.143 20882 20904 W EarlyReceiptCache: Checking early receipts (1521936312830): 0
03-24 18:05:15.188 20882 20904 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:15.254 20882 21000 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:15.254 20882 20904 W MessageDatabase: Updating ID: 22041 to base type: 10485783
03-24 18:05:15.351 20882 20904 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:15.352 20882 21006 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:15.394 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:15.430 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:15.437 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:15.442 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:15.444 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:15.448 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:15.538 20882 20904 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (read = 0 AND notified = 0) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (read = 0 AND notified = 0) ORDER BY date_received ASC)
03-24 18:05:15.538 20882 21034 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:15.556 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:15.571 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:15.572 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:15.573 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:15.573 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:15.574 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:15.580 20882 20902 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:15.580 20882 20902 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v2/keys/
03-24 18:05:17.413 20882 20902 W RefreshPreKeysJob: Available keys sufficient: 62
03-24 18:05:17.709 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:17.709 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:17.710 20882 20915 W SignalServiceEnvelope: Our MAC: b4 a6 48 ba 72 e5 b8 f4 95 bf 
03-24 18:05:17.710 20882 20915 W SignalServiceEnvelope: Thr MAC: b4 a6 48 ba 72 e5 b8 f4 95 bf 
03-24 18:05:17.710 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:17.712 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936316285)
03-24 18:05:17.714 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@7d00093
03-24 18:05:17.714 20882 20915 W EarlyReceiptCache: Early receipt: (1521936316285, +*********57)
03-24 18:05:17.715 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:17.715 20882 20915 W EarlyReceiptCache: Early receipt: (1521936316285, +*********57)
03-24 18:05:17.718 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:17.719 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:17.933 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:17.934 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:17.934 20882 20915 W SignalServiceEnvelope: Our MAC: 99 48 74 a4 18 94 08 20 27 59 
03-24 18:05:17.935 20882 20915 W SignalServiceEnvelope: Thr MAC: 99 48 74 a4 18 94 08 20 27 59 
03-24 18:05:17.936 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********65
03-24 18:05:17.976 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:17.979 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:17.994 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:17.994 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:17.995 20882 20915 W SignalServiceEnvelope: Our MAC: eb 19 6b 5a eb 89 3a 60 bc 7b 
03-24 18:05:17.996 20882 20915 W SignalServiceEnvelope: Thr MAC: eb 19 6b 5a eb 89 3a 60 bc 7b 
03-24 18:05:17.996 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:17.998 20882 20902 W SessionBuilder: We've already setup a session for this V3 message, letting bundled message fall through...
03-24 18:05:17.998 20882 20915 W PushReceivedJob: Received receipt: (XXXXX, 1521936316285)
03-24 18:05:18.005 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@7d00093
03-24 18:05:18.006 20882 20915 W EarlyReceiptCache: Early receipt: (1521936316285, +*********57)
03-24 18:05:18.008 20882 20915 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@b8ac0c9
03-24 18:05:18.008 20882 20915 W EarlyReceiptCache: Early receipt: (1521936316285, +*********57)
03-24 18:05:18.012 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:18.012 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:18.014 20882 20902 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@7d00093
03-24 18:05:18.014 20882 20902 W EarlyReceiptCache: Checking early receipts (1521936316285): 1
03-24 18:05:18.014 20882 20902 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@8f195df
03-24 18:05:18.014 20882 20902 W EarlyReceiptCache: Checking early receipts (1521936316285): 0
03-24 18:05:18.060 20882 20902 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:18.145 20882 20935 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:18.147 20882 20902 W MessageDatabase: Updating ID: 22042 to base type: 10485783
03-24 18:05:18.227 20882 20957 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:18.235 20882 20902 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:18.282 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:18.295 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:18.307 20882 20882 I chatty  : uid=10105(org.thoughtcrime.securesms) identical 4 lines
03-24 18:05:18.310 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:18.394 20882 21000 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 500)
03-24 18:05:18.394 20882 20902 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (read = 0 AND notified = 0) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (read = 0 AND notified = 0) ORDER BY date_received ASC)
03-24 18:05:18.415 20882 20882 W ConversationFragment: onLoadFinished
03-24 18:05:18.428 20882 20903 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:18.428 20882 20903 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v2/keys/
03-24 18:05:18.429 20882 20882 W ConversationAdapter: Bind time: 2
03-24 18:05:18.430 20882 20882 W ConversationAdapter: Bind time: 1
03-24 18:05:18.430 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:18.431 20882 20882 I chatty  : uid=10105(org.thoughtcrime.securesms) identical 2 lines
03-24 18:05:18.432 20882 20882 W ConversationAdapter: Bind time: 0
03-24 18:05:18.856 20882 20903 W RefreshPreKeysJob: Available keys sufficient: 62
03-24 18:05:37.874 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:38.415 20882 20882 D AppTracker: App Event: stop
03-24 18:05:38.419 20882 20882 W PassphraseRequiredActionBarActivity: onPause()
03-24 18:05:38.443 20882 20882 W KeyCachingService: onStartCommand, org.thoughtcrime.securesms.service.action.ACTIVITY_STOP_EVENT
03-24 18:05:38.443 20882 20882 W KeyCachingService: Decrementing activity count...
03-24 18:05:38.443 20882 20882 W MessageRetrievalService: Active Count: 0
03-24 18:05:38.451 20882 20882 W PassphraseRequiredActionBarActivity: onResume()
03-24 18:05:38.456 20882 20882 D AppTracker: App Event: start
03-24 18:05:38.497 20882 20882 W KeyCachingService: onStartCommand, org.thoughtcrime.securesms.service.action.ACTIVITY_START_EVENT
03-24 18:05:38.497 20882 20882 W KeyCachingService: Incrementing activity count...
03-24 18:05:38.498 20882 20882 W MessageRetrievalService: Active Count: 1
03-24 18:05:38.709 20882 20882 W EventBus: Subscriber to unregister was not registered before: class org.thoughtcrime.securesms.ConversationActivity
03-24 18:05:38.710 20882 20882 W PassphraseRequiredActionBarActivity: onDestroy()
03-24 18:05:38.721 20882 23513 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:39.245 20882 20888 I zygote  : Do partial code cache collection, code=244KB, data=169KB
03-24 18:05:39.247 20882 20888 I zygote  : After code cache collection, code=242KB, data=168KB
03-24 18:05:39.247 20882 20888 I zygote  : Increasing code cache capacity to 1024KB
03-24 18:05:41.091 20882 20882 D AppTracker: App Event: stop
03-24 18:05:41.093 20882 20882 W PassphraseRequiredActionBarActivity: onPause()
03-24 18:05:41.100 20882 20882 W KeyCachingService: onStartCommand, org.thoughtcrime.securesms.service.action.ACTIVITY_STOP_EVENT
03-24 18:05:41.100 20882 20882 W KeyCachingService: Decrementing activity count...
03-24 18:05:41.100 20882 20882 W MessageRetrievalService: Active Count: 0
03-24 18:05:41.102 20882 20882 W PassphraseRequiredActionBarActivity: onCreate(null)
03-24 18:05:41.103 20882 20882 W ExperienceUpgradeActivity: getExperienceUpgrade(333)
03-24 18:05:41.103 20882 20882 W PassphraseRequiredActionBarActivity: routeApplicationState(), state: 0
03-24 18:05:41.104 20882 20882 W PassphraseRequiredActionBarActivity: initializeClearKeyReceiver()
03-24 18:05:41.106 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:41.113 20882 20882 I chatty  : uid=10105(org.thoughtcrime.securesms) identical 2 lines
03-24 18:05:41.114 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:41.115 20882 20882 W PassphraseRequiredActionBarActivity: onResume()
03-24 18:05:41.120 20882 20882 D AppTracker: App Event: start
03-24 18:05:41.152 20882 20882 W KeyCachingService: onStartCommand, org.thoughtcrime.securesms.service.action.ACTIVITY_START_EVENT
03-24 18:05:41.152 20882 20882 W KeyCachingService: Incrementing activity count...
03-24 18:05:41.153 20882 20882 W MessageRetrievalService: Active Count: 1
03-24 18:05:41.159 20882 20891 W MediaPlayer-JNI: MediaPlayer finalized without being released
03-24 18:05:41.165 20882 20891 V MediaPlayer: resetDrmState:  mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
03-24 18:05:41.165 20882 20891 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
03-24 18:05:41.167 20882 20891 W MediaPlayer-JNI: MediaPlayer finalized without being released
03-24 18:05:41.171 20882 20891 V MediaPlayer: resetDrmState:  mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
03-24 18:05:41.171 20882 20891 V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null
03-24 18:05:41.197 20882 20937 D OpenGLRenderer: endAllActiveAnimators on 0xc7509580 (MenuPopupWindow$MenuDropDownListView) with handle 0xc6448ea0
03-24 18:05:43.131 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:43.132 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:43.949 20882 23240 W WebSocketConnection: WSC onMessage()
03-24 18:05:43.950 20882 23240 W WebSocketConnection: Message Type: 1
03-24 18:05:43.951 20882 20915 W SignalServiceEnvelope: Our MAC: 3b 4f 59 77 24 72 cf 3c c5 99 
03-24 18:05:43.952 20882 20915 W SignalServiceEnvelope: Thr MAC: 3b 4f 59 77 24 72 cf 3c c5 99 
03-24 18:05:43.953 20882 20915 W MessageRetrievalService: Retrieved envelope! +*********57
03-24 18:05:43.984 20882 20915 W MessageRetrievalService: Network requirement: true, active activities: 1, push pending: 0, gcm disabled: false
03-24 18:05:43.985 20882 20915 W MessageRetrievalService: Reading message...
03-24 18:05:44.020 20882 20905 W PushDecryptJob: Received encrypted read receipt: (XXXXX, 1521936289272)
03-24 18:05:44.060 20882 20905 W MmsSmsDatabase: Executing query: SELECT _id, unique_row_id, body, type, thread_id, address, address_device_id, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, network_failures, subscription_id, expires_in, expire_started, notified, transport_type, attachment_id, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM (SELECT DISTINCT date_sent AS date_sent, date AS date_received, _id, 'SMS::' || _id || '::' || date_sent AS unique_row_id, NULL AS attachment_id, body, read, thread_id, type, address, address_device_id, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, NULL AS network_failures, 'sms' AS transport_type, NULL AS unique_id, NULL AS mid, NULL AS data_size, NULL AS file_name, NULL AS _data, NULL AS thumbnail, NULL AS ct, NULL AS cl, NULL AS digest, NULL AS fast_preflight_id, NULL AS voice_note, NULL AS cd, NULL AS name, NULL AS pending_push FROM sms WHERE (thread_id = 4) UNION ALL SELECT DISTINCT date AS date_sent, date_received AS date_received, mms._id AS _id, 'MMS::' || mms._id || '::' || date AS unique_row_id, part._id AS attachment_id, body, read, thread_id, NULL AS type, address, address_device_id, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, delivery_receipt_count, read_receipt_count, mismatched_identities, subscription_id, expires_in, expire_started, notified, network_failures, 'mms' AS transport_type, unique_id, mid, data_size, file_name, _data, thumbnail, ct, cl, digest, fast_preflight_id, voice_note, cd, name, pending_push FROM mms LEFT OUTER JOIN part ON part._id =  (SELECT part._id FROM part WHERE part.mid = mms._id LIMIT 1) WHERE (thread_id = 4) ORDER BY date_received DESC LIMIT 1)
03-24 18:05:44.100 20882 20905 W EarlyReceiptCache: org.thoughtcrime.securesms.database.EarlyReceiptCache@8983a84
03-24 18:05:44.100 20882 20905 W EarlyReceiptCache: Early receipt: (1521936289272, +*********57)
03-24 18:05:44.105 20882 20903 W PushServiceSocket: Push service URL: https://textsecure-service.whispersystems.org
03-24 18:05:44.105 20882 20903 W PushServiceSocket: Opening URL: https://textsecure-service.whispersystems.org/v2/keys/
03-24 18:05:44.427 20882 20903 W RefreshPreKeysJob: Available keys sufficient: 61
03-24 18:05:44.747 20882 20882 D AppTracker: App Event: stop
03-24 18:05:44.748 20882 20882 W PassphraseRequiredActionBarActivity: onPause()
03-24 18:05:44.754 20882 20882 W KeyCachingService: onStartCommand, org.thoughtcrime.securesms.service.action.ACTIVITY_STOP_EVENT
03-24 18:05:44.754 20882 20882 W KeyCachingService: Decrementing activity count...
03-24 18:05:44.754 20882 20882 W MessageRetrievalService: Active Count: 0
03-24 18:05:44.759 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:44.762 20882 20882 I chatty  : uid=10105(org.thoughtcrime.securesms) identical 2 lines
03-24 18:05:44.762 20882 20882 E BoostFramework: BoostFramework() : Exception_1 = java.lang.NoSuchMethodException: perfIOPrefetchStart [int, class java.lang.String]
03-24 18:05:44.765 20882 20882 D AppTracker: App Event: start
03-24 18:05:44.816 20882 20937 D OpenGLRenderer: endAllActiveAnimators on 0xc9998280 (RippleDrawab

@dmzpkts
Copy link
Author

dmzpkts commented Mar 25, 2018

Rebooted phone, reset sessions again, finally working phone and desktop

@scottnonnenberg-signal
Copy link
Contributor

Thanks for letting us know about this, glad it ended up in good shape. Can you tell us any more about what you did right before all this to cause the rate limiting to kick in?

@dmzpkts
Copy link
Author

dmzpkts commented Apr 12, 2018

I was attempting to back everything up before I wasted my messages and started over.. So I did it i believe the wrong way. I rolled back to the chrome app, so that I could export the DB again.. And then reimported it into desktop.

It apparently tried to pull a bunch of messages from the missing time inbetween the previous export, and the time I rolled it back.

Then when I reimpoted the new backup, it ratelimited me.

@five-c-d five-c-d mentioned this issue Sep 28, 2018
Closed
1 task
@josh-signal josh-signal mentioned this issue Sep 29, 2021
Closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Feature Request
Milestone
No milestone
Development

No branches or pull requests
3 participants
@dmzpkts @scottnonnenberg-signal @automated-signal