search

signalapp / Signal-Desktop

A private messenger for Windows, macOS, and Linux.
https://signal.org/download
GNU Affero General Public License v3.0
14.69k stars 2.69k forks source link

android#A sends voiceNote, android#B receives (but linked linux stuck with spinning-circle) #3429

Open five-c-d opened 5 years ago

five-c-d commented 5 years ago

Bug Description

When signal4desktop was closed, I received a voiceNote on my linked signal4android, and upon re-opening signal4desktop to reply, the voicenote had a chat-bubble with a spinning circle and "330.15 KB" ...which never finished downloading.

Steps to Reproduce

  1. sender has android, s9+
  2. recipient has android, alcatel, on wifi
  3. linked signal-desktop is also on wifi but with the application closed
  4. voicenote arrives
  5. unlock alcatel and listen to voicenote
  6. launch signal4desktop to reply with real keyboard
  7. send some textual replies
  8. notice that voiceNote never finished download into signal4desktop

Actual Result:

Expected Result:

Screenshots

Can provide if needed

Platform Info

Signal Version: v1.25.2-beta.3

Operating System: Arch Linux

Linked Device Version: signal-android 4.42.3 stable, android 7.1.1

Link to Debug Log

The message was sent to me about 3 hours ago, only noticed it later:

Seems likely to be the same root cause as #3357 (iphone recipient).

p.s. Also, debuglogs seem to (again) have a VERY large number of database-queries,

Then seventeen hours later

Which is every 131 milliseconds, on average... doing a bit of rough LibreOffice calculation shows that the logfile which starts around midnight UTC on the 29th, was performing one request every 500 milliseconds by around 2am, one request every 250ms by around 4am, and one request every 99ms by lunchtime. I was oblivious to all this action :-) but I'm pretty sure it was NOT hitting the disk-drive, so whatever database requests were happening, were presumably to info that is cached in RAM.

scottnonnenberg-signal commented 5 years ago

You should update to v1.25.3-beta.1 - this fix should help with your too-frequent database queries: https://github.com/signalapp/Signal-Desktop/commit/cc4886caa58f125b64d8e60eec30bdf211a95b80

kenpowers-signal commented 5 years ago

@five-c-d Do any attachments work for you? There appears to be a lot of DNS errors in your debug log. Can you tell us about your network?

five-c-d commented 5 years ago

update to v1.25.3-beta.1

done, will keep you informed if I see anything weird

Do any attachments work

Yes, they pretty much always work. I've only noticed two failures (i.e. under <1% of media). https://debuglogs.org/30a286734c3b495714b6da473a4ef0d5e2a8243e155afb4b810a48b049e73fb3 has some attachment-tests with 1.25.3-beta.1 near the end of the debuglog, all were successful. I cannot reproduce the spinning-circle at will, and don't know why it happens sometimes. The first time it happened was in a batch of five imagefiles, 3 of 5 arrived on desktop, 5 of 5 on android. Second time was a voicenote of medium-duration.

DNS errors

There was a weather-outage last week, but otherwise should be "normal". Somewhat-busy wifi with half a dozen devices on it. Don't think it is a network hiccup causing the never-able-to-download, but hard to rule it out 100.000%

edit: Ah, looking at the behavior of the logfiles when I put my laptop into airplane mode (wifi killswitch at the "BIOS level") shows that signal4desktop does this:

a bit chatty when network-card is powered off...

``` $ tail ~/.config/Signal\ Beta/logs/log.log -f {..."level":30,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices","time":"2019-07-01T17:56:24.014Z","v":0} {..."level":30,"msg":"MessageReceiver: finished processing messages after 'empty', now waiting for application","time":"2019-07-01T17:56:24.015Z","v":0} {..."level":30,"msg":"MessageReceiver: emitting 'empty' event","time":"2019-07-01T17:56:24.015Z","v":0} {..."level":50,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices 0 Error","time":"2019-07-01T17:56:34.017Z","v":0} {..."level":30,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices","time":"2019-07-01T17:56:35.018Z","v":0} {..."level":50,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices 0 Error","time":"2019-07-01T17:56:35.024Z","v":0} {..."level":30,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices","time":"2019-07-01T17:56:36.025Z","v":0} {..."level":50,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices 0 Error","time":"2019-07-01T17:56:36.031Z","v":0} {..."level":50,"msg":"background onError: HTTPError: promiseAjax catch; code: -1\n at HTTPError ([REDACTED]/app.asar/js/modules/web_api.js:379:13)\n at fetch.then.catch.e ([REDACTED]/app.asar/js/modules/web_api.js:351:16)\n at process._tickCallback (internal/process/next_tick.js:68:7)\nOriginal stack:\nFetchError: request to https://textsecure-service.whispersystems.org/v1/devices failed, reason: getaddrinfo EAI_AGAIN textsecure-service.whispersystems.org textsecure-service.whispersystems.org:443\n at ClientRequest. ([REDACTED]/app.asar/node_modules/node-fetch/index.js:133:11)\n at ClientRequest.emit (events.js:182:13)\n at TLSSocket.socketErrorListener (_http_client.js:391:9)\n at TLSSocket.emit (events.js:182:13)\n at emitErrorNT (internal/streams/destroy.js:82:8)\n at emitErrorAndCloseNT (internal/streams/destroy.js:50:3)\n at process._tickCallback (internal/process/next_tick.js:63:19)\nInitial stack:\nError\n at _outerAjax ([REDACTED]/app.asar/js/modules/web_api.js:373:19)\n at _ajax ([REDACTED]/app.asar/js/modules/web_api.js:476:14)\n at Object.getDevices ([REDACTED]/app.asar/js/modules/web_api.js:667:14)\n at MessageReceiver.onclose (file://[REDACTED]/app.asar/js/libtextsecure.js:38479:8)\n at WebSocketResource.dispatchEvent (file://[REDACTED]/app.asar/js/libtextsecure.js:37333:35)\n at setTimeout (file://[REDACTED]/app.asar/js/libtextsecure.js:38166:14)","time":"2019-07-01T17:56:36.032Z","v":0} {..."level":30,"msg":"retrying in 1 minute","time":"2019-07-01T17:56:36.033Z","v":0} {..."level":30,"msg":"connect","time":"2019-07-01T17:57:36.033Z","v":0} {..."level":30,"msg":"MessageReceiver.close()","time":"2019-07-01T17:57:36.035Z","v":0} {..."level":30,"msg":"opening message socket https://textsecure-service.whispersystems.org","time":"2019-07-01T17:57:36.036Z","v":0} {..."level":30,"msg":"Start idle detector","time":"2019-07-01T17:57:36.039Z","v":0} {..."level":30,"msg":"drained","time":"2019-07-01T17:57:36.039Z","v":0} {..."level":50,"msg":"websocket error","time":"2019-07-01T17:57:36.040Z","v":0} {..."level":30,"msg":"websocket closed 1006 connection failed calledClose: ","time":"2019-07-01T17:57:36.041Z","v":0} {..."level":30,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices","time":"2019-07-01T17:57:36.041Z","v":0} {..."level":30,"msg":"Background migration complete. Stopping idle detector.","time":"2019-07-01T17:57:36.043Z","v":0} {..."level":30,"msg":"Stop idle detector","time":"2019-07-01T17:57:36.044Z","v":0} {..."level":50,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices 0 Error","time":"2019-07-01T17:57:36.044Z","v":0} {..."level":30,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices","time":"2019-07-01T17:57:37.045Z","v":0} {..."level":50,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices 0 Error","time":"2019-07-01T17:57:37.052Z","v":0} {..."level":30,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices","time":"2019-07-01T17:57:38.053Z","v":0} {..."level":50,"msg":"GET https://textsecure-service.whispersystems.org/v1/devices 0 Error","time":"2019-07-01T17:57:38.059Z","v":0} {..."level":50,"msg":"background onError: HTTPError: promiseAjax catch; code: -1\n at HTTPError ([REDACTED]/app.asar/js/modules/web_api.js:379:13)\n at fetch.then.catch.e ([REDACTED]/app.asar/js/modules/web_api.js:351:16)\n at process._tickCallback (internal/process/next_tick.js:68:7)\nOriginal stack:\nFetchError: request to https://textsecure-service.whispersystems.org/v1/devices failed, reason: getaddrinfo EAI_AGAIN textsecure-service.whispersystems.org textsecure-service.whispersystems.org:443\n at ClientRequest. ([REDACTED]/app.asar/node_modules/node-fetch/index.js:133:11)\n at ClientRequest.emit (events.js:182:13)\n at TLSSocket.socketErrorListener (_http_client.js:391:9)\n at TLSSocket.emit (events.js:182:13)\n at emitErrorNT (internal/streams/destroy.js:82:8)\n at emitErrorAndCloseNT (internal/streams/destroy.js:50:3)\n at process._tickCallback (internal/process/next_tick.js:63:19)\nInitial stack:\nError\n at _outerAjax ([REDACTED]/app.asar/js/modules/web_api.js:373:19)\n at _ajax ([REDACTED]/app.asar/js/modules/web_api.js:476:14)\n at Object.getDevices ([REDACTED]/app.asar/js/modules/web_api.js:667:14)\n at MessageReceiver.onclose (file://[REDACTED]/app.asar/js/libtextsecure.js:38479:8)\n at W3CWebSocket._dispatchEvent [as dispatchEvent] ([REDACTED]/app.asar/node_modules/yaeti/lib/EventTarget.js:107:17)\n at W3CWebSocket.onConnectFailed ([REDACTED]/app.asar/node_modules/websocket/lib/W3CWebSocket.js:219:14)\n at WebSocketClient. ([REDACTED]/app.asar/node_modules/websocket/lib/W3CWebSocket.js:59:25)\n at WebSocketClient.emit (events.js:182:13)\n at ClientRequest.handleRequestError ([REDACTED]/app.asar/node_modules/websocket/lib/WebSocketClient.js:227:14)\n at ClientRequest.emit (events.js:182:13)","time":"2019-07-01T17:57:38.060Z","v":0} {..."level":30,"msg":"retrying in 1 minute","time":"2019-07-01T17:57:38.060Z","v":0} ``` Those are not "network-errors" they are just, minimizing the blinking lights

p.s. Here is the relevant forum-thread == https://community.signalusers.org/t/some-messages-never-arrive-on-desktop/6701 with some other reports.

five-c-d commented 5 years ago 
Sent        Wednesday, July 3, 2019 9:00 AM (1562162448520)
Received    Wednesday, July 3, 2019 1:01 PM (1562176890596)

This is just after I opened up / launched signal4desktop. Message is a voice-note, ~237.08 KiB, signal4android received it okay about 9 seconds after it was sent.

Also, for the first time (that I remember at least), when sending something from signal4android master the signal4desktop slave, failed to receive the sync. It was ~105.8 KiB png from the shared-filesystem area 

Sent        Wednesday, July 3, 2019 1:41 PM (1562179274232)
Received    Wednesday, July 3, 2019 1:41 PM (1562179273069)

This was with both devices online, and a different signal4android sender (the master of the downstream desktop rather than another party sending to me).

excerpts near those two messages, plus errors, signal4desktop

``` $ cat ~/.config/Signal\ Beta/logs/log.log | grep "7-03T" | grep -i error ERR "GET https://cdn.signal.org/profiles/i8uldDr3_X7KrYMnL7e9Kw 403 Error","time":"2019-07-03T06:46:52.506Z","v":0} ERR "GET https://cdn.signal.org/profiles/i8uldDr3_X7KrYMnL7e9Kw 403 Error","time":"2019-07-03T09:49:37.550Z","v":0} ERR "decrypt failed to save decrypted message contents to cache: Error: Rejecting SQL channel job (updateUnprocessedWithData); application is shutting down\n at _makeJob ([REDACTED]/app.asar/js/modules/data.js:237:11)\n at Object.channels.(anonymous function).args [as updateUnprocessedWithData] ([REDACTED]/app.asar/js/modules/data.js:331:19)\n at Object.updateUnprocessedWithData ([REDACTED]/app.asar/js/modules/data.js:871:18)\n at SignalProtocolStore.updateUnprocessedWithData (file://[REDACTED]/app.asar/js/signal_protocol_store.js:852:33)\n at Object.addDecryptedData (file://[REDACTED]/app.asar/js/libtextsecure.js:37075:42)\n at MessageReceiver.updateCache (file://[REDACTED]/app.asar/js/libtextsecure.js:38789:43)","time":"2019-07-03T09:50:20.825Z","v":0} ERR "ReadReceipts.onReceipt error: Error: Rejecting SQL channel job (saveMessage); application is shutting down\n at _makeJob ([REDACTED]/app.asar/js/modules/data.js:237:11)\n at Object.channels.(anonymous function).args [as saveMessage] ([REDACTED]/app.asar/js/modules/data.js:331:19)\n at Object.saveMessage ([REDACTED]/app.asar/js/modules/data.js:676:29)\n at child.onReceipt (file://[REDACTED]/app.asar/js/read_receipts.js:104:36)","time":"2019-07-03T09:50:20.894Z","v":0} ERR "ReadReceipts.onReceipt error: Error: Rejecting SQL channel job (getMessagesBySentAt); application is shutting down\n at _makeJob ([REDACTED]/app.asar/js/modules/data.js:237:11)\n at Object.channels.(anonymous function).args [as getMessagesBySentAt] ([REDACTED]/app.asar/js/modules/data.js:331:19)\n at Object.getMessagesBySentAt ([REDACTED]/app.asar/js/modules/data.js:823:35)\n at child.onReceipt (file://[REDACTED]/app.asar/js/read_receipts.js:67:51)\n at onReadReceipt (file://[REDACTED]/app.asar/js/background.js:1707:33)\n at eventHandlerQueue.add (file://[REDACTED]/app.asar/js/background.js:820:37)\n at run ([REDACTED]/app.asar/node_modules/p-queue/index.js:211:20)\n at PQueue._tryToStartAnother ([REDACTED]/app.asar/node_modules/p-queue/index.js:173:25)\n at PQueue._next ([REDACTED]/app.asar/node_modules/p-queue/index.js:113:8)\n at run ([REDACTED]/app.asar/node_modules/p-queue/index.js:216:10)","time":"2019-07-03T09:50:20.894Z","v":0} ERR "ReadReceipts.onReceipt error: Error: Rejecting SQL channel job (getMessagesBySentAt); application is shutting down\n at _makeJob ([REDACTED]/app.asar/js/modules/data.js:237:11)\n at Object.channels.(anonymous function).args [as getMessagesBySentAt] ([REDACTED]/app.asar/js/modules/data.js:331:19)\n at Object.getMessagesBySentAt ([REDACTED]/app.asar/js/modules/data.js:823:35)\n at child.onReceipt (file://[REDACTED]/app.asar/js/read_receipts.js:67:51)\n at onReadReceipt (file://[REDACTED]/app.asar/js/background.js:1707:33)\n at eventHandlerQueue.add (file://[REDACTED]/app.asar/js/background.js:820:37)\n at run ([REDACTED]/app.asar/node_modules/p-queue/index.js:211:20)\n at PQueue._tryToStartAnother ([REDACTED]/app.asar/node_modules/p-queue/index.js:173:25)\n at PQueue._next ([REDACTED]/app.asar/node_modules/p-queue/index.js:113:8)\n at run ([REDACTED]/app.asar/node_modules/p-queue/index.js:216:10)","time":"2019-07-03T09:50:20.894Z","v":0} $ cat ~/.config/Signal\ Beta/logs/log.log | grep "7-03T18\:01\:" "app ready","time":"2019-07-03T18:01:06.191Z" "starting version 1.25.3-beta.1","time":"2019-07-03T18:01:06.191Z" "updateSchema: Current schema version: 15; Most recent schema version: 15; SQLite version: 3.20.1; SQLCipher version: 3.4.2;","time":"2019-07-03T18:01:06.207Z" "Initializing BrowserWindow config: {\"show\":true,\"width\":1400,\"height\":986,\"minWidth\":640,\"minHeight\":550,\"autoHideMenuBar\":true,\"backgroundColor\":\"#2090EA\",\"webPreferences\":{\"nodeIntegration\":false,\"nodeIntegrationInWorker\":false,\"contextIsolation\":false,\"preload\":\"/opt/Signal Beta/resources/app.asar/preload.js\",\"nativeWindowOpen\":true},\"icon\":\"/opt/Signal Beta/resources/app.asar/images/icon_256.png\",\"maximized\":false,\"x\":9,\"y\":34}","time":"2019-07-03T18:01:06.215Z" "Updating BrowserWindow config: {\"maximized\":true,\"autoHideMenuBar\":true,\"width\":1400,\"height\":986,\"x\":0,\"y\":64}","time":"2019-07-03T18:01:06.774Z" "config/set: Saving ephemeral config to disk","time":"2019-07-03T18:01:06.774Z" "Detected Linux. Using default en_US spell check dictionary","time":"2019-07-03T18:01:07.583Z" "pre-main prep time: 2 ms","time":"2019-07-03T18:01:07.805Z" "Build expires: 2019-09-25T22:58:04.000Z","time":"2019-07-03T18:01:07.830Z" "Preloading emoji images","time":"2019-07-03T18:01:07.847Z" "background page reloaded","time":"2019-07-03T18:01:07.858Z" "environment: production","time":"2019-07-03T18:01:07.858Z" "SQL channel job 1 (getItemById) succeeded in 123ms","time":"2019-07-03T18:01:07.982Z" "Storage fetch","time":"2019-07-03T18:01:07.982Z" "SQL channel job 2 (getAllItems) succeeded in 85ms","time":"2019-07-03T18:01:08.068Z" "SQL channel job 3 (createOrUpdateItem) succeeded in 166ms","time":"2019-07-03T18:01:08.240Z" "Starting background data migration. Target version: 10","time":"2019-07-03T18:01:08.240Z" "ConversationController: starting initial fetch","time":"2019-07-03T18:01:08.240Z" "SQL channel job 4 (getAllConversations) succeeded in 29ms","time":"2019-07-03T18:01:08.270Z" "SQL channel job 5 (getAllStickerPacks) succeeded in 71ms","time":"2019-07-03T18:01:08.313Z" "SQL channel job 6 (getAllStickers) succeeded in 73ms","time":"2019-07-03T18:01:08.314Z" "SQL channel job 7 (getRecentStickers) succeeded in 73ms","time":"2019-07-03T18:01:08.315Z" "SQL channel job 8 (getRecentEmojis) succeeded in 73ms","time":"2019-07-03T18:01:08.315Z" "SQL channel job 9 (getAllIdentityKeys) succeeded in 74ms","time":"2019-07-03T18:01:08.315Z" "SignalProtocolStore: Finished caching identityKeys data","time":"2019-07-03T18:01:08.317Z" "ConversationController: done with initial fetch","time":"2019-07-03T18:01:08.320Z" "SignalProtocolStore: Finished caching sessions data","time":"2019-07-03T18:01:08.330Z" "SignalProtocolStore: Finished caching preKeys data","time":"2019-07-03T18:01:08.340Z" "SignalProtocolStore: Finished caching signedPreKeys data","time":"2019-07-03T18:01:08.346Z" "Cleanup: starting...","time":"2019-07-03T18:01:08.350Z" "SQL channel job 15 (getOutgoingWithoutExpiresAt) succeeded in 88ms","time":"2019-07-03T18:01:08.438Z" "Cleanup: Found 2 messages for cleanup","time":"2019-07-03T18:01:08.448Z" "Cleanup: complete","time":"2019-07-03T18:01:08.448Z" "listening for registration events","time":"2019-07-03T18:01:08.449Z" "Next signed key rotation scheduled for 2019-07-03T17:34:55.159Z","time":"2019-07-03T18:01:08.452Z" "refreshSenderCertificate: Getting new certificate...","time":"2019-07-03T18:01:08.453Z" "GET https://textsecure-service.whispersystems.org/v1/certificate/delivery","time":"2019-07-03T18:01:08.455Z" "connect","time":"2019-07-03T18:01:08.466Z" "getAllFromCache","time":"2019-07-03T18:01:08.468Z" "opening message socket https://textsecure-service.whispersystems.org","time":"2019-07-03T18:01:08.468Z" "Start idle detector","time":"2019-07-03T18:01:08.472Z" "open inbox","time":"2019-07-03T18:01:08.473Z" "SQL channel job 16 (getNextExpiringMessage) succeeded in 82ms","time":"2019-07-03T18:01:08.534Z" "next message expires 2019-07-03T14:59:07.605Z","time":"2019-07-03T18:01:08.535Z" "SQL channel job 17 (getUnprocessedCount) succeeded in 70ms","time":"2019-07-03T18:01:08.536Z" "SQL channel job 18 (resetAttachmentDownloadPending) succeeded in 65ms","time":"2019-07-03T18:01:08.537Z" "Rotating signed prekey...","time":"2019-07-03T18:01:08.604Z" "Next signed key rotation scheduled for 2019-07-05T18:01:08.605Z","time":"2019-07-03T18:01:08.609Z" "SQL channel job 19 (getAllUnprocessed) succeeded in 75ms","time":"2019-07-03T18:01:08.612Z" "getAllFromCache loaded 1 saved envelopes","time":"2019-07-03T18:01:08.612Z" "SQL channel job 20 (getNextAttachmentDownloadJobs) succeeded in 76ms","time":"2019-07-03T18:01:08.614Z" "destroyExpiredMessages: Loading messages...","time":"2019-07-03T18:01:08.620Z" "SQL channel job 21 (createOrUpdateItem) succeeded in 501ms","time":"2019-07-03T18:01:09.106Z" "SQL channel job 22 (getItemById) succeeded in 507ms","time":"2019-07-03T18:01:09.111Z" "SQL channel job 23 (updateUnprocessedAttempts) succeeded in 509ms","time":"2019-07-03T18:01:09.124Z" "SQL channel job 24 (getExpiredMessages) succeeded in 505ms","time":"2019-07-03T18:01:09.124Z" "Message expired {\"sentAt\":1561533847350}","time":"2019-07-03T18:01:09.142Z" "Message expired {\"sentAt\":1561561411333}","time":"2019-07-03T18:01:09.142Z" "Message expired {\"sentAt\":1561566436388}","time":"2019-07-03T18:01:09.143Z" "Message expired {\"sentAt\":1561566441669}","time":"2019-07-03T18:01:09.143Z" "Message expired {\"sentAt\":1561566446374}","time":"2019-07-03T18:01:09.143Z" "Message expired {\"sentAt\":1561566449494}","time":"2019-07-03T18:01:09.143Z" "Message expired {\"sentAt\":1561566490320}","time":"2019-07-03T18:01:09.143Z" "Message expired {\"sentAt\":1561566508723}","time":"2019-07-03T18:01:09.144Z" "Message expired {\"sentAt\":1561566566937}","time":"2019-07-03T18:01:09.144Z" "Message expired {\"sentAt\":1561566665035}","time":"2019-07-03T18:01:09.144Z" "Message expired {\"sentAt\":1561568249297}","time":"2019-07-03T18:01:09.144Z" "Message expired {\"sentAt\":1561568263054}","time":"2019-07-03T18:01:09.144Z" "Message expired {\"sentAt\":1561568405248}","time":"2019-07-03T18:01:09.145Z" "Message expired {\"sentAt\":1561569112841}","time":"2019-07-03T18:01:09.145Z" "Message expired {\"sentAt\":1561569409839}","time":"2019-07-03T18:01:09.145Z" "Message expired {\"sentAt\":1561569468516}","time":"2019-07-03T18:01:09.146Z" "Message expired {\"sentAt\":1561569668443}","time":"2019-07-03T18:01:09.146Z" "Message expired {\"sentAt\":1561569835888}","time":"2019-07-03T18:01:09.146Z" "Worker job 1 (stringToArrayBufferBase64) succeeded in 38ms","time":"2019-07-03T18:01:09.161Z" "queueing envelope +[REDACTED]185.1 1562147420736 (b6bdbfb4-96b9-4e81-8f30-4e932d310dae)","time":"2019-07-03T18:01:09.163Z" "message from +[REDACTED]185.1 1562147420736 (b6bdbfb4-96b9-4e81-8f30-4e932d310dae)","time":"2019-07-03T18:01:09.164Z" ERR "background onError: MessageCounterError: Message key not found. The counter was repeated or the key was not filled.\n at file://[REDACTED]/app.asar/js/libtextsecure.js:36421:25","time":"2019-07-03T18:01:09.184Z" ERR "queueEnvelope error handling envelope +[REDACTED]185.1 1562147420736 (b6bdbfb4-96b9-4e81-8f30-4e932d310dae) : MessageCounterError: Message key not found. The counter was repeated or the key was not filled.\n at file://[REDACTED]/app.asar/js/libtextsecure.js:36421:25","time":"2019-07-03T18:01:09.185Z" "SQL channel job 25 (getMessageById) succeeded in 34ms","time":"2019-07-03T18:01:09.185Z" "SQL channel job 26 (getMessageById) succeeded in 36ms","time":"2019-07-03T18:01:09.186Z" "SQL channel job 27 (getMessageById) succeeded in 40ms","time":"2019-07-03T18:01:09.186Z" "SQL channel job 28 (getMessageById) succeeded in 41ms","time":"2019-07-03T18:01:09.186Z" "SQL channel job 29 (getMessageById) succeeded in 44ms","time":"2019-07-03T18:01:09.187Z" "SQL channel job 30 (getMessageById) succeeded in 45ms","time":"2019-07-03T18:01:09.187Z" "SQL channel job 31 (getMessageById) succeeded in 46ms","time":"2019-07-03T18:01:09.197Z" "SQL channel job 32 (getMessageById) succeeded in 48ms","time":"2019-07-03T18:01:09.198Z" "SQL channel job 33 (getMessageById) succeeded in 48ms","time":"2019-07-03T18:01:09.198Z" "SQL channel job 34 (getMessageById) succeeded in 50ms","time":"2019-07-03T18:01:09.198Z" "SQL channel job 35 (getMessageById) succeeded in 54ms","time":"2019-07-03T18:01:09.198Z" "SQL channel job 36 (getMessageById) succeeded in 55ms","time":"2019-07-03T18:01:09.199Z" "SQL channel job 37 (getMessageById) succeeded in 56ms","time":"2019-07-03T18:01:09.199Z" "SQL channel job 38 (getMessageById) succeeded in 58ms","time":"2019-07-03T18:01:09.200Z" "SQL channel job 39 (getMessageById) succeeded in 60ms","time":"2019-07-03T18:01:09.201Z" "SQL channel job 40 (getMessageById) succeeded in 61ms","time":"2019-07-03T18:01:09.202Z" "SQL channel job 41 (getMessageById) succeeded in 61ms","time":"2019-07-03T18:01:09.204Z" "SQL channel job 42 (getMessageById) succeeded in 63ms","time":"2019-07-03T18:01:09.205Z" ERR "Top-level unhandled promise rejection: MessageCounterError: Message key not found. The counter was repeated or the key was not filled.","time":"2019-07-03T18:01:09.206Z" "websocket open","time":"2019-07-03T18:01:09.221Z" "Worker job 2 (arrayBufferToStringBase64) succeeded in 93ms","time":"2019-07-03T18:01:09.316Z" "Worker job 3 (arrayBufferToStringBase64) succeeded in 91ms","time":"2019-07-03T18:01:09.317Z" "Worker job 4 (arrayBufferToStringBase64) succeeded in 91ms","time":"2019-07-03T18:01:09.320Z" "Worker job 5 (arrayBufferToStringBase64) succeeded in 97ms","time":"2019-07-03T18:01:09.327Z" "Worker job 6 (arrayBufferToStringBase64) succeeded in 96ms","time":"2019-07-03T18:01:09.328Z" "Worker job 7 (arrayBufferToStringBase64) succeeded in 97ms","time":"2019-07-03T18:01:09.328Z" "Worker job 8 (arrayBufferToStringBase64) succeeded in 98ms","time":"2019-07-03T18:01:09.328Z" "Worker job 9 (arrayBufferToStringBase64) succeeded in 98ms","time":"2019-07-03T18:01:09.328Z" "SQL channel job 43 (removeUnprocessed) succeeded in 154ms","time":"2019-07-03T18:01:09.328Z" "Worker job 10 (arrayBufferToStringBase64) succeeded in 13ms","time":"2019-07-03T18:01:09.333Z" "Worker job 11 (arrayBufferToStringBase64) succeeded in 12ms","time":"2019-07-03T18:01:09.333Z" "Worker job 12 (arrayBufferToStringBase64) succeeded in 13ms","time":"2019-07-03T18:01:09.333Z" "Worker job 13 (arrayBufferToStringBase64) succeeded in 12ms","time":"2019-07-03T18:01:09.334Z" "GET https://textsecure-service.whispersystems.org/v1/certificate/delivery 200 Success","time":"2019-07-03T18:01:09.335Z" "Next sender certificate refresh scheduled for 2019-07-04T18:01:09.337Z","time":"2019-07-03T18:01:09.340Z" "GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]085 (unauth)","time":"2019-07-03T18:01:09.341Z" "Worker job 14 (arrayBufferToStringBase64) succeeded in 23ms","time":"2019-07-03T18:01:09.346Z" "Worker job 15 (arrayBufferToStringBase64) succeeded in 24ms","time":"2019-07-03T18:01:09.347Z" "Worker job 16 (arrayBufferToStringBase64) succeeded in 8ms","time":"2019-07-03T18:01:09.352Z" "Worker job 17 (arrayBufferToStringBase64) succeeded in 8ms","time":"2019-07-03T18:01:09.353Z" "Worker job 18 (arrayBufferToStringBase64) succeeded in 8ms","time":"2019-07-03T18:01:09.354Z" "Worker job 19 (arrayBufferToStringBase64) succeeded in 10ms","time":"2019-07-03T18:01:09.356Z" "Worker job 20 (arrayBufferToStringBase64) succeeded in 10ms","time":"2019-07-03T18:01:09.356Z" "Worker job 21 (arrayBufferToStringBase64) succeeded in 7ms","time":"2019-07-03T18:01:09.358Z" "Worker job 22 (arrayBufferToStringBase64) succeeded in 10ms","time":"2019-07-03T18:01:09.362Z" "Worker job 23 (arrayBufferToStringBase64) succeeded in 10ms","time":"2019-07-03T18:01:09.362Z" "Worker job 24 (arrayBufferToStringBase64) succeeded in 11ms","time":"2019-07-03T18:01:09.363Z" "Worker job 25 (arrayBufferToStringBase64) succeeded in 12ms","time":"2019-07-03T18:01:09.363Z" "Worker job 26 (arrayBufferToStringBase64) succeeded in 4ms","time":"2019-07-03T18:01:09.364Z" "got request PUT /api/v1/queue/empty","time":"2019-07-03T18:01:09.386Z" "Worker job 27 (arrayBufferToStringBase64) succeeded in 14ms","time":"2019-07-03T18:01:09.387Z" "Worker job 28 (arrayBufferToStringBase64) succeeded in 15ms","time":"2019-07-03T18:01:09.387Z" "Worker job 29 (arrayBufferToStringBase64) succeeded in 16ms","time":"2019-07-03T18:01:09.388Z" "Worker job 30 (arrayBufferToStringBase64) succeeded in 16ms","time":"2019-07-03T18:01:09.388Z" "Worker job 31 (arrayBufferToStringBase64) succeeded in 15ms","time":"2019-07-03T18:01:09.390Z" "Worker job 32 (arrayBufferToStringBase64) succeeded in 22ms","time":"2019-07-03T18:01:09.398Z" "Worker job 33 (arrayBufferToStringBase64) succeeded in 24ms","time":"2019-07-03T18:01:09.399Z" "Saving new signed prekey 8921","time":"2019-07-03T18:01:09.419Z" "PUT https://textsecure-service.whispersystems.org/v2/keys/signed","time":"2019-07-03T18:01:09.420Z" "PUT https://textsecure-service.whispersystems.org/v2/keys/signed 204 Success","time":"2019-07-03T18:01:09.480Z" "SQL channel job 44 (removeMessage) succeeded in 338ms","time":"2019-07-03T18:01:09.514Z" "GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]085 200 Success","time":"2019-07-03T18:01:09.562Z" "Setting sealedSender to ENABLED for conversation +[REDACTED]085","time":"2019-07-03T18:01:09.569Z" "GET https://cdn.signal.org/profiles/qBZt1wTqGhhymx0cGf5GLw","time":"2019-07-03T18:01:09.577Z" "SQL channel job 45 (removeMessage) succeeded in 511ms","time":"2019-07-03T18:01:09.688Z" "SQL channel job 46 (removeMessage) succeeded in 664ms","time":"2019-07-03T18:01:09.845Z" "GET https://cdn.signal.org/profiles/qBZt1wTqGhhymx0cGf5GLw 200 Success","time":"2019-07-03T18:01:09.922Z" "SQL channel job 47 (removeMessage) succeeded in 822ms","time":"2019-07-03T18:01:10.005Z" "SQL channel job 48 (removeMessage) succeeded in 992ms","time":"2019-07-03T18:01:10.178Z" "SQL channel job 49 (removeMessage) succeeded in 1174ms","time":"2019-07-03T18:01:10.361Z" "SQL channel job 50 (removeMessage) succeeded in 1322ms","time":"2019-07-03T18:01:10.511Z" "Done preloading emoji images in 2793ms","time":"2019-07-03T18:01:10.640Z" "SQL channel job 51 (removeMessage) succeeded in 1516ms","time":"2019-07-03T18:01:10.705Z" "SQL channel job 52 (removeMessage) succeeded in 1681ms","time":"2019-07-03T18:01:10.873Z" "SQL channel job 53 (removeMessage) succeeded in 1871ms","time":"2019-07-03T18:01:11.063Z" "SQL channel job 54 (removeMessage) succeeded in 2049ms","time":"2019-07-03T18:01:11.245Z" "SQL channel job 55 (removeMessage) succeeded in 2213ms","time":"2019-07-03T18:01:11.411Z" "SQL channel job 56 (removeMessage) succeeded in 2410ms","time":"2019-07-03T18:01:11.609Z" "SQL channel job 57 (removeMessage) succeeded in 2566ms","time":"2019-07-03T18:01:11.767Z" "SQL channel job 58 (removeMessage) succeeded in 2754ms","time":"2019-07-03T18:01:11.957Z" "SQL channel job 59 (removeMessage) succeeded in 2936ms","time":"2019-07-03T18:01:12.139Z" "SQL channel job 60 (removeMessage) succeeded in 3142ms","time":"2019-07-03T18:01:12.346Z" "SQL channel job 61 (removeMessage) succeeded in 3340ms","time":"2019-07-03T18:01:12.545Z" "destroyExpiredMessages: complete","time":"2019-07-03T18:01:12.546Z" "SQL channel job 62 (saveUnprocessed) succeeded in 3336ms","time":"2019-07-03T18:01:12.653Z" "queueing envelope +[REDACTED]185.1 1562147425983 (fb2035c8-6d1b-443a-a4d9-86968028ce32)","time":"2019-07-03T18:01:12.654Z" "message from +[REDACTED]185.1 1562147425983 (fb2035c8-6d1b-443a-a4d9-86968028ce32)","time":"2019-07-03T18:01:12.654Z" "SQL channel job 63 (saveUnprocessed) succeeded in 3460ms","time":"2019-07-03T18:01:12.777Z" "queueing envelope +[REDACTED]185.1 1562147432528 (798c5e42-565d-4375-a9c2-d673329c722d)","time":"2019-07-03T18:01:12.778Z" "SQL channel job 64 (saveUnprocessed) succeeded in 3583ms","time":"2019-07-03T18:01:12.902Z" "queueing envelope +[REDACTED]185.1 1562147439907 (d05f5151-db30-4333-bd56-70228cc7e640)","time":"2019-07-03T18:01:12.902Z" "SQL channel job 65 (saveUnprocessed) succeeded in 3713ms","time":"2019-07-03T18:01:13.036Z" "queueing envelope +[REDACTED]085.1 1562147857132 (feb3e7c1-8dc0-40af-a99a-ddbf9feb788c)","time":"2019-07-03T18:01:13.036Z" "SQL channel job 66 (saveUnprocessed) succeeded in 3845ms","time":"2019-07-03T18:01:13.169Z" "queueing envelope +[REDACTED]185.1 1562147879440 (7b27cb51-2433-422b-8e03-176911724a33)","time":"2019-07-03T18:01:13.169Z" "SQL channel job 67 (saveUnprocessed) succeeded in 3953ms","time":"2019-07-03T18:01:13.277Z" "queueing envelope +[REDACTED]185.1 1562147881440 (4cc4edfe-27d0-4931-9cbe-bf9a3b456f9f)","time":"2019-07-03T18:01:13.277Z" "SQL channel job 68 (saveUnprocessed) succeeded in 4067ms","time":"2019-07-03T18:01:13.393Z" "queueing envelope +[REDACTED]085.1 1562147882277 (5a79e6a4-117c-4bf4-a698-e38c035d33e6)","time":"2019-07-03T18:01:13.394Z" "SQL channel job 69 (saveUnprocessed) succeeded in 4190ms","time":"2019-07-03T18:01:13.517Z" "queueing envelope +[REDACTED]185.2 1562155809515 (4d24b89c-7e98-4b15-81ad-679f4bfc8550)","time":"2019-07-03T18:01:13.517Z" "SQL channel job 70 (createOrUpdateItem) succeeded in 4305ms","time":"2019-07-03T18:01:13.633Z" "SQL channel job 71 (saveUnprocessed) succeeded in 4400ms","time":"2019-07-03T18:01:13.733Z" "queueing envelope +[REDACTED]185.2 1562155810002 (30c7990b-9f42-4d75-99b6-87c55c4310ae)","time":"2019-07-03T18:01:13.733Z" "SQL channel job 72 (saveUnprocessed) succeeded in 4524ms","time":"2019-07-03T18:01:13.857Z" "queueing envelope +[REDACTED]185.2 1562155810220 (736b126d-b2a4-4feb-aa9b-1d6510e7d050)","time":"2019-07-03T18:01:13.858Z" "SQL channel job 73 (saveUnprocessed) succeeded in 4631ms","time":"2019-07-03T18:01:13.965Z" "queueing envelope +[REDACTED]185.2 1562155813069 (4412ffc2-e0e9-4095-a173-14ee7ac3b7f7)","time":"2019-07-03T18:01:13.966Z" "SQL channel job 74 (saveUnprocessed) succeeded in 4739ms","time":"2019-07-03T18:01:14.073Z" "queueing envelope +[REDACTED]185.1 1562156098380 (f66508ab-5aa2-4fc7-b087-7a58174c3354)","time":"2019-07-03T18:01:14.073Z" "SQL channel job 75 (createOrUpdateItem) succeeded in 4861ms","time":"2019-07-03T18:01:14.197Z" "SQL channel job 76 (saveUnprocessed) succeeded in 5000ms","time":"2019-07-03T18:01:14.347Z" "queueing envelope +[REDACTED]085.1 1562156101782 (03a3b16d-3ede-4991-93ef-81cd6b0716e5)","time":"2019-07-03T18:01:14.347Z" "SQL channel job 77 (saveUnprocessed) succeeded in 5117ms","time":"2019-07-03T18:01:14.463Z" "queueing envelope +[REDACTED]185.2 1562156107404 (5464db22-ba9b-47eb-927d-6c353d7cf78c)","time":"2019-07-03T18:01:14.463Z" "SQL channel job 78 (saveUnprocessed) succeeded in 5221ms","time":"2019-07-03T18:01:14.572Z" "queueing envelope +[REDACTED]185.2 1562156111952 (4cdf19f9-f0af-4361-bfd7-6dd39ae9d01b)","time":"2019-07-03T18:01:14.573Z" "SQL channel job 79 (saveUnprocessed) succeeded in 5344ms","time":"2019-07-03T18:01:14.697Z" "queueing envelope +[REDACTED]185.2 1562156117862 (43019f76-0baa-4656-b616-6d1cd83dbc4e)","time":"2019-07-03T18:01:14.697Z" "SQL channel job 80 (saveUnprocessed) succeeded in 5483ms","time":"2019-07-03T18:01:14.838Z" MSG "queueing envelope +[REDACTED]185.1 1562162448520 (4aad4c46-d28d-4095-9921-2d149b26b087)","time":"2019-07-03T18:01:14.838Z" "SQL channel job 81 (saveUnprocessed) succeeded in 5607ms","time":"2019-07-03T18:01:14.962Z" "queueing envelope +[REDACTED]085.1 1562162473757 (50200067-a5e6-4c33-a560-989d2c35ac04)","time":"2019-07-03T18:01:14.963Z" "SQL channel job 82 (saveUnprocessed) succeeded in 5747ms","time":"2019-07-03T18:01:15.103Z" "queueing envelope +[REDACTED]185.1 1562162501841 (c8c8b1f6-a1d4-477a-a82a-cab0e7609579)","time":"2019-07-03T18:01:15.103Z" "SQL channel job 83 (saveUnprocessed) succeeded in 5870ms","time":"2019-07-03T18:01:15.227Z" "queueing envelope +[REDACTED]085.1 1562162509536 (19ad55c7-2361-414b-ad31-4ae8d123d449)","time":"2019-07-03T18:01:15.228Z" "SQL channel job 84 (saveUnprocessed) succeeded in 5999ms","time":"2019-07-03T18:01:15.360Z" "queueing envelope +[REDACTED]185.1 1562162545099 (f967e6a5-d9f1-4a87-b97a-2c1be45b366b)","time":"2019-07-03T18:01:15.361Z" "SQL channel job 85 (saveUnprocessed) succeeded in 6115ms","time":"2019-07-03T18:01:15.476Z" "queueing envelope +[REDACTED]185.1 1562162545287 (296ba93c-2348-4181-b9e1-2d06037e34e7)","time":"2019-07-03T18:01:15.477Z" "SQL channel job 86 (saveUnprocessed) succeeded in 6247ms","time":"2019-07-03T18:01:15.609Z" "queueing envelope +[REDACTED]185.1 1562162545490 (956f1715-19ca-4093-b5ca-266552515a29)","time":"2019-07-03T18:01:15.609Z" "SQL channel job 87 (saveUnprocessed) succeeded in 6361ms","time":"2019-07-03T18:01:15.725Z" "queueing envelope +[REDACTED]185.1 1562162545697 (b987d136-4a83-4ed6-b81a-78341dc683a9)","time":"2019-07-03T18:01:15.725Z" "SQL channel job 88 (saveUnprocessed) succeeded in 6486ms","time":"2019-07-03T18:01:15.850Z" "queueing envelope +[REDACTED]185.1 1562162545884 (e9cbcb1b-fc61-4a18-a8ac-438f13b0ebd9)","time":"2019-07-03T18:01:15.850Z" "SQL channel job 89 (saveUnprocessed) succeeded in 6630ms","time":"2019-07-03T18:01:16.003Z" "queueing envelope +[REDACTED]185.1 1562162546067 (489837b3-d9e1-44a7-82c5-aaa618eab56f)","time":"2019-07-03T18:01:16.004Z" "SQL channel job 90 (saveUnprocessed) succeeded in 6783ms","time":"2019-07-03T18:01:16.158Z" "queueing envelope +[REDACTED]185.1 1562162546247 (6419cfd6-3688-4bde-8967-d6b1de699c6c)","time":"2019-07-03T18:01:16.158Z" "SQL channel job 91 (saveUnprocessed) succeeded in 6915ms","time":"2019-07-03T18:01:16.291Z" "queueing envelope +[REDACTED]185.1 1562163307740 (6d0f1994-3d73-4ce6-bd41-f7a51ed3d2df)","time":"2019-07-03T18:01:16.291Z" "SQL channel job 92 (saveUnprocessed) succeeded in 7047ms","time":"2019-07-03T18:01:16.423Z" "queueing envelope +[REDACTED]185.1 1562174857771 (844e596d-cf2d-4bb9-8690-331460928412)","time":"2019-07-03T18:01:16.424Z" "SQL channel job 93 (saveUnprocessed) succeeded in 7185ms","time":"2019-07-03T18:01:16.573Z" "queueing envelope +[REDACTED]085.1 1562174864522 (e0ee892e-17e0-4a02-9dea-d511910d2e18)","time":"2019-07-03T18:01:16.573Z" "SQL channel job 94 (saveUnprocessed) succeeded in 7318ms","time":"2019-07-03T18:01:16.714Z" "queueing envelope +[REDACTED]185.1 1562176715757 (9c3f82e3-380e-4d20-961e-83a4bf43bb9e)","time":"2019-07-03T18:01:16.715Z" "SQL channel job 95 (saveUnprocessed) succeeded in 7449ms","time":"2019-07-03T18:01:16.846Z" "queueing envelope +[REDACTED]185.1 1562176717058 (1d641278-3306-427a-b1c1-21d5de439a12)","time":"2019-07-03T18:01:16.847Z" "SQL channel job 96 (createOrUpdateItem) succeeded in 7544ms","time":"2019-07-03T18:01:16.963Z" "SQL channel job 97 (createOrUpdateSignedPreKey) succeeded in 7667ms","time":"2019-07-03T18:01:17.087Z" "Confirming new signed prekey 8921","time":"2019-07-03T18:01:17.087Z" "SQL channel job 98 (getMessagesByConversation) succeeded in 7573ms","time":"2019-07-03T18:01:17.088Z" "SQL channel job 99 (getMessagesByConversation) succeeded in 7401ms","time":"2019-07-03T18:01:17.090Z" "SQL channel job 100 (getMessagesByConversation) succeeded in 7231ms","time":"2019-07-03T18:01:17.091Z" "SQL channel job 101 (getMessagesByConversation) succeeded in 7076ms","time":"2019-07-03T18:01:17.092Z" "SQL channel job 102 (getMessagesByConversation) succeeded in 6906ms","time":"2019-07-03T18:01:17.093Z" "SQL channel job 103 (getMessagesByConversation) succeeded in 6732ms","time":"2019-07-03T18:01:17.094Z" "SQL channel job 104 (getMessagesByConversation) succeeded in 6584ms","time":"2019-07-03T18:01:17.095Z" "SQL channel job 105 (getMessagesByConversation) succeeded in 6389ms","time":"2019-07-03T18:01:17.096Z" "SQL channel job 106 (getMessagesByConversation) succeeded in 6224ms","time":"2019-07-03T18:01:17.097Z" "SQL channel job 107 (getMessagesByConversation) succeeded in 6033ms","time":"2019-07-03T18:01:17.098Z" "SQL channel job 108 (getMessagesByConversation) succeeded in 5853ms","time":"2019-07-03T18:01:17.099Z" "SQL channel job 109 (getMessagesByConversation) succeeded in 5688ms","time":"2019-07-03T18:01:17.100Z" "SQL channel job 110 (getMessagesByConversation) succeeded in 5492ms","time":"2019-07-03T18:01:17.101Z" "SQL channel job 111 (getMessagesByConversation) succeeded in 5336ms","time":"2019-07-03T18:01:17.103Z" "SQL channel job 112 (getMessagesByConversation) succeeded in 5147ms","time":"2019-07-03T18:01:17.105Z" "SQL channel job 113 (getMessagesByConversation) succeeded in 4967ms","time":"2019-07-03T18:01:17.106Z" "SQL channel job 114 (getMessagesByConversation) succeeded in 4761ms","time":"2019-07-03T18:01:17.108Z" "SQL channel job 115 (getMessagesByConversation) succeeded in 4562ms","time":"2019-07-03T18:01:17.109Z" "SQL channel job 116 (getNextExpiringMessage) succeeded in 4563ms","time":"2019-07-03T18:01:17.110Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:17.111Z" "SQL channel job 117 (getItemById) succeeded in 4454ms","time":"2019-07-03T18:01:17.111Z" "SQL channel job 118 (removeItemById) succeeded in 25ms","time":"2019-07-03T18:01:17.112Z" "SQL channel job 119 (createOrUpdateSignedPreKey) succeeded in 132ms","time":"2019-07-03T18:01:17.221Z" "Most recent signed key: 8921","time":"2019-07-03T18:01:17.222Z" "Most recent confirmed signed key: 8921","time":"2019-07-03T18:01:17.222Z" "Total signed key count: 6 - 6 confirmed","time":"2019-07-03T18:01:17.222Z" "Removing confirmed signed prekey: 8916 with timestamp: 1561409997144","time":"2019-07-03T18:01:17.222Z" "SQL channel job 120 (createOrUpdateSession) succeeded in 267ms","time":"2019-07-03T18:01:17.386Z" "data message from +[REDACTED]185.1 1562147425983 (fb2035c8-6d1b-443a-a4d9-86968028ce32)","time":"2019-07-03T18:01:17.388Z" "message from +[REDACTED]185.1 1562147432528 (798c5e42-565d-4375-a9c2-d673329c722d)","time":"2019-07-03T18:01:17.391Z" "SQL channel job 121 (removeSignedPreKeyById) succeeded in 271ms","time":"2019-07-03T18:01:17.494Z" "SQL channel job 122 (getUnprocessedById) succeeded in 107ms","time":"2019-07-03T18:01:17.494Z" "Worker job 34 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:17.495Z" "SQL channel job 123 (getMessageBySender) succeeded in 105ms","time":"2019-07-03T18:01:17.496Z" "Starting handleDataMessage for message +[REDACTED]185.1 1562147425983 in conversation +[REDACTED]185","time":"2019-07-03T18:01:17.497Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:17.514Z" "SQL channel job 124 (getItemById) succeeded in 144ms","time":"2019-07-03T18:01:17.537Z" "SQL channel job 125 (updateUnprocessedWithData) succeeded in 117ms","time":"2019-07-03T18:01:17.613Z" "SQL channel job 126 (saveMessage) succeeded in 226ms","time":"2019-07-03T18:01:17.763Z" "SQL channel job 127 (createOrUpdateSession) succeeded in 295ms","time":"2019-07-03T18:01:17.909Z" "data message from +[REDACTED]185.1 1562147432528 (798c5e42-565d-4375-a9c2-d673329c722d)","time":"2019-07-03T18:01:17.909Z" "message from +[REDACTED]185.1 1562147439907 (d05f5151-db30-4333-bd56-70228cc7e640)","time":"2019-07-03T18:01:17.910Z" "SQL channel job 128 (getNextExpiringMessage) succeeded in 148ms","time":"2019-07-03T18:01:17.910Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:17.912Z" "SQL channel job 129 (getConversationById) succeeded in 147ms","time":"2019-07-03T18:01:17.912Z" "Worker job 35 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:17.915Z" "SQL channel job 131 (updateConversation) succeeded in 86ms","time":"2019-07-03T18:01:18.000Z" "SQL channel job 132 (updateUnprocessedWithData) succeeded in 184ms","time":"2019-07-03T18:01:18.100Z" "SQL channel job 133 (getItemById) succeeded in 184ms","time":"2019-07-03T18:01:18.100Z" "SQL channel job 134 (getMessagesByConversation) succeeded in 100ms","time":"2019-07-03T18:01:18.101Z" "SQL channel job 135 (getMessageById) succeeded in 112ms","time":"2019-07-03T18:01:18.113Z" "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562147425983}","time":"2019-07-03T18:01:18.165Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:18.168Z" "SQL channel job 137 (createOrUpdateSession) succeeded in 162ms","time":"2019-07-03T18:01:18.274Z" "data message from +[REDACTED]185.1 1562147439907 (d05f5151-db30-4333-bd56-70228cc7e640)","time":"2019-07-03T18:01:18.274Z" "message from +[REDACTED]085.1 1562147857132 (feb3e7c1-8dc0-40af-a99a-ddbf9feb788c)","time":"2019-07-03T18:01:18.275Z" "New remote ephemeral key","time":"2019-07-03T18:01:18.275Z" "SQL channel job 138 (updateConversation) succeeded in 255ms","time":"2019-07-03T18:01:18.373Z" "SQL channel job 139 (removeUnprocessed) succeeded in 330ms","time":"2019-07-03T18:01:18.498Z" "SQL channel job 140 (getMessageBySender) succeeded in 330ms","time":"2019-07-03T18:01:18.498Z" "Starting handleDataMessage for message +[REDACTED]185.1 1562147432528 in conversation +[REDACTED]185","time":"2019-07-03T18:01:18.499Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:18.511Z" "SQL channel job 141 (getUnprocessedById) succeeded in 256ms","time":"2019-07-03T18:01:18.530Z" "SQL channel job 142 (getItemById) succeeded in 206ms","time":"2019-07-03T18:01:18.530Z" "Worker job 36 (arrayBufferToStringBase64) succeeded in 2ms","time":"2019-07-03T18:01:18.532Z" "Deleting chain closed at 1562134317349","time":"2019-07-03T18:01:18.535Z" "SQL channel job 143 (saveMessage) succeeded in 168ms","time":"2019-07-03T18:01:18.697Z" "SQL channel job 144 (updateUnprocessedWithData) succeeded in 298ms","time":"2019-07-03T18:01:18.830Z" "SQL channel job 145 (createOrUpdateSession) succeeded in 420ms","time":"2019-07-03T18:01:18.954Z" "read messages from +[REDACTED]085.1 1562147857132 (feb3e7c1-8dc0-40af-a99a-ddbf9feb788c)","time":"2019-07-03T18:01:18.955Z" "message from +[REDACTED]185.1 1562147879440 (7b27cb51-2433-422b-8e03-176911724a33)","time":"2019-07-03T18:01:18.955Z" "SQL channel job 146 (getConversationById) succeeded in 258ms","time":"2019-07-03T18:01:18.956Z" "SQL channel job 147 (getNextExpiringMessage) succeeded in 194ms","time":"2019-07-03T18:01:18.958Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:18.959Z" "Worker job 37 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:18.960Z" "SQL channel job 149 (updateConversation) succeeded in 113ms","time":"2019-07-03T18:01:19.070Z" "SQL channel job 150 (updateUnprocessedWithData) succeeded in 193ms","time":"2019-07-03T18:01:19.153Z" "SQL channel job 151 (getItemById) succeeded in 192ms","time":"2019-07-03T18:01:19.153Z" "SQL channel job 152 (getMessagesByConversation) succeeded in 85ms","time":"2019-07-03T18:01:19.155Z" "SQL channel job 153 (getMessageById) succeeded in 100ms","time":"2019-07-03T18:01:19.170Z" "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562147432528}","time":"2019-07-03T18:01:19.182Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:19.183Z" "SQL channel job 155 (createOrUpdateSession) succeeded in 158ms","time":"2019-07-03T18:01:19.327Z" "message from +[REDACTED]185.1 1562147881440 (4cc4edfe-27d0-4931-9cbe-bf9a3b456f9f)","time":"2019-07-03T18:01:19.328Z" "SQL channel job 156 (updateConversation) succeeded in 271ms","time":"2019-07-03T18:01:19.444Z" "SQL channel job 157 (removeUnprocessed) succeeded in 419ms","time":"2019-07-03T18:01:19.602Z" "SQL channel job 158 (getMessageBySender) succeeded in 419ms","time":"2019-07-03T18:01:19.603Z" "Starting handleDataMessage for message +[REDACTED]185.1 1562147439907 in conversation +[REDACTED]185","time":"2019-07-03T18:01:19.604Z" "SQL channel job 159 (getUnprocessedById) succeeded in 276ms","time":"2019-07-03T18:01:19.604Z" "Worker job 38 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:19.605Z" "SQL channel job 160 (getItemById) succeeded in 276ms","time":"2019-07-03T18:01:19.605Z" "SQL channel job 161 (createOrUpdateItem) succeeded in 305ms","time":"2019-07-03T18:01:19.817Z" "SQL channel job 162 (getMessagesBySentAt) succeeded in 216ms","time":"2019-07-03T18:01:19.819Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:19.831Z" "SQL channel job 163 (updateUnprocessedWithData) succeeded in 759ms","time":"2019-07-03T18:01:20.363Z" "SQL channel job 164 (createOrUpdateSession) succeeded in 963ms","time":"2019-07-03T18:01:20.569Z" "message from +[REDACTED]085.1 1562147882277 (5a79e6a4-117c-4bf4-a698-e38c035d33e6)","time":"2019-07-03T18:01:20.570Z" "SQL channel job 165 (saveMessage) succeeded in 912ms","time":"2019-07-03T18:01:20.759Z" "SQL channel job 166 (getUnprocessedById) succeeded in 191ms","time":"2019-07-03T18:01:20.760Z" "SQL channel job 167 (getItemById) succeeded in 190ms","time":"2019-07-03T18:01:20.761Z" "Worker job 39 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:20.761Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:20.762Z" "SQL channel job 170 (updateUnprocessedWithData) succeeded in 131ms","time":"2019-07-03T18:01:20.892Z" "SQL channel job 171 (updateConversation) succeeded in 222ms","time":"2019-07-03T18:01:20.984Z" "SQL channel job 172 (createOrUpdateSession) succeeded in 336ms","time":"2019-07-03T18:01:21.099Z" "sent message to +[REDACTED]185 1562147882277 from +[REDACTED]085.1 1562147882277 (5a79e6a4-117c-4bf4-a698-e38c035d33e6)","time":"2019-07-03T18:01:21.100Z" "message from +[REDACTED]185.2 1562155809515 (4d24b89c-7e98-4b15-81ad-679f4bfc8550)","time":"2019-07-03T18:01:21.101Z" "SQL channel job 173 (createOrUpdateItem) succeeded in 385ms","time":"2019-07-03T18:01:21.216Z" "SQL channel job 174 (getMessagesByConversation) succeeded in 233ms","time":"2019-07-03T18:01:21.217Z" "SQL channel job 175 (getMessageById) succeeded in 241ms","time":"2019-07-03T18:01:21.227Z" "SQL channel job 176 (getUnprocessedById) succeeded in 129ms","time":"2019-07-03T18:01:21.229Z" "SQL channel job 177 (getItemById) succeeded in 127ms","time":"2019-07-03T18:01:21.230Z" "Worker job 40 (arrayBufferToStringBase64) succeeded in 4ms","time":"2019-07-03T18:01:21.233Z" "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562147439907}","time":"2019-07-03T18:01:21.235Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:21.235Z" "read sync +[REDACTED]185 1562147425983","time":"2019-07-03T18:01:21.236Z" "SQL channel job 179 (updateConversation) succeeded in 100ms","time":"2019-07-03T18:01:21.332Z" "SQL channel job 180 (updateUnprocessedWithData) succeeded in 182ms","time":"2019-07-03T18:01:21.415Z" "SQL channel job 181 (removeUnprocessed) succeeded in 303ms","time":"2019-07-03T18:01:21.540Z" "SQL channel job 182 (getMessagesBySentAt) succeeded in 304ms","time":"2019-07-03T18:01:21.540Z" "Remove notification","time":"2019-07-03T18:01:21.542Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562147425983,\"wasMessageFound\":true,\"wasNotificationFound\":true,\"wasNotificationRemoved\":true}","time":"2019-07-03T18:01:21.543Z" "SQL channel job 183 (createOrUpdateSession) succeeded in 493ms","time":"2019-07-03T18:01:21.731Z" "message from +[REDACTED]185.2 1562155810002 (30c7990b-9f42-4d75-99b6-87c55c4310ae)","time":"2019-07-03T18:01:21.732Z" "New remote ephemeral key","time":"2019-07-03T18:01:21.732Z" "SQL channel job 184 (saveMessage) succeeded in 327ms","time":"2019-07-03T18:01:21.872Z" "Set message expiration {\"expiresAt\":1562752657132,\"sentAt\":1562147425983}","time":"2019-07-03T18:01:21.872Z" "SQL channel job 185 (saveMessage) succeeded in 491ms","time":"2019-07-03T18:01:22.038Z" "Remove notification","time":"2019-07-03T18:01:22.039Z" "Remove notification","time":"2019-07-03T18:01:22.039Z" "read sync +[REDACTED]185 1562147432528","time":"2019-07-03T18:01:22.039Z" "SQL channel job 186 (getUnprocessedById) succeeded in 308ms","time":"2019-07-03T18:01:22.040Z" "SQL channel job 187 (getItemById) succeeded in 274ms","time":"2019-07-03T18:01:22.040Z" "SQL channel job 188 (getNextExpiringMessage) succeeded in 169ms","time":"2019-07-03T18:01:22.040Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:22.041Z" "Worker job 41 (arrayBufferToStringBase64) succeeded in 2ms","time":"2019-07-03T18:01:22.042Z" "Deleting chain closed at 1562088532501","time":"2019-07-03T18:01:22.042Z" "SQL channel job 189 (removeUnprocessed) succeeded in 148ms","time":"2019-07-03T18:01:22.187Z" "SQL channel job 190 (getUnreadByConversation) succeeded in 153ms","time":"2019-07-03T18:01:22.193Z" "SQL channel job 191 (getMessagesBySentAt) succeeded in 162ms","time":"2019-07-03T18:01:22.201Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562147432528,\"wasMessageFound\":true,\"wasNotificationFound\":false,\"wasNotificationRemoved\":false}","time":"2019-07-03T18:01:22.202Z" "SQL channel job 192 (updateUnprocessedWithData) succeeded in 261ms","time":"2019-07-03T18:01:22.303Z" "SQL channel job 193 (createOrUpdateSession) succeeded in 393ms","time":"2019-07-03T18:01:22.436Z" "message from +[REDACTED]185.2 1562155810220 (736b126d-b2a4-4feb-aa9b-1d6510e7d050)","time":"2019-07-03T18:01:22.436Z" "SQL channel job 194 (getConversationById) succeeded in 236ms","time":"2019-07-03T18:01:22.437Z" "SQL channel job 195 (saveMessage) succeeded in 440ms","time":"2019-07-03T18:01:22.643Z" "Set message expiration {\"expiresAt\":1562752657132,\"sentAt\":1562147432528}","time":"2019-07-03T18:01:22.643Z" "SQL channel job 196 (saveMessage) succeeded in 629ms","time":"2019-07-03T18:01:22.833Z" "read sync +[REDACTED]185 1562147439907","time":"2019-07-03T18:01:22.833Z" "SQL channel job 197 (getUnprocessedById) succeeded in 398ms","time":"2019-07-03T18:01:22.833Z" "Worker job 42 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:22.834Z" "SQL channel job 198 (updateConversation) succeeded in 495ms","time":"2019-07-03T18:01:22.933Z" "SQL channel job 199 (getItemById) succeeded in 494ms","time":"2019-07-03T18:01:22.933Z" "SQL channel job 200 (removeUnprocessed) succeeded in 101ms","time":"2019-07-03T18:01:22.935Z" "SQL channel job 201 (getMessagesBySentAt) succeeded in 101ms","time":"2019-07-03T18:01:22.935Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562147439907,\"wasMessageFound\":true,\"wasNotificationFound\":false,\"wasNotificationRemoved\":false}","time":"2019-07-03T18:01:22.936Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:23.040Z" "SQL channel job 202 (updateUnprocessedWithData) succeeded in 223ms","time":"2019-07-03T18:01:23.058Z" "SQL channel job 203 (getNextExpiringMessage) succeeded in 185ms","time":"2019-07-03T18:01:23.058Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:23.060Z" "SQL channel job 204 (getUnreadByConversation) succeeded in 128ms","time":"2019-07-03T18:01:23.060Z" "SQL channel job 205 (createOrUpdateSession) succeeded in 281ms","time":"2019-07-03T18:01:23.214Z" "message from +[REDACTED]185.2 1562155813069 (4412ffc2-e0e9-4095-a173-14ee7ac3b7f7)","time":"2019-07-03T18:01:23.215Z" "SQL channel job 206 (saveMessage) succeeded in 452ms","time":"2019-07-03T18:01:23.388Z" "Set message expiration {\"expiresAt\":1562752657132,\"sentAt\":1562147439907}","time":"2019-07-03T18:01:23.388Z" "SQL channel job 207 (saveMessage) succeeded in 608ms","time":"2019-07-03T18:01:23.545Z" "delivery receipt from +[REDACTED]185.1 1562147882277","time":"2019-07-03T18:01:23.546Z" "SQL channel job 208 (getConversationById) succeeded in 478ms","time":"2019-07-03T18:01:23.546Z" "SQL channel job 209 (getUnprocessedById) succeeded in 333ms","time":"2019-07-03T18:01:23.547Z" "SQL channel job 210 (getItemById) succeeded in 332ms","time":"2019-07-03T18:01:23.548Z" "Worker job 43 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:23.548Z" "SQL channel job 212 (removeUnprocessed) succeeded in 117ms","time":"2019-07-03T18:01:23.663Z" "SQL channel job 213 (getMessagesBySentAt) succeeded in 118ms","time":"2019-07-03T18:01:23.663Z" "No message for delivery receipt +[REDACTED]185 1562147882277","time":"2019-07-03T18:01:23.663Z" "read receipt +[REDACTED]185 1562147882277","time":"2019-07-03T18:01:23.664Z" "SQL channel job 214 (updateConversation) succeeded in 207ms","time":"2019-07-03T18:01:23.754Z" "SQL channel job 215 (updateUnprocessedWithData) succeeded in 315ms","time":"2019-07-03T18:01:23.863Z" "SQL channel job 216 (createOrUpdateSession) succeeded in 628ms","time":"2019-07-03T18:01:24.177Z" "message from +[REDACTED]185.1 1562156098380 (f66508ab-5aa2-4fc7-b087-7a58174c3354)","time":"2019-07-03T18:01:24.177Z" "SQL channel job 217 (getMessagesBySentAt) succeeded in 514ms","time":"2019-07-03T18:01:24.178Z" "No message for read receipt +[REDACTED]185 1562147882277","time":"2019-07-03T18:01:24.179Z" "SQL channel job 218 (getUnreadByConversation) succeeded in 426ms","time":"2019-07-03T18:01:24.181Z" "SQL channel job 219 (getNextExpiringMessage) succeeded in 320ms","time":"2019-07-03T18:01:24.190Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:24.191Z" "SQL channel job 220 (createOrUpdateItem) succeeded in 272ms","time":"2019-07-03T18:01:24.334Z" "SQL channel job 221 (getUnprocessedById) succeeded in 160ms","time":"2019-07-03T18:01:24.337Z" "SQL channel job 222 (getMessageBySender) succeeded in 156ms","time":"2019-07-03T18:01:24.337Z" "SQL channel job 223 (getConversationById) succeeded in 147ms","time":"2019-07-03T18:01:24.337Z" "SQL channel job 224 (getItemById) succeeded in 146ms","time":"2019-07-03T18:01:24.338Z" "Worker job 44 (arrayBufferToStringBase64) succeeded in 2ms","time":"2019-07-03T18:01:24.339Z" "SQL channel job 225 (updateConversation) succeeded in 113ms","time":"2019-07-03T18:01:24.450Z" "Starting handleDataMessage for message +[REDACTED]085.1 1562147882277 in conversation +[REDACTED]185","time":"2019-07-03T18:01:24.450Z" "Set message expiration {\"expiresAt\":1562752683300,\"sentAt\":1562147882277}","time":"2019-07-03T18:01:24.459Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:24.460Z" "Found early read receipts for message","time":"2019-07-03T18:01:24.460Z" "SQL channel job 226 (updateUnprocessedWithData) succeeded in 235ms","time":"2019-07-03T18:01:24.574Z" "SQL channel job 227 (createOrUpdateSession) succeeded in 367ms","time":"2019-07-03T18:01:24.707Z" "message from +[REDACTED]085.1 1562156101782 (03a3b16d-3ede-4991-93ef-81cd6b0716e5)","time":"2019-07-03T18:01:24.707Z" "SQL channel job 228 (removeUnprocessed) succeeded in 380ms","time":"2019-07-03T18:01:24.840Z" "SQL channel job 229 (saveMessage) succeeded in 528ms","time":"2019-07-03T18:01:24.997Z" "SQL channel job 230 (getUnprocessedById) succeeded in 291ms","time":"2019-07-03T18:01:24.998Z" "SQL channel job 231 (getItemById) succeeded in 289ms","time":"2019-07-03T18:01:24.999Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:25.002Z" "Worker job 45 (arrayBufferToStringBase64) succeeded in 4ms","time":"2019-07-03T18:01:25.002Z" "SQL channel job 234 (updateUnprocessedWithData) succeeded in 87ms","time":"2019-07-03T18:01:25.089Z" "SQL channel job 235 (updateConversation) succeeded in 177ms","time":"2019-07-03T18:01:25.180Z" "SQL channel job 236 (createOrUpdateSession) succeeded in 307ms","time":"2019-07-03T18:01:25.312Z" "sent message to +[REDACTED]185 1562156101782 from +[REDACTED]085.1 1562156101782 (03a3b16d-3ede-4991-93ef-81cd6b0716e5)","time":"2019-07-03T18:01:25.313Z" "message from +[REDACTED]185.2 1562156107404 (5464db22-ba9b-47eb-927d-6c353d7cf78c)","time":"2019-07-03T18:01:25.313Z" "SQL channel job 237 (getMessagesByConversation) succeeded in 133ms","time":"2019-07-03T18:01:25.313Z" "SQL channel job 238 (getMessageById) succeeded in 140ms","time":"2019-07-03T18:01:25.321Z" "delivery receipt from +[REDACTED]185.2 1562147350111","time":"2019-07-03T18:01:25.322Z" "SQL channel job 239 (createOrUpdateItem) succeeded in 238ms","time":"2019-07-03T18:01:25.420Z" "SQL channel job 240 (getUnprocessedById) succeeded in 111ms","time":"2019-07-03T18:01:25.422Z" "SQL channel job 241 (getConversationById) succeeded in 102ms","time":"2019-07-03T18:01:25.423Z" "Worker job 46 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:25.424Z" "SQL channel job 242 (removeUnprocessed) succeeded in 249ms","time":"2019-07-03T18:01:25.571Z" "SQL channel job 243 (removeUnprocessed) succeeded in 374ms","time":"2019-07-03T18:01:25.696Z" "SQL channel job 244 (getMessagesBySentAt) succeeded in 374ms","time":"2019-07-03T18:01:25.697Z" "SQL channel job 245 (getItemById) succeeded in 376ms","time":"2019-07-03T18:01:25.700Z" "SQL channel job 246 (updateConversation) succeeded in 389ms","time":"2019-07-03T18:01:25.812Z" "SQL channel job 247 (updateUnprocessedWithData) succeeded in 496ms","time":"2019-07-03T18:01:25.920Z" "SQL channel job 248 (saveMessage) succeeded in 378ms","time":"2019-07-03T18:01:26.077Z" "delivery receipt from +[REDACTED]185.2 1562147379439","time":"2019-07-03T18:01:26.078Z" "SQL channel job 249 (createOrUpdateSession) succeeded in 500ms","time":"2019-07-03T18:01:26.201Z" "message from +[REDACTED]185.2 1562156111952 (4cdf19f9-f0af-4361-bfd7-6dd39ae9d01b)","time":"2019-07-03T18:01:26.201Z" "SQL channel job 250 (getNextExpiringMessage) succeeded in 124ms","time":"2019-07-03T18:01:26.202Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:26.203Z" "SQL channel job 251 (getMessagesByConversation) succeeded in 126ms","time":"2019-07-03T18:01:26.203Z" "SQL channel job 252 (removeUnprocessed) succeeded in 256ms","time":"2019-07-03T18:01:26.334Z" "SQL channel job 253 (getMessagesBySentAt) succeeded in 256ms","time":"2019-07-03T18:01:26.335Z" "SQL channel job 254 (getUnprocessedById) succeeded in 136ms","time":"2019-07-03T18:01:26.338Z" "SQL channel job 255 (getItemById) succeeded in 133ms","time":"2019-07-03T18:01:26.338Z" "Worker job 47 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:26.338Z" "SQL channel job 256 (saveMessage) succeeded in 179ms","time":"2019-07-03T18:01:26.516Z" "onProgress: Message count is 10","time":"2019-07-03T18:01:26.517Z" "delivery receipt from +[REDACTED]185.2 1562147407530","time":"2019-07-03T18:01:26.518Z" "SQL channel job 257 (updateUnprocessedWithData) succeeded in 278ms","time":"2019-07-03T18:01:26.616Z" "SQL channel job 258 (createOrUpdateSession) succeeded in 402ms","time":"2019-07-03T18:01:26.740Z" "message from +[REDACTED]185.2 1562156117862 (43019f76-0baa-4656-b616-6d1cd83dbc4e)","time":"2019-07-03T18:01:26.740Z" "SQL channel job 259 (getMessagesByConversation) succeeded in 224ms","time":"2019-07-03T18:01:26.741Z" "SQL channel job 260 (removeUnprocessed) succeeded in 355ms","time":"2019-07-03T18:01:26.872Z" "SQL channel job 261 (getMessagesBySentAt) succeeded in 355ms","time":"2019-07-03T18:01:26.873Z" "SQL channel job 262 (getUnprocessedById) succeeded in 135ms","time":"2019-07-03T18:01:26.875Z" "SQL channel job 263 (getItemById) succeeded in 132ms","time":"2019-07-03T18:01:26.875Z" "Worker job 48 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:26.876Z" "SQL channel job 264 (saveMessage) succeeded in 196ms","time":"2019-07-03T18:01:27.071Z" "delivery receipt from +[REDACTED]185.2 1562147882277","time":"2019-07-03T18:01:27.072Z" "SQL channel job 265 (updateUnprocessedWithData) succeeded in 320ms","time":"2019-07-03T18:01:27.196Z" "SQL channel job 266 (createOrUpdateSession) succeeded in 461ms","time":"2019-07-03T18:01:27.338Z" "data message from +[REDACTED]185.2 1562156117862 (43019f76-0baa-4656-b616-6d1cd83dbc4e)","time":"2019-07-03T18:01:27.338Z" MSG "message from +[REDACTED]185.1 1562162448520 (4aad4c46-d28d-4095-9921-2d149b26b087)","time":"2019-07-03T18:01:27.338Z" "SQL channel job 267 (getMessagesByConversation) succeeded in 267ms","time":"2019-07-03T18:01:27.339Z" "SQL channel job 268 (removeUnprocessed) succeeded in 399ms","time":"2019-07-03T18:01:27.470Z" "SQL channel job 269 (getMessagesBySentAt) succeeded in 399ms","time":"2019-07-03T18:01:27.471Z" "SQL channel job 270 (getNextExpiringMessage) succeeded in 394ms","time":"2019-07-03T18:01:27.474Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:27.474Z" "SQL channel job 271 (getUnprocessedById) succeeded in 136ms","time":"2019-07-03T18:01:27.474Z" "SQL channel job 272 (getItemById) succeeded in 134ms","time":"2019-07-03T18:01:27.475Z" "Worker job 49 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:27.475Z" "SQL channel job 273 (saveMessage) succeeded in 163ms","time":"2019-07-03T18:01:27.636Z" "delivery receipt from +[REDACTED]185.1 1562156101782","time":"2019-07-03T18:01:27.636Z" "SQL channel job 274 (updateUnprocessedWithData) succeeded in 261ms","time":"2019-07-03T18:01:27.736Z" "SQL channel job 275 (createOrUpdateSession) succeeded in 408ms","time":"2019-07-03T18:01:27.885Z" MSG "data message from +[REDACTED]185.1 1562162448520 (4aad4c46-d28d-4095-9921-2d149b26b087)","time":"2019-07-03T18:01:27.885Z" "message from +[REDACTED]085.1 1562162473757 (50200067-a5e6-4c33-a560-989d2c35ac04)","time":"2019-07-03T18:01:27.888Z" "SQL channel job 276 (getMessagesByConversation) succeeded in 253ms","time":"2019-07-03T18:01:27.889Z" "SQL channel job 277 (removeUnprocessed) succeeded in 389ms","time":"2019-07-03T18:01:28.026Z" "SQL channel job 278 (getMessagesBySentAt) succeeded in 389ms","time":"2019-07-03T18:01:28.026Z" "No message for delivery receipt +[REDACTED]185 1562156101782","time":"2019-07-03T18:01:28.027Z" "SQL channel job 279 (getUnprocessedById) succeeded in 143ms","time":"2019-07-03T18:01:28.029Z" "SQL channel job 280 (getItemById) succeeded in 137ms","time":"2019-07-03T18:01:28.029Z" "Worker job 50 (arrayBufferToStringBase64) succeeded in 2ms","time":"2019-07-03T18:01:28.029Z" "Starting handleDataMessage for message +[REDACTED]085.1 1562156101782 in conversation +[REDACTED]185","time":"2019-07-03T18:01:28.030Z" "Set message expiration {\"expiresAt\":1562760902751,\"sentAt\":1562156101782}","time":"2019-07-03T18:01:28.038Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:28.039Z" "SQL channel job 282 (updateUnprocessedWithData) succeeded in 112ms","time":"2019-07-03T18:01:28.142Z" "SQL channel job 283 (saveMessage) succeeded in 269ms","time":"2019-07-03T18:01:28.316Z" "SQL channel job 284 (createOrUpdateSession) succeeded in 391ms","time":"2019-07-03T18:01:28.440Z" "read messages from +[REDACTED]085.1 1562162473757 (50200067-a5e6-4c33-a560-989d2c35ac04)","time":"2019-07-03T18:01:28.440Z" "message from +[REDACTED]185.1 1562162501841 (c8c8b1f6-a1d4-477a-a82a-cab0e7609579)","time":"2019-07-03T18:01:28.440Z" "SQL channel job 285 (getNextExpiringMessage) succeeded in 361ms","time":"2019-07-03T18:01:28.441Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:28.442Z" "SQL channel job 286 (getConversationById) succeeded in 126ms","time":"2019-07-03T18:01:28.443Z" "Worker job 51 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:28.445Z" "SQL channel job 288 (updateConversation) succeeded in 95ms","time":"2019-07-03T18:01:28.539Z" "SQL channel job 289 (updateUnprocessedWithData) succeeded in 227ms","time":"2019-07-03T18:01:28.672Z" "SQL channel job 290 (getItemById) succeeded in 226ms","time":"2019-07-03T18:01:28.672Z" "SQL channel job 291 (getMessagesByConversation) succeeded in 134ms","time":"2019-07-03T18:01:28.673Z" "SQL channel job 292 (getMessageById) succeeded in 142ms","time":"2019-07-03T18:01:28.681Z" "delivery receipt from +[REDACTED]185.2 1562156101782","time":"2019-07-03T18:01:28.685Z" "SQL channel job 294 (removeUnprocessed) succeeded in 103ms","time":"2019-07-03T18:01:28.788Z" "SQL channel job 295 (removeUnprocessed) succeeded in 220ms","time":"2019-07-03T18:01:28.905Z" "SQL channel job 296 (getMessagesBySentAt) succeeded in 220ms","time":"2019-07-03T18:01:28.905Z" "SQL channel job 297 (createOrUpdateSession) succeeded in 360ms","time":"2019-07-03T18:01:29.045Z" "data message from +[REDACTED]185.1 1562162501841 (c8c8b1f6-a1d4-477a-a82a-cab0e7609579)","time":"2019-07-03T18:01:29.046Z" "message from +[REDACTED]085.1 1562162509536 (19ad55c7-2361-414b-ad31-4ae8d123d449)","time":"2019-07-03T18:01:29.046Z" "SQL channel job 298 (updateConversation) succeeded in 469ms","time":"2019-07-03T18:01:29.155Z" "SQL channel job 299 (saveMessage) succeeded in 420ms","time":"2019-07-03T18:01:29.328Z" "read receipt +[REDACTED]185 1562156101782","time":"2019-07-03T18:01:29.329Z" "SQL channel job 300 (getUnprocessedById) succeeded in 283ms","time":"2019-07-03T18:01:29.329Z" "SQL channel job 301 (getItemById) succeeded in 282ms","time":"2019-07-03T18:01:29.329Z" "Worker job 52 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:29.330Z" "SQL channel job 302 (getNextExpiringMessage) succeeded in 251ms","time":"2019-07-03T18:01:29.330Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:29.331Z" "SQL channel job 305 (updateUnprocessedWithData) succeeded in 82ms","time":"2019-07-03T18:01:29.412Z" "SQL channel job 306 (saveMessage) succeeded in 243ms","time":"2019-07-03T18:01:29.577Z" "SQL channel job 307 (createOrUpdateSession) succeeded in 359ms","time":"2019-07-03T18:01:29.693Z" "read messages from +[REDACTED]085.1 1562162509536 (19ad55c7-2361-414b-ad31-4ae8d123d449)","time":"2019-07-03T18:01:29.694Z" "message from +[REDACTED]185.1 1562162545099 (f967e6a5-d9f1-4a87-b97a-2c1be45b366b)","time":"2019-07-03T18:01:29.694Z" "SQL channel job 308 (getMessagesByConversation) succeeded in 118ms","time":"2019-07-03T18:01:29.695Z" "SQL channel job 309 (removeUnprocessed) succeeded in 241ms","time":"2019-07-03T18:01:29.818Z" "SQL channel job 310 (getMessageBySender) succeeded in 240ms","time":"2019-07-03T18:01:29.818Z" "Starting handleDataMessage for message +[REDACTED]185.2 1562156117862 in conversation +[REDACTED]185","time":"2019-07-03T18:01:29.819Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:29.828Z" "SQL channel job 311 (getUnprocessedById) succeeded in 151ms","time":"2019-07-03T18:01:29.845Z" "SQL channel job 312 (getConversationById) succeeded in 142ms","time":"2019-07-03T18:01:29.845Z" "SQL channel job 313 (getItemById) succeeded in 140ms","time":"2019-07-03T18:01:29.845Z" "Worker job 53 (arrayBufferToStringBase64) succeeded in 2ms","time":"2019-07-03T18:01:29.846Z" "SQL channel job 314 (saveMessage) succeeded in 156ms","time":"2019-07-03T18:01:30.000Z" "SQL channel job 315 (updateConversation) succeeded in 245ms","time":"2019-07-03T18:01:30.091Z" "SQL channel job 316 (updateUnprocessedWithData) succeeded in 370ms","time":"2019-07-03T18:01:30.216Z" "SQL channel job 317 (createOrUpdateSession) succeeded in 493ms","time":"2019-07-03T18:01:30.340Z" "call message from +[REDACTED]185.1 1562162545099 (f967e6a5-d9f1-4a87-b97a-2c1be45b366b)","time":"2019-07-03T18:01:30.344Z" "message from +[REDACTED]185.1 1562162545287 (296ba93c-2348-4181-b9e1-2d06037e34e7)","time":"2019-07-03T18:01:30.345Z" "SQL channel job 318 (getConversationById) succeeded in 345ms","time":"2019-07-03T18:01:30.345Z" "SQL channel job 319 (getNextExpiringMessage) succeeded in 265ms","time":"2019-07-03T18:01:30.346Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:30.347Z" "Worker job 54 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:30.348Z" "SQL channel job 321 (removeUnprocessed) succeeded in 145ms","time":"2019-07-03T18:01:30.489Z" "SQL channel job 322 (updateConversation) succeeded in 234ms","time":"2019-07-03T18:01:30.580Z" "SQL channel job 323 (updateUnprocessedWithData) succeeded in 233ms","time":"2019-07-03T18:01:30.581Z" "SQL channel job 324 (getItemById) succeeded in 233ms","time":"2019-07-03T18:01:30.581Z" "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562156117862}","time":"2019-07-03T18:01:30.595Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:30.596Z" "SQL channel job 327 (createOrUpdateSession) succeeded in 156ms","time":"2019-07-03T18:01:30.737Z" "call message from +[REDACTED]185.1 1562162545287 (296ba93c-2348-4181-b9e1-2d06037e34e7)","time":"2019-07-03T18:01:30.738Z" "message from +[REDACTED]185.1 1562162545490 (956f1715-19ca-4093-b5ca-266552515a29)","time":"2019-07-03T18:01:30.738Z" "SQL channel job 328 (getConversationById) succeeded in 149ms","time":"2019-07-03T18:01:30.739Z" "SQL channel job 329 (removeUnprocessed) succeeded in 325ms","time":"2019-07-03T18:01:30.922Z" "SQL channel job 330 (getMessageBySender) succeeded in 325ms","time":"2019-07-03T18:01:30.922Z" MSG "Starting handleDataMessage for message +[REDACTED]185.1 1562162448520 in conversation +[REDACTED]185","time":"2019-07-03T18:01:30.923Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:30.934Z" "SQL channel job 331 (getUnprocessedById) succeeded in 210ms","time":"2019-07-03T18:01:30.948Z" "Worker job 55 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:30.948Z" "SQL channel job 332 (removeUnprocessed) succeeded in 333ms","time":"2019-07-03T18:01:31.071Z" "SQL channel job 333 (updateConversation) succeeded in 431ms","time":"2019-07-03T18:01:31.170Z" "SQL channel job 334 (getItemById) succeeded in 430ms","time":"2019-07-03T18:01:31.171Z" "SQL channel job 335 (createOrUpdateItem) succeeded in 433ms","time":"2019-07-03T18:01:31.262Z" "SQL channel job 336 (saveMessage) succeeded in 464ms","time":"2019-07-03T18:01:31.411Z" "SQL channel job 337 (updateUnprocessedWithData) succeeded in 464ms","time":"2019-07-03T18:01:31.412Z" "SQL channel job 338 (createOrUpdateSession) succeeded in 396ms","time":"2019-07-03T18:01:31.569Z" "call message from +[REDACTED]185.1 1562162545490 (956f1715-19ca-4093-b5ca-266552515a29)","time":"2019-07-03T18:01:31.569Z" "message from +[REDACTED]185.1 1562162545697 (b987d136-4a83-4ed6-b81a-78341dc683a9)","time":"2019-07-03T18:01:31.570Z" "SQL channel job 339 (getNextExpiringMessage) succeeded in 160ms","time":"2019-07-03T18:01:31.570Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:31.571Z" "SQL channel job 340 (saveAttachmentDownloadJob) succeeded in 272ms","time":"2019-07-03T18:01:31.684Z" "SQL channel job 341 (getUnprocessedById) succeeded in 119ms","time":"2019-07-03T18:01:31.687Z" "Worker job 56 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:31.688Z" "SQL channel job 342 (removeUnprocessed) succeeded in 247ms","time":"2019-07-03T18:01:31.817Z" "SQL channel job 343 (getItemById) succeeded in 246ms","time":"2019-07-03T18:01:31.819Z" "SQL channel job 344 (getNextAttachmentDownloadJobs) succeeded in 136ms","time":"2019-07-03T18:01:31.819Z" "SQL channel job 345 (saveMessage) succeeded in 279ms","time":"2019-07-03T18:01:31.966Z" "SQL channel job 346 (updateUnprocessedWithData) succeeded in 279ms","time":"2019-07-03T18:01:31.966Z" "SQL channel job 347 (createOrUpdateSession) succeeded in 295ms","time":"2019-07-03T18:01:32.115Z" "call message from +[REDACTED]185.1 1562162545697 (b987d136-4a83-4ed6-b81a-78341dc683a9)","time":"2019-07-03T18:01:32.116Z" "message from +[REDACTED]185.1 1562162545884 (e9cbcb1b-fc61-4a18-a8ac-438f13b0ebd9)","time":"2019-07-03T18:01:32.116Z" "SQL channel job 348 (getMessageById) succeeded in 296ms","time":"2019-07-03T18:01:32.116Z" "SQL channel job 349 (createOrUpdateItem) succeeded in 297ms","time":"2019-07-03T18:01:32.231Z" "SQL channel job 350 (getConversationById) succeeded in 268ms","time":"2019-07-03T18:01:32.234Z" "SQL channel job 351 (getUnprocessedById) succeeded in 120ms","time":"2019-07-03T18:01:32.235Z" "Worker job 57 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:32.235Z" "SQL channel job 352 (removeUnprocessed) succeeded in 265ms","time":"2019-07-03T18:01:32.381Z" "SQL channel job 353 (setAttachmentDownloadJobPending) succeeded in 363ms","time":"2019-07-03T18:01:32.480Z" "GET https://cdn.signal.org/attachments/347759077529459441","time":"2019-07-03T18:01:32.481Z" "SQL channel job 354 (getItemById) succeeded in 364ms","time":"2019-07-03T18:01:32.482Z" "SQL channel job 355 (updateConversation) succeeded in 346ms","time":"2019-07-03T18:01:32.581Z" "SQL channel job 356 (updateUnprocessedWithData) succeeded in 346ms","time":"2019-07-03T18:01:32.584Z" "GET https://cdn.signal.org/attachments/347759077529459441 200 Success","time":"2019-07-03T18:01:32.584Z" "SQL channel job 357 (getNextExpiringMessage) succeeded in 173ms","time":"2019-07-03T18:01:32.586Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:32.588Z" "SQL channel job 358 (createOrUpdateSession) succeeded in 280ms","time":"2019-07-03T18:01:32.764Z" "call message from +[REDACTED]185.1 1562162545884 (e9cbcb1b-fc61-4a18-a8ac-438f13b0ebd9)","time":"2019-07-03T18:01:32.765Z" "message from +[REDACTED]185.1 1562162546067 (489837b3-d9e1-44a7-82c5-aaa618eab56f)","time":"2019-07-03T18:01:32.765Z" "SQL channel job 359 (getMessagesByConversation) succeeded in 185ms","time":"2019-07-03T18:01:32.767Z" "SQL channel job 360 (getMessageById) succeeded in 197ms","time":"2019-07-03T18:01:32.779Z" MSG "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562162448520}","time":"2019-07-03T18:01:32.785Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:32.785Z" "read sync +[REDACTED]185 1562156117862","time":"2019-07-03T18:01:32.786Z" "SQL channel job 361 (saveMessage) succeeded in 330ms","time":"2019-07-03T18:01:32.929Z" "SQL channel job 362 (getUnprocessedById) succeeded in 167ms","time":"2019-07-03T18:01:32.931Z" "Worker job 58 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:32.931Z" "SQL channel job 363 (removeUnprocessed) succeeded in 297ms","time":"2019-07-03T18:01:33.062Z" "SQL channel job 364 (getConversationById) succeeded in 284ms","time":"2019-07-03T18:01:33.062Z" "SQL channel job 365 (removeUnprocessed) succeeded in 409ms","time":"2019-07-03T18:01:33.195Z" "SQL channel job 366 (getMessagesBySentAt) succeeded in 409ms","time":"2019-07-03T18:01:33.195Z" "Remove notification","time":"2019-07-03T18:01:33.196Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562156117862,\"wasMessageFound\":true,\"wasNotificationFound\":true,\"wasNotificationRemoved\":true}","time":"2019-07-03T18:01:33.196Z" "SQL channel job 367 (getItemById) succeeded in 408ms","time":"2019-07-03T18:01:33.198Z" "SQL channel job 368 (removeAttachmentDownloadJob) succeeded in 380ms","time":"2019-07-03T18:01:33.313Z" "SQL channel job 369 (updateUnprocessedWithData) succeeded in 381ms","time":"2019-07-03T18:01:33.313Z" "SQL channel job 370 (updateConversation) succeeded in 348ms","time":"2019-07-03T18:01:33.411Z" "SQL channel job 371 (saveMessage) succeeded in 387ms","time":"2019-07-03T18:01:33.584Z" "Set message expiration {\"expiresAt\":1562767273757,\"sentAt\":1562156117862}","time":"2019-07-03T18:01:33.585Z" "SQL channel job 372 (saveMessage) succeeded in 569ms","time":"2019-07-03T18:01:33.767Z" "Remove notification","time":"2019-07-03T18:01:33.767Z" MSG "read sync +[REDACTED]185 1562162448520","time":"2019-07-03T18:01:33.767Z" "SQL channel job 373 (createOrUpdateSession) succeeded in 716ms","time":"2019-07-03T18:01:33.916Z" "call message from +[REDACTED]185.1 1562162546067 (489837b3-d9e1-44a7-82c5-aaa618eab56f)","time":"2019-07-03T18:01:33.917Z" "message from +[REDACTED]185.1 1562162546247 (6419cfd6-3688-4bde-8967-d6b1de699c6c)","time":"2019-07-03T18:01:33.917Z" "SQL channel job 374 (getNextAttachmentDownloadJobs) succeeded in 606ms","time":"2019-07-03T18:01:33.917Z" "SQL channel job 375 (getNextExpiringMessage) succeeded in 505ms","time":"2019-07-03T18:01:33.918Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:33.918Z" "SQL channel job 376 (removeUnprocessed) succeeded in 290ms","time":"2019-07-03T18:01:34.057Z" "SQL channel job 377 (getUnreadByConversation) succeeded in 290ms","time":"2019-07-03T18:01:34.058Z" "SQL channel job 378 (getMessagesBySentAt) succeeded in 303ms","time":"2019-07-03T18:01:34.070Z" MSG "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562162448520,\"wasMessageFound\":true,\"wasNotificationFound\":false,\"wasNotificationRemoved\":false}","time":"2019-07-03T18:01:34.071Z" "SQL channel job 379 (getUnprocessedById) succeeded in 157ms","time":"2019-07-03T18:01:34.073Z" "Worker job 59 (arrayBufferToStringBase64) succeeded in 3ms","time":"2019-07-03T18:01:34.076Z" "SQL channel job 380 (removeUnprocessed) succeeded in 678ms","time":"2019-07-03T18:01:34.593Z" "SQL channel job 381 (getItemById) succeeded in 674ms","time":"2019-07-03T18:01:34.594Z" "SQL channel job 382 (getConversationById) succeeded in 524ms","time":"2019-07-03T18:01:34.595Z" "SQL channel job 383 (saveMessage) succeeded in 680ms","time":"2019-07-03T18:01:34.753Z" MSG "Set message expiration {\"expiresAt\":1562767273757,\"sentAt\":1562162448520}","time":"2019-07-03T18:01:34.753Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:34.767Z" "SQL channel job 384 (saveMessage) succeeded in 853ms","time":"2019-07-03T18:01:34.926Z" "SQL channel job 385 (updateUnprocessedWithData) succeeded in 851ms","time":"2019-07-03T18:01:34.928Z" "SQL channel job 386 (getNextExpiringMessage) succeeded in 514ms","time":"2019-07-03T18:01:34.928Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:34.929Z" "SQL channel job 387 (createOrUpdateSession) succeeded in 489ms","time":"2019-07-03T18:01:35.083Z" "call message from +[REDACTED]185.1 1562162546247 (6419cfd6-3688-4bde-8967-d6b1de699c6c)","time":"2019-07-03T18:01:35.084Z" "message from +[REDACTED]185.1 1562163307740 (6d0f1994-3d73-4ce6-bd41-f7a51ed3d2df)","time":"2019-07-03T18:01:35.084Z" "SQL channel job 388 (updateConversation) succeeded in 848ms","time":"2019-07-03T18:01:35.444Z" "SQL channel job 389 (removeUnprocessed) succeeded in 518ms","time":"2019-07-03T18:01:35.445Z" "SQL channel job 390 (getMessageBySender) succeeded in 517ms","time":"2019-07-03T18:01:35.445Z" "SQL channel job 391 (createOrUpdateItem) succeeded in 776ms","time":"2019-07-03T18:01:35.836Z" "SQL channel job 392 (getUnprocessedById) succeeded in 754ms","time":"2019-07-03T18:01:35.838Z" "Worker job 60 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:35.838Z" "SQL channel job 393 (removeUnprocessed) succeeded in 893ms","time":"2019-07-03T18:01:35.976Z" "SQL channel job 394 (getItemById) succeeded in 892ms","time":"2019-07-03T18:01:35.977Z" "SQL channel job 395 (getNextExpiringMessage) succeeded in 564ms","time":"2019-07-03T18:01:35.977Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:35.978Z" "SQL channel job 396 (getUnreadByConversation) succeeded in 534ms","time":"2019-07-03T18:01:35.979Z" "SQL channel job 397 (updateUnprocessedWithData) succeeded in 150ms","time":"2019-07-03T18:01:35.988Z" "SQL channel job 399 (createOrUpdateSession) succeeded in 136ms","time":"2019-07-03T18:01:36.125Z" "call message from +[REDACTED]185.1 1562163307740 (6d0f1994-3d73-4ce6-bd41-f7a51ed3d2df)","time":"2019-07-03T18:01:36.126Z" "message from +[REDACTED]185.1 1562174857771 (844e596d-cf2d-4bb9-8690-331460928412)","time":"2019-07-03T18:01:36.126Z" "SQL channel job 400 (updateConversation) succeeded in 243ms","time":"2019-07-03T18:01:36.234Z" "Starting handleDataMessage for message +[REDACTED]185.1 1562162501841 in conversation +[REDACTED]185","time":"2019-07-03T18:01:36.234Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:36.241Z" "SQL channel job 401 (getUnprocessedById) succeeded in 136ms","time":"2019-07-03T18:01:36.260Z" "Worker job 61 (arrayBufferToStringBase64) succeeded in 3ms","time":"2019-07-03T18:01:36.264Z" "SQL channel job 402 (removeUnprocessed) succeeded in 233ms","time":"2019-07-03T18:01:36.359Z" "SQL channel job 403 (getItemById) succeeded in 231ms","time":"2019-07-03T18:01:36.359Z" "SQL channel job 404 (saveMessage) succeeded in 256ms","time":"2019-07-03T18:01:36.516Z" "SQL channel job 405 (updateUnprocessedWithData) succeeded in 253ms","time":"2019-07-03T18:01:36.516Z" "SQL channel job 406 (createOrUpdateSession) succeeded in 295ms","time":"2019-07-03T18:01:36.656Z" "data message from +[REDACTED]185.1 1562174857771 (844e596d-cf2d-4bb9-8690-331460928412)","time":"2019-07-03T18:01:36.656Z" "message from +[REDACTED]085.1 1562174864522 (e0ee892e-17e0-4a02-9dea-d511910d2e18)","time":"2019-07-03T18:01:36.656Z" "SQL channel job 407 (getNextExpiringMessage) succeeded in 141ms","time":"2019-07-03T18:01:36.657Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:36.658Z" "SQL channel job 408 (getConversationById) succeeded in 142ms","time":"2019-07-03T18:01:36.658Z" "Worker job 62 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:36.659Z" "SQL channel job 410 (updateConversation) succeeded in 89ms","time":"2019-07-03T18:01:36.748Z" "SQL channel job 411 (updateUnprocessedWithData) succeeded in 171ms","time":"2019-07-03T18:01:36.831Z" "SQL channel job 412 (getItemById) succeeded in 171ms","time":"2019-07-03T18:01:36.831Z" "SQL channel job 413 (getMessagesByConversation) succeeded in 85ms","time":"2019-07-03T18:01:36.832Z" "SQL channel job 414 (getMessageById) succeeded in 94ms","time":"2019-07-03T18:01:36.842Z" "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562162501841}","time":"2019-07-03T18:01:36.847Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:36.848Z" "onProgress: Message count is 20","time":"2019-07-03T18:01:36.848Z" "read sync +[REDACTED]185 1562162501841","time":"2019-07-03T18:01:36.848Z" "SQL channel job 415 (createOrUpdateSession) succeeded in 149ms","time":"2019-07-03T18:01:36.981Z" "read messages from +[REDACTED]085.1 1562174864522 (e0ee892e-17e0-4a02-9dea-d511910d2e18)","time":"2019-07-03T18:01:36.982Z" "message from +[REDACTED]185.1 1562176715757 (9c3f82e3-380e-4d20-961e-83a4bf43bb9e)","time":"2019-07-03T18:01:36.982Z" "SQL channel job 416 (getConversationById) succeeded in 142ms","time":"2019-07-03T18:01:36.982Z" "SQL channel job 417 (removeUnprocessed) succeeded in 250ms","time":"2019-07-03T18:01:37.097Z" "SQL channel job 418 (getMessagesBySentAt) succeeded in 249ms","time":"2019-07-03T18:01:37.098Z" "Remove notification","time":"2019-07-03T18:01:37.099Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562162501841,\"wasMessageFound\":true,\"wasNotificationFound\":true,\"wasNotificationRemoved\":true}","time":"2019-07-03T18:01:37.099Z" "SQL channel job 419 (getUnprocessedById) succeeded in 118ms","time":"2019-07-03T18:01:37.100Z" "Worker job 63 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:37.101Z" "SQL channel job 420 (updateConversation) succeeded in 215ms","time":"2019-07-03T18:01:37.197Z" "SQL channel job 421 (getItemById) succeeded in 214ms","time":"2019-07-03T18:01:37.198Z" "SQL channel job 422 (saveMessage) succeeded in 299ms","time":"2019-07-03T18:01:37.398Z" "Set message expiration {\"expiresAt\":1562767309536,\"sentAt\":1562162501841}","time":"2019-07-03T18:01:37.399Z" "SQL channel job 423 (saveMessage) succeeded in 445ms","time":"2019-07-03T18:01:37.545Z" "SQL channel job 424 (updateUnprocessedWithData) succeeded in 553ms","time":"2019-07-03T18:01:37.653Z" "SQL channel job 425 (createOrUpdateSession) succeeded in 586ms","time":"2019-07-03T18:01:37.785Z" "call message from +[REDACTED]185.1 1562176715757 (9c3f82e3-380e-4d20-961e-83a4bf43bb9e)","time":"2019-07-03T18:01:37.790Z" "message from +[REDACTED]185.1 1562176717058 (1d641278-3306-427a-b1c1-21d5de439a12)","time":"2019-07-03T18:01:37.790Z" "SQL channel job 426 (createOrUpdateItem) succeeded in 651ms","time":"2019-07-03T18:01:37.893Z" "SQL channel job 427 (getNextExpiringMessage) succeeded in 382ms","time":"2019-07-03T18:01:37.898Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:37.898Z" "SQL channel job 428 (removeUnprocessed) succeeded in 497ms","time":"2019-07-03T18:01:38.042Z" "SQL channel job 429 (getUnreadByConversation) succeeded in 497ms","time":"2019-07-03T18:01:38.043Z" "SQL channel job 430 (getMessageBySender) succeeded in 504ms","time":"2019-07-03T18:01:38.052Z" "SQL channel job 431 (getUnprocessedById) succeeded in 266ms","time":"2019-07-03T18:01:38.052Z" "Worker job 64 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:01:38.053Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:38.099Z" "SQL channel job 432 (removeUnprocessed) succeeded in 402ms","time":"2019-07-03T18:01:38.191Z" "SQL channel job 433 (getItemById) succeeded in 400ms","time":"2019-07-03T18:01:38.192Z" "SQL channel job 434 (getConversationById) succeeded in 141ms","time":"2019-07-03T18:01:38.193Z" "SQL channel job 435 (updateUnprocessedWithData) succeeded in 140ms","time":"2019-07-03T18:01:38.194Z" "SQL channel job 436 (updateConversation) succeeded in 99ms","time":"2019-07-03T18:01:38.292Z" "Starting handleDataMessage for message +[REDACTED]185.1 1562174857771 in conversation +[REDACTED]185","time":"2019-07-03T18:01:38.293Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:01:38.302Z" "SQL channel job 437 (createOrUpdateSession) succeeded in 264ms","time":"2019-07-03T18:01:38.457Z" "call message from +[REDACTED]185.1 1562176717058 (1d641278-3306-427a-b1c1-21d5de439a12)","time":"2019-07-03T18:01:38.458Z" "MessageReceiver: finished processing messages after 'empty', now waiting for application","time":"2019-07-03T18:01:38.458Z" "SQL channel job 438 (saveMessage) succeeded in 322ms","time":"2019-07-03T18:01:38.639Z" "SQL channel job 439 (getUnprocessedById) succeeded in 182ms","time":"2019-07-03T18:01:38.640Z" "Worker job 65 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:01:38.640Z" "SQL channel job 440 (removeUnprocessed) succeeded in 340ms","time":"2019-07-03T18:01:38.798Z" "SQL channel job 441 (getNextExpiringMessage) succeeded in 282ms","time":"2019-07-03T18:01:38.798Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:38.799Z" "SQL channel job 442 (getConversationById) succeeded in 160ms","time":"2019-07-03T18:01:38.800Z" "SQL channel job 443 (updateUnprocessedWithData) succeeded in 160ms","time":"2019-07-03T18:01:38.801Z" "SQL channel job 444 (updateConversation) succeeded in 89ms","time":"2019-07-03T18:01:38.889Z" "Add notification {\"conversationId\":\"+[REDACTED]185\",\"isExpiringMessage\":true,\"messageSentAt\":1562174857771}","time":"2019-07-03T18:01:38.903Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"disabled\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:38.903Z" "onProgress: Message count is 30","time":"2019-07-03T18:01:38.904Z" "read sync +[REDACTED]185 1562174857771","time":"2019-07-03T18:01:38.904Z" "SQL channel job 448 (updateConversation) succeeded in 148ms","time":"2019-07-03T18:01:39.048Z" "SQL channel job 449 (removeUnprocessed) succeeded in 258ms","time":"2019-07-03T18:01:39.163Z" "SQL channel job 450 (getMessagesBySentAt) succeeded in 259ms","time":"2019-07-03T18:01:39.163Z" "Remove notification","time":"2019-07-03T18:01:39.164Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562174857771,\"wasMessageFound\":true,\"wasNotificationFound\":true,\"wasNotificationRemoved\":true}","time":"2019-07-03T18:01:39.164Z" "SQL channel job 451 (saveMessage) succeeded in 552ms","time":"2019-07-03T18:01:39.716Z" "Set message expiration {\"expiresAt\":1562779664522,\"sentAt\":1562174857771}","time":"2019-07-03T18:01:39.717Z" "SQL channel job 452 (saveMessage) succeeded in 716ms","time":"2019-07-03T18:01:39.882Z" "MessageReceiver: emitting 'empty' event","time":"2019-07-03T18:01:39.882Z" "updater/start: Updates disabled - not starting new version checks","time":"2019-07-03T18:01:39.883Z" "SQL channel job 453 (createOrUpdateItem) succeeded in 686ms","time":"2019-07-03T18:01:39.990Z" "SQL channel job 454 (getNextExpiringMessage) succeeded in 476ms","time":"2019-07-03T18:01:39.992Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:39.993Z" "SQL channel job 455 (removeUnprocessed) succeeded in 207ms","time":"2019-07-03T18:01:40.090Z" "SQL channel job 456 (getUnreadByConversation) succeeded in 207ms","time":"2019-07-03T18:01:40.090Z" "SQL channel job 458 (updateConversation) succeeded in 90ms","time":"2019-07-03T18:01:40.189Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:01:40.521Z" "Update notifications: {\"shouldClearNotifications\":false,\"shouldPlayNotificationSound\":false,\"shouldShowNotifications\":false,\"type\":\"noNotifications\",\"isNotificationGroupingSupported\":true}","time":"2019-07-03T18:01:40.884Z" "SQL channel job 460 (createOrUpdateItem) succeeded in 122ms","time":"2019-07-03T18:01:41.214Z" "Upgrade message schema (with index): {\"done\":true,\"numProcessed\":0,\"fetchDuration\":4,\"upgradeDuration\":0,\"saveDuration\":1,\"totalDuration\":5}","time":"2019-07-03T18:01:43.647Z" "Background migration complete. Stopping idle detector.","time":"2019-07-03T18:01:43.647Z" "Stop idle detector","time":"2019-07-03T18:01:43.648Z" "open inbox","time":"2019-07-03T18:01:51.399Z" "fetchMessages","time":"2019-07-03T18:01:51.414Z" "GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]185 (unauth)","time":"2019-07-03T18:01:51.544Z" "SQL channel job 462 (getMessagesByConversation) succeeded in 110ms","time":"2019-07-03T18:01:51.550Z" "SQL channel job 463 (getMessagesByConversation) succeeded in 13ms","time":"2019-07-03T18:01:51.559Z" "Conversation +[REDACTED]185 took 1132 milliseconds to load","time":"2019-07-03T18:01:52.570Z" "GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]185 200 Success","time":"2019-07-03T18:01:52.613Z" "Setting sealedSender to ENABLED for conversation +[REDACTED]185","time":"2019-07-03T18:01:52.615Z" "GET https://cdn.signal.org/profiles/i8uldDr3_X7KrYMnL7e9Kw","time":"2019-07-03T18:01:52.620Z" ERR "GET https://cdn.signal.org/profiles/i8uldDr3_X7KrYMnL7e9Kw 403 Error","time":"2019-07-03T18:01:52.773Z" "done with status fetch","time":"2019-07-03T18:01:52.774Z" "SQL channel job 466 (updateConversation) succeeded in 157ms","time":"2019-07-03T18:01:52.774Z" "SQL channel job 467 (getMessagesByConversation) succeeded in 13ms","time":"2019-07-03T18:01:52.774Z" "SQL channel job 469 (updateConversation) succeeded in 95ms","time":"2019-07-03T18:01:52.871Z" $ cat ~/.config/Signal\ Beta/logs/log.log | grep "7-03T18\:41\:1" "Worker job 110 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:41:11.493Z" "SQL channel job 947 (saveUnprocessed) succeeded in 132ms","time":"2019-07-03T18:41:11.626Z" "queueing envelope +[REDACTED]085.1 1562179275361 (3fdb5b31-1c45-4f23-b828-1ef0007afa33)","time":"2019-07-03T18:41:11.626Z" "message from +[REDACTED]085.1 1562179275361 (3fdb5b31-1c45-4f23-b828-1ef0007afa33)","time":"2019-07-03T18:41:11.626Z" "SQL channel job 949 (createOrUpdateSession) succeeded in 162ms","time":"2019-07-03T18:41:11.791Z" "read messages from +[REDACTED]085.1 1562179275361 (3fdb5b31-1c45-4f23-b828-1ef0007afa33)","time":"2019-07-03T18:41:11.792Z" "read sync +[REDACTED]185 1562179264681","time":"2019-07-03T18:41:11.793Z" "Worker job 111 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:41:11.794Z" "Receive read sync: {\"receiptSender\":\"+[REDACTED]185\",\"receiptTimestamp\":1562179264681,\"wasMessageFound\":true,\"wasNotificationFound\":false,\"wasNotificationRemoved\":false}","time":"2019-07-03T18:41:11.796Z" "SQL channel job 952 (updateUnprocessedWithData) succeeded in 122ms","time":"2019-07-03T18:41:11.916Z" "SQL channel job 953 (saveMessage) succeeded in 265ms","time":"2019-07-03T18:41:12.065Z" "Set message expiration {\"expiresAt\":1562784071800,\"sentAt\":1562179264681}","time":"2019-07-03T18:41:12.065Z" "SQL channel job 954 (saveMessage) succeeded in 409ms","time":"2019-07-03T18:41:12.214Z" "SQL channel job 955 (getNextExpiringMessage) succeeded in 149ms","time":"2019-07-03T18:41:12.214Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:41:12.215Z" "SQL channel job 956 (removeUnprocessed) succeeded in 117ms","time":"2019-07-03T18:41:12.330Z" "SQL channel job 957 (getUnreadByConversation) succeeded in 117ms","time":"2019-07-03T18:41:12.330Z" "SQL channel job 959 (updateConversation) succeeded in 95ms","time":"2019-07-03T18:41:12.446Z" "Worker job 112 (arrayBufferToStringBase64) succeeded in 1ms","time":"2019-07-03T18:41:12.845Z" "SQL channel job 960 (saveUnprocessed) succeeded in 105ms","time":"2019-07-03T18:41:12.950Z" MSG "queueing envelope +[REDACTED]085.1 1562179274232 (8782be4c-0179-4cff-97e4-4cb77b9ef034)","time":"2019-07-03T18:41:12.950Z" MSG "message from +[REDACTED]085.1 1562179274232 (8782be4c-0179-4cff-97e4-4cb77b9ef034)","time":"2019-07-03T18:41:12.950Z" "SQL channel job 962 (createOrUpdateSession) succeeded in 113ms","time":"2019-07-03T18:41:13.066Z" MSG "sent message to +[REDACTED]185 1562179274232 from +[REDACTED]085.1 1562179274232 (8782be4c-0179-4cff-97e4-4cb77b9ef034)","time":"2019-07-03T18:41:13.068Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:41:13.070Z" MSG "Starting handleDataMessage for message +[REDACTED]085.1 1562179274232 in conversation +[REDACTED]185","time":"2019-07-03T18:41:13.082Z" MSG "Set message expiration {\"expiresAt\":1562784073069,\"sentAt\":1562179274232}","time":"2019-07-03T18:41:13.090Z" "Update conversation 'expireTimer' {\"id\":\"+[REDACTED]185\",\"expireTimer\":604800,\"source\":\"handleDataMessage\"}","time":"2019-07-03T18:41:13.091Z" "Worker job 113 (arrayBufferToStringBase64) succeeded in 34ms","time":"2019-07-03T18:41:13.104Z" "SQL channel job 966 (saveMessage) succeeded in 178ms","time":"2019-07-03T18:41:13.282Z" "SQL channel job 967 (updateUnprocessedWithData) succeeded in 288ms","time":"2019-07-03T18:41:13.392Z" "SQL channel job 968 (saveAttachmentDownloadJob) succeeded in 206ms","time":"2019-07-03T18:41:13.490Z" "Worker job 114 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:41:13.508Z" "Worker job 115 (arrayBufferToStringBase64) succeeded in 0ms","time":"2019-07-03T18:41:13.527Z" "SQL channel job 969 (createOrUpdateItem) succeeded in 276ms","time":"2019-07-03T18:41:13.606Z" "SQL channel job 970 (getNextAttachmentDownloadJobs) succeeded in 118ms","time":"2019-07-03T18:41:13.609Z" "SQL channel job 971 (saveMessage) succeeded in 290ms","time":"2019-07-03T18:41:13.780Z" "SQL channel job 972 (saveUnprocessed) succeeded in 364ms","time":"2019-07-03T18:41:13.871Z" "queueing envelope +[REDACTED]185.1 1562179271573 (5f898e40-e8ea-4868-af8c-17467cd04c38)","time":"2019-07-03T18:41:13.872Z" "message from +[REDACTED]185.1 1562179271573 (5f898e40-e8ea-4868-af8c-17467cd04c38)","time":"2019-07-03T18:41:13.872Z" "SQL channel job 973 (saveUnprocessed) succeeded in 453ms","time":"2019-07-03T18:41:13.979Z" "queueing envelope +[REDACTED]185.1 1562179271567 (6b41b84a-5e65-4570-8ceb-b69aad5ec50b)","time":"2019-07-03T18:41:13.980Z" "SQL channel job 974 (getMessageById) succeeded in 372ms","time":"2019-07-03T18:41:13.981Z" "SQL channel job 975 (getConversationById) succeeded in 201ms","time":"2019-07-03T18:41:13.981Z" "SQL channel job 976 (getItemById) succeeded in 108ms","time":"2019-07-03T18:41:13.982Z" "SQL channel job 977 (setAttachmentDownloadJobPending) succeeded in 123ms","time":"2019-07-03T18:41:14.104Z" "GET https://cdn.signal.org/attachments/5648751264561189516","time":"2019-07-03T18:41:14.104Z" "SQL channel job 978 (updateConversation) succeeded in 230ms","time":"2019-07-03T18:41:14.212Z" "GET https://cdn.signal.org/attachments/5648751264561189516 200 Success","time":"2019-07-03T18:41:14.262Z" "SQL channel job 979 (createOrUpdateSession) succeeded in 393ms","time":"2019-07-03T18:41:14.377Z" "message from +[REDACTED]185.1 1562179271567 (6b41b84a-5e65-4570-8ceb-b69aad5ec50b)","time":"2019-07-03T18:41:14.378Z" "SQL channel job 980 (getNextExpiringMessage) succeeded in 312ms","time":"2019-07-03T18:41:14.379Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:41:14.380Z" "SQL channel job 981 (getMessagesByConversation) succeeded in 167ms","time":"2019-07-03T18:41:14.380Z" "SQL channel job 982 (getMessageById) succeeded in 167ms","time":"2019-07-03T18:41:14.393Z" MSG "delivery receipt from +[REDACTED]185.1 1562179274232","time":"2019-07-03T18:41:14.397Z" "SQL channel job 983 (saveMessage) succeeded in 248ms","time":"2019-07-03T18:41:14.560Z" "SQL channel job 984 (getUnprocessedById) succeeded in 188ms","time":"2019-07-03T18:41:14.564Z" "SQL channel job 985 (getConversationById) succeeded in 172ms","time":"2019-07-03T18:41:14.565Z" "Worker job 116 (arrayBufferToStringBase64) succeeded in 2ms","time":"2019-07-03T18:41:14.567Z" "SQL channel job 986 (removeUnprocessed) succeeded in 279ms","time":"2019-07-03T18:41:14.676Z" "SQL channel job 987 (removeUnprocessed) succeeded in 362ms","time":"2019-07-03T18:41:14.759Z" "SQL channel job 988 (getMessagesBySentAt) succeeded in 362ms","time":"2019-07-03T18:41:14.760Z" "SQL channel job 989 (getItemById) succeeded in 360ms","time":"2019-07-03T18:41:14.764Z" "SQL channel job 990 (getMessagesByConversation) succeeded in 352ms","time":"2019-07-03T18:41:14.765Z" "SQL channel job 991 (removeAttachmentDownloadJob) succeeded in 310ms","time":"2019-07-03T18:41:14.875Z" "SQL channel job 992 (updateConversation) succeeded in 402ms","time":"2019-07-03T18:41:14.968Z" "SQL channel job 993 (updateUnprocessedWithData) succeeded in 401ms","time":"2019-07-03T18:41:14.968Z" "SQL channel job 994 (saveMessage) succeeded in 345ms","time":"2019-07-03T18:41:15.109Z" "SQL channel job 995 (createOrUpdateSession) succeeded in 484ms","time":"2019-07-03T18:41:15.249Z" MSG "read receipt +[REDACTED]185 1562179274232","time":"2019-07-03T18:41:15.250Z" "SQL channel job 996 (getNextAttachmentDownloadJobs) succeeded in 376ms","time":"2019-07-03T18:41:15.251Z" "SQL channel job 997 (getNextExpiringMessage) succeeded in 185ms","time":"2019-07-03T18:41:15.251Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:41:15.252Z" "SQL channel job 998 (getMessagesByConversation) succeeded in 142ms","time":"2019-07-03T18:41:15.252Z" "SQL channel job 999 (getConversationById) succeeded in 51ms","time":"2019-07-03T18:41:15.264Z" "SQL channel job 1000 (getUnprocessedById) succeeded in 15ms","time":"2019-07-03T18:41:15.265Z" "SQL channel job 1001 (getMessagesBySentAt) succeeded in 15ms","time":"2019-07-03T18:41:15.265Z" "Worker job 117 (arrayBufferToStringBase64) succeeded in 7ms","time":"2019-07-03T18:41:15.272Z" "SQL channel job 1003 (updateConversation) succeeded in 101ms","time":"2019-07-03T18:41:15.366Z" "SQL channel job 1004 (saveMessage) succeeded in 269ms","time":"2019-07-03T18:41:15.540Z" "SQL channel job 1005 (updateConversation) succeeded in 360ms","time":"2019-07-03T18:41:15.631Z" "SQL channel job 1006 (updateUnprocessedWithData) succeeded in 450ms","time":"2019-07-03T18:41:15.722Z" "SQL channel job 1007 (getMessagesByConversation) succeeded in 183ms","time":"2019-07-03T18:41:15.723Z" "SQL channel job 1008 (removeUnprocessed) succeeded in 282ms","time":"2019-07-03T18:41:15.822Z" "SQL channel job 1009 (getConversationById) succeeded in 86ms","time":"2019-07-03T18:41:15.822Z" "SQL channel job 1010 (updateConversation) succeeded in 81ms","time":"2019-07-03T18:41:15.905Z" "next message expires 2019-07-03T21:13:25.321Z","time":"2019-07-03T18:41:16.067Z" $ cat ~/.config/Signal\ Beta/logs/log.log | grep "7-03T" | grep -i error ERR "GET https://textsecure-service.whispersystems.org/v1/profile/+[REDACTED]185 0 Error","time":"2019-07-03T19:01:30.229Z","v":0} ERR "GET https://cdn.signal.org/profiles/i8uldDr3_X7KrYMnL7e9Kw 403 Error","time":"2019-07-03T19:01:31.607Z","v":0} ```

This is the only one that stands out:

The timestamp is different from the two messages that got wedged in endless-progress-spinner-mode, however.