8000 All Boards etc pagination? (was: Scalability / Performance) · Issue #3788 · wekan/wekan · GitHub
[go: up one dir, main page]
More Web Proxy on the site http://driver.im/
Skip to content

All Boards etc pagination? (was: Scalability / Performance) #3788

New issue

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

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

Already on GitHub? Sign in to your account

Closed
e-gaulue opened this issue May 3, 2021 · 37 comments
Closed

All Boards etc pagination? (was: Scalability / Performance) #3788

e-gaulue opened this issue May 3, 2021 · 37 comments

Comments

@e-gaulue
Copy link
Contributor
e-gaulue commented May 3, 2021

Not really an issue, but a question. Is there a place with all configuration recommandations for scalability / performance ?

I read #3585, #3345. Today we saw in the log the "Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit." while wekan was searching something in boards.

We have presently (under snap) 30 k boards, 30 k swimlanes, 90 k lists, 1000 cards and 200 attachments (outside of Mongo). It looks big numbers, but in fact it not so much, few Mo db. We wan't to go to 150 k boards, 150 k swimlanes, 450 k lists, 600 k cards, 250 k attachments.

We will never need the all board page view. But it looks the request is thrown from somewhere else anyway.

First, I would just like to check if similar things already happened to anybody, hence the question.

Best regards,

@e-gaulue
Copy link
Contributor Author
e-gaulue commented May 4, 2021

Just to document:

May  4 09:31:20 hcrm mongod.27019[2898]: [conn3] Plan executor error during find command: FAILURE, stats: { stage: "PROJECTION", nReturned: 0, executionTimeMillisEstimate: 80, works: 11199, advanced: 0, needTime: 11198, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, transformBy: { _id: 1, boardId: 1, archived: 1, slug: 1, title: 1, description: 1, color: 1, members: 1, permission: 1, type: 1, sort: 1 }, inputStage: { stage: "SORT", nReturned: 0, executionTimeMillisEstimate: 80, works: 11199, advanced: 0, needTime: 11198, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, sortPattern: { sort: 1 }, memUsage: 33557135, memLimit: 33554432, inputStage: { stage: "SORT_KEY_GENERATOR", nReturned: 0, executionTimeMillisEstimate: 50, works: 11198, advanced: 0, needTime: 2, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, inputStage: { stage: "COLLSCAN", filter: { $and: [ { $or: [ { members: { $elemMatch: { $and: [ { isActive: { $eq: true } }, { userId: { $eq: "czH33CwzPNruj25XP" } } ] } } }, { permission: { $eq: "public" } } ] }, { archived: { $eq: false } } ] }, nReturned: 11196, executionTimeMillisEstimate: 40, works: 11197, advanced: 11196, needTime: 1, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, direction: "forward", docsExamined: 11196 } } } }
May  4 09:31:20 hcrm wekan.wekan[647]: Exception from sub boards id o53rZaoWnkBRouN9g Error: Exception while polling query {"collectionName":"boards","selector":{"archived":false,"$or":[{"permission":"public"},{"members":{"$elemMatch":{"userId":"czH33CwzPNruj25XP","isActive":true}}}]},"options":{"fields":{"_id":1,"boardId":1,"archived":1,"slug":1,"title":1,"description":1,"color":1,"members":1,"permission":1,"type":1,"sort":1},"sort":{"sort":1}}}: Executor error during find command: OperationFailed: Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit.
May  4 09:31:20 hcrm wekan.wekan[647]:     at PollingObserveDriver._pollMongo (packages/mongo/polling_observe_driver.js:165:11)
May  4 09:31:20 hcrm wekan.wekan[647]:     at Object.task (packages/mongo/polling_observe_driver.js:93:12)
May  4 09:31:20 hcrm wekan.wekan[647]:     at Meteor._SynchronousQueue.SQp._run (packages/meteor.js:917:16)
May  4 09:31:20 hcrm wekan.wekan[647]:     at packages/meteor.js:894:12

For the moment I moved the mongo internalQueryExecMaxBlockingSortBytes limit to 320 Mb.

Don't know which index should help yet.

@xet7
Copy link
Member
xet7 commented May 4, 2021

@e-gaulue

Thanks! I added some info to Scaling.

It is also at https://wekan.github.io / Download / Scaling (green button). And I also added it to wiki below Features.

@e-gaulue
Copy link
Contributor Author
e-gaulue commented May 5, 2021

As mongo states increasing the internalQueryExecMaxBlockingSortBytes is never the solution. Since we've changed this setting, MongoDB managed the request but the navigator didn't. It's not possible to send 30 k boards in the all boards view to a "normal" computer.

The real solution would be pagination or a global parameter to set a limit to 1000. Who wants to search a board out of 1000 on the screen? Search tool is there for that. So a global parameter limitation could be a first approach easier than pagination.

@xet7 xet7 changed the title Scalability / Performance ? All Boards etc pagination? (was: Scalability / Performance) May 5, 2021
@xet7
Copy link
Member
xet7 commented May 5, 2021

@e-gaulue

Some alternatives, kind of related:

a) Pagination

b) Infinite scroll

c) Automatic categorization + Pagination, based on board color, etc, with tabs/tree/other categorization method #2796 #641

d) Swimlanes for All Boards page #2796 #641

e) Search board name and board description

f) Some other ways, but not preferred to do those, because some features could be lost.

@e-gaulue
Copy link
Contributor Author
e-gaulue commented May 7, 2021

Today, I'm looking for tools to profile wekan mongo.

Pages rendering is too slow despite proc/memory/IO is all right (8 Vcores, 8 Go, 40 Go SSD). Mongo process is often 100% but I believe on one core only.

Cards table is 1.5 Go, but I think, it's not involved. My page is slow to render a lot before any request are done to Cards. And while on page all the rest is fluent. I'm quite sure this it's slow because of my Board table.

Board table is 80 Mo. If I set internalQueryExecMaxBlockingSortBytes above this value I don't see Exception anymore in the logs, but the board never come. When I say the board never come, I mean a specific board ie. http://localhost:3001/b/board_id/whatever. When I put back the previous value, the board is coming but after 20 sec and Exceptions.

I'm rather new to Mongo. So any advices and tools are welcome. Here are the logs observed between the time I call the link and the one I get the board. I believe some 'conn' are not linked to my page loading but just to background requests.

May  7 10:37:40 hcrm mongod.27019[14999]: [conn11] received client metadata from 127.0.0.1:39128 conn11: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:40 hcrm mongod.27019[14999]: [conn11] command admin.$cmd command: isMaster { ismaster: true, client: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" } } keyUpdates:0 writeConflicts:0 numYields:0 reslen:178 locks:{} protocol:op_query 4117ms
May  7 10:37:40 hcrm mongod.27019[14999]: [conn18] received client metadata from 127.0.0.1:39142 conn18: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:40 hcrm mongod.27019[14999]: [conn3] Plan executor error during find command: FAILURE, stats: { stage: "PROJECTION", nReturned: 0, executionTimeMillisEstimate: 70, works: 11199, advanced: 0, needTime: 11198, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, transformBy: { _id: 1, boardId: 1, archived: 1, slug: 1, title: 1, description: 1, color: 1, members: 1, permission: 1, type: 1, sort: 1 }, inputStage: { stage: "SORT", nReturned: 0, executionTimeMillisEstimate: 70, works: 11199, advanced: 0, needTime: 11198, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, sortPattern: { sort: 1 }, memUsage: 33557135, memLimit: 33554432, inputStage: { stage: "SORT_KEY_GENERATOR", nReturned: 0, executionTimeMillisEstimate: 60, works: 11198, advanced: 0, needTime: 2, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, inputStage: { stage: "COLLSCAN", filter: { $and: [ { $or: [ { members: { $elemMatch: { $and: [ { isActive: { $eq: true } }, { userId: { $eq: "harymQHiFGaqxhn7o" } } ] } } }, { permission: { $eq: "public" } } ] }, { archived: { $eq: false } } ] }, nReturned: 11196, executionTimeMillisEstimate: 30, works: 11197, advanced: 11196, needTime: 1, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, direction: "forward", docsExamined: 11196 } } } }
May  7 10:37:40 hcrm mongod.27019[14999]: [conn18] command admin.$cmd command: isMaster { ismaster: true, client: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" } } keyUpdates:0 writeConflicts:0 numYields:0 reslen:178 locks:{} protocol:op_query 4117ms
May  7 10:37:40 hcrm mongod.27019[14999]: [conn3] command wekan.boards command: find { find: "boards", filter: { archived: false, $or: [ { permission: "public" }, { members: { $elemMatch: { userId: "harymQHiFGaqxhn7o", isActive: true } } } ] }, sort: { sort: 1 }, projection: { _id: 1, boardId: 1, archived: 1, slug: 1, title: 1, description: 1, color: 1, members: 1, permission: 1, type: 1, sort: 1 }, returnKey: false, showRecordId: false } keyUpdates:0 writeConflicts:0 numYields:87 reslen:237 locks:{ Global: { acquireCount: { r: 176 } }, Database: { acquireCount: { r: 88 } }, Collection: { acquireCount: { r: 88 } } } protocol:op_query 114ms
May  7 10:37:40 hcrm wekan.wekan[12885]: error caught on publication:  boards :  Exception while polling query {"collectionName":"boards","selector":{"archived":false,"$or":[{"permission":"public"},{"members":{"$elemMatch":{"userId":"harymQHiFGaqxhn7o","isActive":true}}}]},"options":{"fields":{"_id":1,"boardId":1,"archived":1,"slug":1,"title":1,"description":1,"color":1,"members":1,"permission":1,"type":1,"sort":1},"sort":{"sort":1}}}: Executor error during find command: OperationFailed: Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit.
May  7 10:37:44 hcrm mongod.27019[14999]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 206ms
May  7 10:37:44 hcrm mongod.27019[14999]: [conn8] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 402ms
May  7 10:37:45 hcrm mongod.27019[14999]: [conn11] received client metadata from 127.0.0.1:39128 conn11: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:45 hcrm mongod.27019[14999]: [conn18] received client metadata from 127.0.0.1:39142 conn18: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:50 hcrm mongod.27019[14999]: [conn11] received client metadata from 127.0.0.1:39128 conn11: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:50 hcrm mongod.27019[14999]: [conn18] received client metadata from 127.0.0.1:39142 conn18: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:54 hcrm mongod.27019[14999]: [conn8] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 220ms
May  7 10:37:54 hcrm mongod.27019[14999]: [conn12] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 390ms
May  7 10:37:55 hcrm mongod.27019[14999]: [conn11] received client metadata from 127.0.0.1:39128 conn11: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:37:55 hcrm mongod.27019[14999]: [conn18] received client metadata from 127.0.0.1:39142 conn18: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:38:01 hcrm mongod.27019[14999]: [conn11] received client metadata from 127.0.0.1:39128 conn11: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:38:01 hcrm mongod.27019[14999]: [conn22] received client metadata from 127.0.0.1:39152 conn22: { driver: { name: "nodejs", version: "2.2.9" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "Node.js v12.22.1, LE, mongodb-core: 2.0.11" }
May  7 10:38:03 hcrm mongod.27019[14999]: [conn2] Plan executor error during find command: FAILURE, stats: { stage: "PROJECTION", nReturned: 0, executionTimeMillisEstimate: 60, works: 11199, advanced: 0, needTime: 11198, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, transformBy: { _id: 1, boardId: 1, archived: 1, slug: 1, title: 1, description: 1, color: 1, members: 1, permission: 1, type: 1, sort: 1 }, inputStage: { stage: "SORT", nReturned: 0, executionTimeMillisEstimate: 60, works: 11199, advanced: 0, needTime: 11198, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, sortPattern: { sort: 1 }, memUsage: 33557135, memLimit: 33554432, inputStage: { stage: "SORT_KEY_GENERATOR", nReturned: 0, executionTimeMillisEstimate: 40, works: 11198, advanced: 0, needTime: 2, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, inputStage: { stage: "COLLSCAN", filter: { $and: [ { $or: [ { members: { $elemMatch: { $and: [ { isActive: { $eq: true } }, { userId: { $eq: "harymQHiFGaqxhn7o" } } ] } } }, { permission: { $eq: "public" } } ] }, { archived: { $eq: false } } ] }, nReturned: 11196, executionTimeMillisEstimate: 40, works: 11197, advanced: 11196, needTime: 1, needYield: 0, saveState: 87, restoreState: 87, isEOF: 0, invalidates: 0, direction: "forward", docsExamined: 11196 } } } }
May  7 10:38:03 hcrm wekan.wekan[12885]: Exception from sub boards id SEpn8AdbAdN5juTSW Error: Exception while polling query {"collectionName":"boards","selector":{"archived":false,"$or":[{"permission":"public"},{"members":{"$elemMatch":{"userId":"harymQHiFGaqxhn7o","isActive":true}}}]},"options":{"fields":{"_id":1,"boardId":1,"archived":1,"slug":1,"title":1,"description":1,"color":1,"members":1,"permission":1,"type":1,"sort":1},"sort":{"sort":1}}}: Executor error during find command: OperationFailed: Sort operation used more than the maximum 33554432 bytes of RAM. Add an index, or specify a smaller limit.
May  7 10:38:03 hcrm wekan.wekan[12885]:     at PollingObserveDriver._pollMongo (packages/mongo/polling_observe_driver.js:165:11)
May  7 10:38:03 hcrm wekan.wekan[12885]:     at Object.task (packages/mongo/polling_observe_driver.js:93:12)
May  7 10:38:03 hcrm wekan.wekan[12885]:     at Meteor._SynchronousQueue.SQp._run (packages/meteor.js:917:16)
May  7 10:38:03 hcrm wekan.wekan[12885]:     at packages/meteor.js:894:12
May  7 10:38:04 hcrm mongod.27019[14999]: [conn13] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 239ms
May  7 10:38:04 hcrm mongod.27019[14999]: [conn8] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 410ms

20 secs is the classic time I need to see the board. Sometime, I even wonder, if it's not a kind of time out.

I also see regular May 7 10:37:54 hcrm mongod.27019[14999]: [conn8] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 220ms May 7 10:37:54 hcrm mongod.27019[14999]: [conn12] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240961 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 390ms I have to investigate.

Regards,

@xet7
Copy link
Member
xet7 commented May 7, 2021

See #3345 (comment) and 9db3c93

@xet7
Copy link
Member
xet7 commented May 7, 2021

Also see https://github.com/wekan/wekan/wiki/Debugging about debugging related stuff. Not everything is added to Wekan, and Wekan does not use Apollo or GraphQL, I prefer current Wekan REST API.

@xet7
Copy link
Member
xet7 commented May 7, 2021

Usually with any new optimization added to Wekan, it speeds up a lot.

@e-gaulue
Copy link
Contributor Author
e-gaulue commented May 7, 2021

Great ! I was on https://github.com/wekan/wekan/wiki/Logs.

@xet7
Copy link
Member
xet7 commented May 7, 2021

For MongoDB GUI, there is also DBGate https://github.com/wekan/wekan/wiki/Backup#dbgate-open-source-mongodb-gui , you can test does it have some useful features.

@xet7
Copy link
Member
xet7 commented May 7, 2021

You see current indexes with:

cd wekan
./find.sh ensureIndex

@e-gaulue
Copy link
Contributor Author
e-gaulue commented May 7, 2021

Here we are completely stuck.

It already arrived yesterday. And this time, I think it's linked with db.cfs.attachments.filerecord. Suddenly, while working directly on the db, wekan go away. node process is still there, but nothing is listening on port 3001 anymore. snap restart wekan doesn't change anything and of course rebooting neither.

The only thing we see in the log /var/log/syslog are:

May  7 18:04:25 hcrm mongod.27019[2746]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 183ms
May  7 18:04:30 hcrm mongod.27019[2746]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 367ms

While restarting we have:

May  7 18:11:20 hcrm systemd[1]: Started Service for snap application wekan.caddy.
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] got signal 15 (Terminated), will terminate after current cmd ends
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] Shutting down full-time diagnostic data capture
May  7 18:11:20 hcrm systemd[1]: Stopping Service for snap application wekan.mongodb...
May  7 18:11:20 hcrm systemd[1]: snap.wekan.mongodb.service: Main process exited, code=killed, status=15/TERM
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] now exiting
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] shutdown: going to close listening sockets...
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] closing listening socket: 6
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] closing listening socket: 7
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] removing socket file: /tmp/mongodb-27019.sock
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] shutdown: going to flush diaglog...
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] shutdown: going to close sockets...
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] WiredTigerKVEngine shutting down
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] shutdown: removing fs lock...
May  7 18:11:20 hcrm mongod.27019[2746]: [signalProcessingThread] dbexit:  rc: 0
May  7 18:11:20 hcrm systemd[1]: snap.wekan.mongodb.service: Succeeded.
May  7 18:11:20 hcrm wekan.caddy[2932]: DEBUG=false (default value)
May  7 18:11:20 hcrm systemd[1]: Stopped Service for snap application wekan.mongodb.
May  7 18:11:20 hcrm wekan.caddy[2932]: MONGO_LOG_DESTINATION=syslog
May  7 18:11:20 hcrm wekan.caddy[2932]: MONGO_URL= (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: MONGODB_BIND_UNIX_SOCKET= (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: MONGO_URL= (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: MONGODB_BIND_IP=127.0.0.1 (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: MONGODB_PORT=27019
May  7 18:11:20 hcrm wekan.caddy[2932]: MAIL_URL=smtp://user:pass@mailserver.examples.com:25/ (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: MAIL_FROM=wekan-admin@example.com (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: ROOT_URL=http://hcrm.echoppe.stage
May  7 18:11:20 hcrm wekan.caddy[2932]: PORT=3001
May  7 18:11:20 hcrm wekan.caddy[2932]: DISABLE_MONGODB=false (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: CADDY_ENABLED=false (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: CADDY_BIND_PORT=3001 (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: WITH_API=true (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: RICHER_CARD_COMMENT_EDITOR=false (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: CARD_OPENED_WEBHOOK_ENABLED=false (default value)
May  7 18:11:20 hcrm wekan.caddy[2932]: ACCOUNTS_LOCKOUT_KNOWN_USERS_FAILURES_BEFORE=3 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: ACCOUNTS_LOCKOUT_KNOWN_USERS_PERIOD=60 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: ACCOUNTS_LOCKOUT_KNOWN_USERS_FAILURE_WINDOW=15 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_FAILURES_BERORE=3 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_LOCKOUT_PERIOD=60 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_FAILURE_WINDOW=15 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: MAX_IMAGE_PIXEL= (default value)
May  7 18:11:21 hcrm systemd[1]: Started Service for snap application wekan.mongodb.
May  7 18:11:21 hcrm wekan.caddy[2932]: IMAGE_COMPRESS_RATIO= (default value)
May  7 18:11:21 hcrm systemd[1]: Stopping Service for snap application wekan.wekan...
May  7 18:11:21 hcrm systemd[1]: snap.wekan.wekan.service: Main process exited, code=killed, status=15/TERM
May  7 18:11:21 hcrm systemd[1]: snap.wekan.wekan.service: Succeeded.
May  7 18:11:21 hcrm systemd[1]: Stopped Service for snap application wekan.wekan.
May  7 18:11:21 hcrm wekan.caddy[2932]: BIGEVENTS_PATTERN=NONE (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: NOTIFICATION_TRAY_AFTER_READ_DAYS_BEFORE_REMOVE= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: NOTIFY_DUE_DAYS_BEFORE_AND_AFTER= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: NOTIFY_DUE_AT_HOUR_OF_DAY= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: EMAIL_NOTIFICATION_TIMEOUT=30000 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: CORS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: CORS_ALLOW_HEADERS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: CORS_EXPOSE_HEADERS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: MATOMO_ADDRESS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: MATOMO_SITE_ID= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: MATOMO_DO_NOT_TRACK=true (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: DEBUG=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: MATOMO_WITH_USERNAME=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MONGO_LOG_DESTINATION=syslog
May  7 18:11:21 hcrm wekan.caddy[2932]: BROWSER_POLICY_ENABLED=false
May  7 18:11:21 hcrm wekan.mongodb[3120]: MONGO_URL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: TRUSTED_URL= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MONGODB_BIND_UNIX_SOCKET= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: WEBHOOKS_ATTRIBUTES= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MONGO_URL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MONGODB_BIND_IP=127.0.0.1 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_CA_CERT= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MONGODB_PORT=27019
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_LOGIN_STYLE=redirect (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MAIL_URL=smtp://user:pass@mailserver.examples.com:25/ (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_CLIENT_ID= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MAIL_FROM=wekan-admin@example.com (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_SECRET= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ROOT_URL=http://hcrm.echoppe.stage
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_SERVER_URL= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: PORT=3001
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_AUTH_ENDPOINT= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: DISABLE_MONGODB=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_USERINFO_ENDPOINT= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: CADDY_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_TOKEN_ENDPOINT= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: CADDY_BIND_PORT=3001 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_ID_MAP= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: WITH_API=true (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_USERNAME_MAP= (default value)
May  7 18:11:21 hcrm systemd[1]: Started Service for snap application wekan.wekan.
May  7 18:11:21 hcrm wekan.mongodb[3120]: RICHER_CARD_COMMENT_EDITOR=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_FULLNAME_MAP= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: CARD_OPENED_WEBHOOK_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_ID_TOKEN_WHITELIST_FIELDS= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ACCOUNTS_LOCKOUT_KNOWN_USERS_FAILURES_BEFORE=3 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_EMAIL_MAP= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ACCOUNTS_LOCKOUT_KNOWN_USERS_PERIOD=60 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_REQUEST_PERMISSIONS='openid profile email' (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ACCOUNTS_LOCKOUT_KNOWN_USERS_FAILURE_WINDOW=15 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: OAUTH2_ADFS_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_FAILURES_BERORE=3 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_ENABLE=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_LOCKOUT_PERIOD=60 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_PORT=389 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_FAILURE_WINDOW=15 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_HOST= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MAX_IMAGE_PIXEL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_BASEDN= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: IMAGE_COMPRESS_RATIO= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: DEBUG=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_LOGIN_FALLBACK=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: BIGEVENTS_PATTERN=NONE (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MONGO_LOG_DESTINATION=syslog
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_RECONNECT=true (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: NOTIFICATION_TRAY_AFTER_READ_DAYS_BEFORE_REMOVE= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MONGO_URL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_TIMEOUT=10000 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: NOTIFY_DUE_DAYS_BEFORE_AND_AFTER= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MONGODB_BIND_UNIX_SOCKET= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_IDLE_TIMEOUT=10000 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: NOTIFY_DUE_AT_HOUR_OF_DAY= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MONGO_URL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_CONNECT_TIMEOUT=10000 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: EMAIL_NOTIFICATION_TIMEOUT=30000 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_AUTHENTIFICATION=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MONGODB_BIND_IP=127.0.0.1 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: CORS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_AUTHENTIFICATION_USERDN= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MONGODB_PORT=27019
May  7 18:11:21 hcrm wekan.mongodb[3120]: CORS_ALLOW_HEADERS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_AUTHENTIFICATION_PASSWORD= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MAIL_URL=smtp://user:pass@mailserver.examples.com:25/ (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: CORS_EXPOSE_HEADERS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_LOG_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MAIL_FROM=wekan-admin@example.com (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MATOMO_ADDRESS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_BACKGROUND_SYNC=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ROOT_URL=http://hcrm.echoppe.stage
May  7 18:11:21 hcrm wekan.mongodb[3120]: MATOMO_SITE_ID= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_BACKGROUND_SYNC_INTERVAL= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MATOMO_DO_NOT_TRACK=true (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: PORT=3001
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_BACKGROUND_SYNC_KEEP_EXISTANT_USERS_UPDATED=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: MATOMO_WITH_USERNAME=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: DISABLE_MONGODB=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_BACKGROUND_SYNC_IMPORT_NEW_USERS=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: BROWSER_POLICY_ENABLED=false
May  7 18:11:21 hcrm wekan.wekan[3420]: CADDY_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_ENCRYPTION=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: TRUSTED_URL= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: CADDY_BIND_PORT=3001 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: WEBHOOKS_ATTRIBUTES= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_CA_CERT= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: WITH_API=true (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_REJECT_UNAUTHORIZED=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: RICHER_CARD_COMMENT_EDITOR=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_USER_AUTHENTICATION=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_CA_CERT= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: CARD_OPENED_WEBHOOK_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_USER_AUTHENTICATION_FIELD=uid (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_LOGIN_STYLE=redirect (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ACCOUNTS_LOCKOUT_KNOWN_USERS_FAILURES_BEFORE=3 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_USER_SEARCH_FILTER= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_CLIENT_ID= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ACCOUNTS_LOCKOUT_KNOWN_USERS_PERIOD=60 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_USER_SEARCH_SCOPE= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_SECRET= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ACCOUNTS_LOCKOUT_KNOWN_USERS_FAILURE_WINDOW=15 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_SERVER_URL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_USER_SEARCH_FIELD= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_AUTH_ENDPOINT= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_FAILURES_BERORE=3 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SEARCH_PAGE_SIZE=10
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_USERINFO_ENDPOINT= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SEARCH_SIZE_LIMIT=0 (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_LOCKOUT_PERIOD=60 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_TOKEN_ENDPOINT= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_GROUP_FILTER_ENABLE=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: ACCOUNTS_LOCKOUT_UNKNOWN_USERS_FAILURE_WINDOW=15 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_ID_MAP= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_GROUP_FILTER_OBJECTCLASS= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MAX_IMAGE_PIXEL= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_USERNAME_MAP= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_GROUP_FILTER_GROUP_ID_ATTRIBUTE= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: IMAGE_COMPRESS_RATIO= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_FULLNAME_MAP= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_GROUP_FILTER_GROUP_MEMBER_ATTRIBUTE= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: BIGEVENTS_PATTERN=NONE (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_ID_TOKEN_WHITELIST_FIELDS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_GROUP_FILTER_GROUP_MEMBER_FORMAT= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: NOTIFICATION_TRAY_AFTER_READ_DAYS_BEFORE_REMOVE= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_EMAIL_MAP= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_GROUP_FILTER_GROUP_NAME= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: NOTIFY_DUE_DAYS_BEFORE_AND_AFTER= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_REQUEST_PERMISSIONS='openid profile email' (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_UNIQUE_IDENTIFIER_FIELD= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: NOTIFY_DUE_AT_HOUR_OF_DAY= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: OAUTH2_ADFS_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_UTF8_NAMES_SLUGIFY=true (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: EMAIL_NOTIFICATION_TIMEOUT=30000 (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_ENABLE=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_USERNAME_FIELD= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: CORS= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_PORT=389 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_FULLNAME_FIELD= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: CORS_ALLOW_HEADERS= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_HOST= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_M
8000
ERGE_EXISTING_USERS=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: CORS_EXPOSE_HEADERS= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_BASEDN= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SYNC_USER_DATA=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_LOGIN_FALLBACK=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MATOMO_ADDRESS= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SYNC_USER_DATA_FIELDMAP= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_RECONNECT=true (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MATOMO_SITE_ID= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SYNC_GROUP_ROLES= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_TIMEOUT=10000 (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MATOMO_DO_NOT_TRACK=true (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_DEFAULT_DOMAIN= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: MATOMO_WITH_USERNAME=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_IDLE_TIMEOUT=10000 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_EMAIL_MATCH_ENABLE=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: BROWSER_POLICY_ENABLED=false
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_CONNECT_TIMEOUT=10000 (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_EMAIL_MATCH_REQUIRE=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_AUTHENTIFICATION=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: TRUSTED_URL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_EMAIL_MATCH_VERIFIED=false (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_AUTHENTIFICATION_USERDN= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: WEBHOOKS_ATTRIBUTES= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_EMAIL_FIELD= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_AUTHENTIFICATION_PASSWORD= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: OAUTH2_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SYNC_ADMIN_STATUS= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_LOG_ENABLED=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: OAUTH2_CA_CERT= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: LDAP_SYNC_ADMIN_GROUPS= (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_BACKGROUND_SYNC=false (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: OAUTH2_LOGIN_STYLE=redirect (default value)
May  7 18:11:21 hcrm wekan.mongodb[3120]: LDAP_BACKGROUND_SYNC_INTERVAL= (default value)
May  7 18:11:21 hcrm wekan.caddy[2932]: HEADER_LOGIN_ID= (default value)
May  7 18:11:21 hcrm wekan.wekan[3420]: OAUTH2_CLIENT_ID= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_BACKGROUND_SYNC_KEEP_EXISTANT_USERS_UPDATED=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: HEADER_LOGIN_FIRSTNAME=Header login firstname. Example for siteminder: HEADERFIRSTNAME (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_SECRET= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_BACKGROUND_SYNC_IMPORT_NEW_USERS=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: HEADER_LOGIN_LASTNAME=Header login firstname. Example for siteminder: HEADERLASTNAME (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_SERVER_URL= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_ENCRYPTION=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: HEADER_LOGIN_EMAIL=Header login email. Example for siteminder: HEADEREMAILADDRESS (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_AUTH_ENDPOINT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_CA_CERT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_USERINFO_ENDPOINT= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: LOGOUT_WITH_TIMER=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_REJECT_UNAUTHORIZED=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: LOGOUT_IN= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_TOKEN_ENDPOINT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_USER_AUTHENTICATION=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: LOGOUT_ON_HOURS= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_ID_MAP= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: LOGOUT_ON_MINUTES= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_USER_AUTHENTICATION_FIELD=uid (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_USERNAME_MAP= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: DEFAULT_AUTHENTICATION_METHOD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_USER_SEARCH_FILTER= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: ATTACHMENTS_STORE_PATH=/var/snap/wekan/common/attachments
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_FULLNAME_MAP= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_USER_SEARCH_SCOPE= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: PASSWORD_LOGIN_ENABLED=true (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_ID_TOKEN_WHITELIST_FIELDS= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_USER_SEARCH_FIELD= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: CAS_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_EMAIL_MAP= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SEARCH_PAGE_SIZE=10
May  7 18:11:22 hcrm wekan.caddy[2932]: CAS_BASE_URL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_REQUEST_PERMISSIONS='openid profile email' (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SEARCH_SIZE_LIMIT=0 (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: OAUTH2_ADFS_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: CAS_LOGIN_URL= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_GROUP_FILTER_ENABLE=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_ENABLE=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: CAS_VALIDATE_URL= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_GROUP_FILTER_OBJECTCLASS= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_PORT=389 (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_GROUP_FILTER_GROUP_ID_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_HOST= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_PROVIDER= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_GROUP_FILTER_GROUP_MEMBER_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_BASEDN= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_ENTRYPOINT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_GROUP_FILTER_GROUP_MEMBER_FORMAT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_LOGIN_FALLBACK=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_ISSUER= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_GROUP_FILTER_GROUP_NAME= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_RECONNECT=true (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_CERT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_UNIQUE_IDENTIFIER_FIELD= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_IDPSLO_REDIRECTURL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_TIMEOUT=10000 (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_UTF8_NAMES_SLUGIFY=true (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_PRIVATE_KEYFILE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_IDLE_TIMEOUT=10000 (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_USERNAME_FIELD= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_PUBLIC_CERTFILE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_CONNECT_TIMEOUT=10000 (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_IDENTIFIER_FORMAT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_FULLNAME_FIELD= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_AUTHENTIFICATION=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_LOCAL_PROFILE_MATCH_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_MERGE_EXISTING_USERS=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_AUTHENTIFICATION_USERDN= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: SAML_ATTRIBUTES= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_AUTHENTIFICATION_PASSWORD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SYNC_USER_DATA=false (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: ORACLE_OIM_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_LOG_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SYNC_USER_DATA_FIELDMAP= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: RESULTS_PER_PAGE= (default value)
May  7 18:11:22 hcrm wekan.caddy[2932]: caddy is disabled. Stop service
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_BACKGROUND_SYNC=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SYNC_GROUP_ROLES= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_BACKGROUND_SYNC_INTERVAL= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_DEFAULT_DOMAIN= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_EMAIL_MATCH_ENABLE=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_BACKGROUND_SYNC_KEEP_EXISTANT_USERS_UPDATED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_BACKGROUND_SYNC_IMPORT_NEW_USERS=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_EMAIL_MATCH_REQUIRE=false (default value)
May  7 18:11:22 hcrm systemd[1]: Stopping Service for snap application wekan.caddy...
May  7 18:11:22 hcrm systemd[1]: snap.wekan.caddy.service: Main process exited, code=killed, status=15/TERM
May  7 18:11:22 hcrm systemd[1]: snap.wekan.caddy.service: Succeeded.
May  7 18:11:22 hcrm systemd[1]: Stopped Service for snap application wekan.caddy.
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_EMAIL_MATCH_VERIFIED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_ENCRYPTION=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_EMAIL_FIELD= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_CA_CERT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SYNC_ADMIN_STATUS= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_REJECT_UNAUTHORIZED=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LDAP_SYNC_ADMIN_GROUPS= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_USER_AUTHENTICATION=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: HEADER_LOGIN_ID= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_USER_AUTHENTICATION_FIELD=uid (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: HEADER_LOGIN_FIRSTNAME=Header login firstname. Example for siteminder: HEADERFIRSTNAME (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_USER_SEARCH_FILTER= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: HEADER_LOGIN_LASTNAME=Header login firstname. Example for siteminder: HEADERLASTNAME (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_USER_SEARCH_SCOPE= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: HEADER_LOGIN_EMAIL=Header login email. Example for siteminder: HEADEREMAILADDRESS (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_USER_SEARCH_FIELD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LOGOUT_WITH_TIMER=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SEARCH_PAGE_SIZE=10
May  7 18:11:22 hcrm wekan.mongodb[3120]: LOGOUT_IN= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SEARCH_SIZE_LIMIT=0 (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LOGOUT_ON_HOURS= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_GROUP_FILTER_ENABLE=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: LOGOUT_ON_MINUTES= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_GROUP_FILTER_OBJECTCLASS= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: DEFAULT_AUTHENTICATION_METHOD= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_GROUP_FILTER_GROUP_ID_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: ATTACHMENTS_STORE_PATH=/var/snap/wekan/common/attachments
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_GROUP_FILTER_GROUP_MEMBER_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: PASSWORD_LOGIN_ENABLED=true (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_GROUP_FILTER_GROUP_MEMBER_FORMAT= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: CAS_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_GROUP_FILTER_GROUP_NAME= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: CAS_BASE_URL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_UNIQUE_IDENTIFIER_FIELD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: CAS_LOGIN_URL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_UTF8_NAMES_SLUGIFY=true (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: CAS_VALIDATE_URL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_USERNAME_FIELD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_FULLNAME_FIELD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_PROVIDER= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_MERGE_EXISTING_USERS=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_ENTRYPOINT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SYNC_USER_DATA=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_ISSUER= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SYNC_USER_DATA_FIELDMAP= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_CERT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SYNC_GROUP_ROLES= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_IDPSLO_REDIRECTURL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_DEFAULT_DOMAIN= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_PRIVATE_KEYFILE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_EMAIL_MATCH_ENABLE=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_PUBLIC_CERTFILE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_EMAIL_MATCH_REQUIRE=false (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_IDENTIFIER_FORMAT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_EMAIL_MATCH_VERIFIED=false (default value)
May  7 18:11:22 hcrm systemd[1]: Reloading.
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_LOCAL_PROFILE_MATCH_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_EMAIL_FIELD= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: SAML_ATTRIBUTES= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SYNC_ADMIN_STATUS= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: ORACLE_OIM_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LDAP_SYNC_ADMIN_GROUPS= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: RESULTS_PER_PAGE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: HEADER_LOGIN_ID= (default value)
May  7 18:11:22 hcrm wekan.mongodb[3120]: mongodb bind options:  --bind_ip 127.0.0.1 --port 27019
May  7 18:11:22 hcrm wekan.mongodb[3120]: Sending mongodb logs to syslog
May  7 18:11:22 hcrm wekan.wekan[3420]: HEADER_LOGIN_FIRSTNAME=Header login firstname. Example for siteminder: HEADERFIRSTNAME (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: HEADER_LOGIN_LASTNAME=Header login firstname. Example for siteminder: HEADERLASTNAME (default value)
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] MongoDB starting : pid=5315 port=27019 dbpath=/var/snap/wekan/common 64-bit host=hcrm
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] db version v3.2.22
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] git version: 105acca0d443f9a47c1a5bd608fd7133840a58dd
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] OpenSSL version: OpenSSL 1.0.2g  1 Mar 2016
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] allocator: tcmalloc
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] modules: none
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] build environment:
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten]     distmod: ubuntu1604
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten]     distarch: x86_64
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten]     target_arch: x86_64
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] options: { net: { bindIp: "127.0.0.1", port: 27019 }, storage: { dbPath: "/var/snap/wekan/common", journal: { enabled: true } }, systemLog: { destination: "syslog", quiet: true } }
May  7 18:11:22 hcrm wekan.wekan[3420]: HEADER_LOGIN_EMAIL=Header login email. Example for siteminder: HEADEREMAILADDRESS (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LOGOUT_WITH_TIMER=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LOGOUT_IN= (default value)
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] Detected data files in /var/snap/wekan/common created by the 'wiredTiger' storage engine, so setting the active storage engine to 'wiredTiger'.
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] wiredtiger_open config: create,cache_size=4G,session_max=20000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,archive=true,path=journal,compressor=snappy),file_manager=(close_idle_time=100000),checkpoint=(wait=60,log_size=2GB),statistics_log=(wait=0),verbose=(recovery_progress),
May  7 18:11:22 hcrm wekan.wekan[3420]: LOGOUT_ON_HOURS= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: LOGOUT_ON_MINUTES= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: DEFAULT_AUTHENTICATION_METHOD= (default value)
May  7 18:11:22 hcrm systemd[1]: /etc/systemd/system/wekan.service:16: WorkingDirectory= path is not absolute: ~/Projet/wekan/
May  7 18:11:22 hcrm wekan.wekan[3420]: ATTACHMENTS_STORE_PATH=/var/snap/wekan/common/attachments
May  7 18:11:22 hcrm systemd[1]: /lib/systemd/system/mongod.service:11: PIDFile= references path below legacy directory /var/run/, updating /var/run/mongodb/mongod.pid → /run/mongodb/mongod.pid; please update the unit file accordingly.
May  7 18:11:22 hcrm wekan.wekan[3420]: PASSWORD_LOGIN_ENABLED=true (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: CAS_ENABLED=false (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: CAS_BASE_URL= (default value)
May  7 18:11:22 hcrm systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
May  7 18:11:22 hcrm wekan.wekan[3420]: CAS_LOGIN_URL= (default value)
May  7 18:11:22 hcrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
May  7 18:11:22 hcrm wekan.wekan[3420]: CAS_VALIDATE_URL= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_ENABLED=false (default value)
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] WiredTiger [1620403882:881606][5315:0x7f1918af6c80], txn-recover: Main recovery loop: starting at 150/8320
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_PROVIDER= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_ENTRYPOINT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_ISSUER= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_CERT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_IDPSLO_REDIRECTURL= (default value)
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] WiredTiger [1620403882:953206][5315:0x7f1918af6c80], txn-recover: Recovering log 150 through 151
May  7 18:11:22 hcrm mongod.27019[5315]: [initandlisten] WiredTiger [1620403882:954024][5315:0x7f1918af6c80], txn-recover: Recovering log 151 through 151
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_PRIVATE_KEYFILE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_PUBLIC_CERTFILE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_IDENTIFIER_FORMAT= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_LOCAL_PROFILE_MATCH_ATTRIBUTE= (default value)
May  7 18:11:22 hcrm wekan.wekan[3420]: SAML_ATTRIBUTES= (default value)
May  7 18:11:23 hcrm wekan.wekan[3420]: ORACLE_OIM_ENABLED=false (default value)
May  7 18:11:23 hcrm wekan.wekan[3420]: RESULTS_PER_PAGE= (default value)
May  7 18:11:23 hcrm wekan.wekan[3420]: MONGO_URL=mongodb://127.0.0.1:27019/wekan
May  7 18:11:23 hcrm wekan.wekan[3420]: Warning: Ignoring extra certs from `/usr/local/share/ca-certificates/myCA.crt`, load failed: error:02001002:system library:fopen:No such file or directory
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] ** WARNING: You are running this process as the root user, which is not recommended.
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] 
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] 
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] ** WARNING: /sys/kernel/mm/transparent_hugepage/enabled is 'always'.
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] **        We suggest setting it to 'never'
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] 
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] Error getting directory iterator '/sys/block': Permission denied
May  7 18:11:23 hcrm mongod.27019[5315]: [HostnameCanonicalizationWorker] Starting hostname canonicalization worker
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] Initializing full-time diagnostic data capture with directory '/var/snap/wekan/common/diagnostic.data'
May  7 18:11:23 hcrm kernel: [ 1347.738136] audit: type=1400 audit(1620403883.310:30): apparmor="DENIED" operation="open" profile="snap.wekan.mongodb" name="/sys/block/" pid=5315 comm="mongod" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
May  7 18:11:23 hcrm mongod.27019[5315]: [initandlisten] waiting for connections on port 27019
May  7 18:11:25 hcrm mongod.27019[5315]: [conn1] received client metadata from 127.0.0.1:44168 conn1: { driver: { name: "nodejs", version: "3.6.6" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "'Node.js v12.22.1, LE (unified)" }
May  7 18:11:25 hcrm mongod.27019[5315]: [conn2] received client metadata from 127.0.0.1:44170 conn2: { driver: { name: "nodejs", version: "3.6.6" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "'Node.js v12.22.1, LE (unified)" }
May  7 18:11:26 hcrm wekan.wekan[3420]: Presence started serverId=W3JRDiQJML4t778Dr
May  7 18:11:27 hcrm mongod.27019[5315]: [conn3] received client metadata from 127.0.0.1:44172 conn3: { driver: { name: "nodejs", version: "3.6.6" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "'Node.js v12.22.1, LE (unified)" }
May  7 18:11:27 hcrm mongod.27019[5315]: [conn4] received client metadata from 127.0.0.1:44174 conn4: { driver: { name: "nodejs", version: "3.6.6" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "'Node.js v12.22.1, LE (unified)" }
May  7 18:11:28 hcrm wekan.wekan[3420]: (node:5513) DeprecationWarning: current Server Discovery and Monitoring engine is deprecated, and will be removed in a future version. To use the new Server Discover and Monitoring engine, pass option { useUnifiedTopology: true } to the MongoClient constructor.
May  7 18:11:28 hcrm mongod.27019[5315]: [conn5] received client metadata from 127.0.0.1:44176 conn5: { driver: { name: "nodejs", version: "3.6.2" }, os: { type: "Linux", name: "linux", architecture: "x64", version: "4.19.0-5-amd64" }, platform: "'Node.js v12.22.1, LE (legacy)" }
May  7 18:11:28 hcrm mongod.27019[5315]: [conn2] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 471ms
May  7 18:11:29 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 365ms
May  7 18:11:38 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 177ms
May  7 18:11:39 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 387ms
May  7 18:11:48 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 180ms
May  7 18:11:49 hcrm mongod.27019[5315]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 363ms
May  7 18:11:58 hcrm mongod.27019[5315]: [conn2] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 193ms
May  7 18:11:59 hcrm mongod.27019[5315]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 364ms
May  7 18:12:08 hcrm mongod.27019[5315]: [conn2] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 191ms
May  7 18:12:09 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 355ms
May  7 18:12:18 hcrm mongod.27019[5315]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 213ms
May  7 18:12:19 hcrm mongod.27019[5315]: [conn2] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 394ms
May  7 18:12:28 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 
6D40
}, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 198ms
May  7 18:12:29 hcrm mongod.27019[5315]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 373ms
May  7 18:12:38 hcrm mongod.27019[5315]: [conn4] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { uploadedAt: { $exists: true }, copies.attachments: null, failures.copies.attachments.doneTrying: { $ne: true } }, projection: { copies: 0 }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 190ms
May  7 18:12:39 hcrm mongod.27019[5315]: [conn3] command wekan.cfs.attachments.filerecord command: find { find: "cfs.attachments.filerecord", filter: { $and: [ { chunks: { $exists: true } }, { $or: [ { $and: [ { copies.attachments: { $ne: null } }, { copies.attachments: { $ne: false } } ] }, { failures.copies.attachments.doneTrying: true } ] } ] }, returnKey: false, showRecordId: false } planSummary: COLLSCAN keysExamined:0 docsExamined:240963 cursorExhausted:1 keyUpdates:0 writeConflicts:0 numYields:1882 nreturned:0 reslen:138 locks:{ Global: { acquireCount: { r: 3766 } }, Database: { acquireCount: { r: 1883 } }, Collection: { acquireCount: { r: 1883 } } } protocol:op_query 404ms

When it arrived yesterday, the only solution we found was db.cfs.attachments.filerecord.remove({}), but it's not a solution. By the way, doing that make wekan removes all the files on the disk.

I don't know why mongo/meteor/wekan (I'm lost) keeps requesting this db.cfs.attachments.filerecord, all the time, even when nobody work on wekan. And why it's stuck because of that. I'm in the end of the pit.

Regards,

@xet7
Copy link
Member
xet7 commented May 7, 2021

Meteor web framework does listen to changes at MongoDB and reacts to them immediately. For example, if some new card is added with REST API or directly to database, it will be visible immediately.

@mfilser
Copy link
Contributor
mfilser commented May 7, 2021

@xet7
You mentioned that Meteor is listening for changes to MongoDB. This is what i already ask me for weeks: Is it like polling or like pushing/notify (like inotify in Linux)

@xet7
Copy link
Member
xet7 commented May 7, 2021

@mfilser

Currently like poll and diff:
https://gith 9E88 ub.com/meteor/docs/blob/version-NEXT/long-form/oplog-observe-driver.md

Currently Wekan still uses MongoDB 3.2.22:
https://github.com/wekan/wekan/blob/master/snapcraft.yaml#L67

And it's started here:
https://github.com/wekan/wekan/blob/master/snap-src/bin/mongodb-control

Snap also still uses Ubuntu 16.04 as base. I have some test files to try to sometime get changes to Snap Ubuntu 20.04 base:
https://github.com/wekan/wekan/tree/master/.future-snap

I have sometimes tried to get Snap to user newer Ubuntu base and MongoDB but I have not got them working yet.

Docker uses newer Ubuntu base, and can use newest MongoDB 4.x.

Wekan does not have oplog set yet, using oplog would be faster. If oplog would be used, For example in Snap package:

  1. When building Snap, MongoDB package would be installed like this: https://github.com/RocketChat/Rocket.Chat/blob/develop/.snapcraft/resources/preparemongo
  2. Oplog Replicaset would be set like this https://github.com/RocketChat/Rocket.Chat/blob/develop/.snapcraft/resources/initreplset.js
  3. MongoDB would be started like this https://github.com/RocketChat/Rocket.Chat/blob/develop/.snapcraft/resources/startmongo

Even faster alternative would be to use Redis Oplog, although that would require also installing Redis:
https://github.com/cult-of-coders/redis-oplog

To send those database changes to users, Meteor uses DDP / Websockets. (Using websockets is faster than polling. If someone has restrictive F5 firewall, it could deny websocket traffic, those kind of environments prefer other polling based solutions).
https://github.com/meteor/meteor/blob/master/packages/ddp/DDP.md

Some of these are also listed at Performance section of Awesome Meteor:
https://github.com/Urigo/awesome-meteor#performance

Alternative to current Meteor Publications publish/subscribe for reactivity would be Meteor Methods this way:
https://github.com/adtribute/pub-sub-lite

About inotify for checking filesystem changes: It's not so good on Windows filesystem, it can easily cause file and filesystem corruption, that I did notice when coding some inotify Go code some years ago. Using some atomic transaction database like SQLite or some multiuser database would be better for concurrent access.

@xet7
Copy link
Member
xet7 commented May 7, 2021

For "multitasking" of with Javascript and node, Meteor currently uses fibers:
https://blog.meteor.com/meteor-fibers-meet-promises-meet-callbacks-a-practical-guide-e134db15afcb

Sometime in future Meteor will migrate from fibers to async-await.

Usually porting Wekan to other CPU is just building fibers on that platform:
https://forums.meteor.com/t/install-on-raspberry-pi/31520/13

Wekan uses async-await for for some parts already, like here where Wekan page are combined to one .zip file for Wekan board static HTML page download: https://github.com/wekan/wekan/blob/master/client/lib/exportHTML.js#L15

@mfilser
Copy link
Contributor
mfilser commented May 7, 2021

@xet7
Wow, thank you very much for your long detailed answer, i appreciate it a lot 👍
You should add this very good informations somewhere at Wekan Wiki in a Developer section.

I will read and think about all this information, and without reading them all now, i think polling isnt the best strategy, the more queries which are watched for polling the more Meteor and MongoDB needs resources like CPU.

But now, i will read all the informations first 🙂

@xet7
Copy link
Member
xet7 commented May 7, 2021

@mfilser

I added it to here https://github.com/wekan/wekan/wiki/Developer-Documentation#stories-about-how-wekan-works-and-developing-wekan to first topic "How realtime board updates work".

@xet7
Copy link
Member
xet7 commented May 7, 2021

@mfilser

Storing attachments in database causes high RAM consumption etc:
https://forums.rocket.chat/t/high-ram-consumption-on-ubuntu/11095

@xet7
Copy link
Member
xet7 commented May 7, 2021

Both Wekan and RocketChat are made with Meteor and MongoDB.

@xet7
Copy link
Member
xet7 commented May 7, 2021

@mfilser
Copy link
Contributor
mfilser commented May 7, 2021

@xet7
Thank you for your hints of storing attachements to the filesystem. Is there any migration or script to move the attachements from MongoDB to the filesystem?

@xet7
Copy link
Member
xet7 commented May 7, 2021

@mfilser

At this article https://forums.rocket.chat/t/high-ram-consumption-on-ubuntu/11095/3 is mentioned this repo https://github.com/arminfelder/gridfsmigrate for RocketChat, but it has not been tested with Wekan.

At Wekan v5.27 with this commit I did fork non-maintained CollectionFS repos to be at wekan/packages and at https://github.com/zcfs , but I did not yet add CollectionFS AWS S3 repo https://github.com/zcfs/Meteor-cfs-s3cloud to be part of Wekan. To actually maintain CollectionFS properly like I asked here https://github.com/CollectionFS/RemoveOrganisation/issues/1#issuecomment-825794625 , I should:

  1. Add all those changes to https://github.com/zcfs
  2. Remove it from wekan/packages/
  3. Publish packages at https://atmospherejs.com
  4. Use those published packages at Wekan.

@xet7
Copy link
Member
xet7 commented May 7, 2021

Currently storing files in filesystem works this way, if I got info from some email correctly:

  • If new file is added, and that filename already exists, it is stored with different filename
  • If file is deleted from card, it is also deleted from filesystem. This should be prevented by changing Wekan code, and only adding info to MongoDB that file is deleted, but not actually delete file from filesystem. Permanently deleting file should be admin-only option.

@xet7
Copy link
Member
xet7 commented May 7, 2021

About that https://github.com/zcfs , how I forked it to wekan:

  1. I cloned all repos and renamed them to wekan/packages/wekan-cfs-*
  2. With Regexxer GUI sudo apt install regexxer I did search-replace all of cfs: to wekan-cfs- at wekan/packages/, wekan/.meteor/packages, and wekan/.meteor/versions etc
  3. For some dependency that was outdated in cfs packages, I changed to newer version.

In those CollectionFS changes was already previous fixes that it could upload and download non-ASCII filename files. It's just that those changes were not released to packages at https://atmospherejs.com .

@e-gaulue
Copy link
Contributor Author

@xet7

Really, really, really interesting. We have 250 k attachments (representing 50 Go). This could explain a lot.

We are not on Ubuntu but I believe in this context Debian Buster is the same. We have installed last MongoDB from the mongo community. Wekan snap comes with its own mongodb, but through snap we can set another mongodb port. If we duplicate the data. Would using a newer mongodb be as simple as changing this port? Reading your post, I've got the feeling it's not so simple.

Other idea, is this behaviour fully intricate in the code or could it be easily disable by a setting. It's a wonderful feature and I understand it better now. I've seen Wekan was reactive to everybody actions on the same board but without imagining the constraints. I would love to have such a feature but in the meanwhile could it be disabled? We have one board for each prospect and there are no reason for operators to share the same board at any time. What we like with wekan is it's fast and easy to add a new card but we don't really use its collaborative feature for our CRM purpose. By the way, we have another instance of wekan that we really use in a collaborative way.

Right now, I have:

  • set snap to debug mode
  • set mongodb to db.setProfilingLevel(2)
    But I don't see more details in the logs.

Regards,

@e-gaulue
Copy link
Contributor Author

@xet7

Could you remind us about the reason to choose CollectionFS? What about Meteor-Files? Is there one better that the other? Is it just history?

I found this regarding Meteor-Files to avoid deletion: https://github.com/VeliovGroup/Meteor-Files/blob/master/docs/constructor.md#use-onbeforeremove-to-avoid-unauthorized-remove. I suppose it's about the same with cfs.

Regards,

@e-gaulue
Copy link
Contributor Author

Sorry, for the noise. Onbeforeremove is the right place to check permission but it doesn't help you split delete db entries and unlink file on the FS.

@xet7
Copy link
Member
xet7 commented May 10, 2021

@e-gaulue

I added summary about CollectionFS and Meteor-Files here #3273 (comment)

@xet7
Copy link
Member
xet7 commented May 10, 2021

From @e-gaulue

@xet7

Really, really, really interesting. We have 250 k attachments (representing 50 Go). This could explain a lot.

We are not on Ubuntu but I believe in this context Debian Buster is the same. We have installed last MongoDB from the mongo community. Wekan snap comes with its own mongodb, but through snap we can set another mongodb port. If we duplicate the data. Would using a newer mongodb be as simple as changing this port? Reading your post, I've got the feeling it's not so simple.

Wekan can be run without Snap. Wekan only requires node+mongodb. Prebuilt bundle info is at https://github.com/wekan/wekan/Raspberry-Pi (includes info about startup script and systemd service). Or alternatively like at https://github.com/wekan/wekan/wiki/Offline .

If you would like to build that bundle from source code, info is at https://github.com/wekan/wekan/wiki/Emoji steps 1-4.With building, bundle is at wekan/.build/bundle. That prebuild bundle is that directory in .zip archive.

That redis oplog https://github.com/cult-of-coders/redis-oplog can be installed with buiding Wekan, and then:

sudo apt-get install redis-server
cd wekan
meteor add cultofcoders:redis-oplog
meteor add disable-oplog

Then building Wekan again, and running with start-wekan.sh or as service.

Other idea, is this behaviour fully intricate in the code or could it be easily disable by a setting. It's a wonderful feature and I understand it better now. I've seen Wekan was reactive to everybody actions on the same board but without imagining the constraints. I would love to have such a feature but in the meanwhile could it be disabled?

No, it can not be disabled.

We have one board for each prospect and there are no reason for operators to share the same board at any time. What we like with wekan is it's fast and easy to add a new card but we don't really use its collaborative feature for our CRM purpose. By the way, we have another instance of wekan that we really use in a collaborative way.

You can have read-only public board.

Right now, I have:

* set snap to debug mode

* set mongodb to db.setProfilingLevel(2)
  But I don't see more details in the logs.

Snap has MongoDB logs disabled https://github.com/wekan/wekan/blob/master/snap-src/bin/mongodb-control#L92 , to see any logs you should use non-Snap install of Wekan.

Also, with #3345 (comment) I did mean you look at step 3 at Nosqlbooster database profiler, to see are there slow queries that take a lot of time to run.

@e-gaulue
Copy link
Contributor Author

Thanks @xet7, I'm going to try most of this. We manage to compile wekan 3 weeks ago, so we may manage to handle it. Wekan code looks really readable, all you say is quite understandable, but it looks hard for me. I've no skill I can rely on coming from the admin sys + PHP-MySQL and server driven world. I have to read a lot, so it's slow. But I'm pretty sure there is no reason why a 3 Gb database couldn't be serve fluently by a 8 Gb server. I also know now, that client is also involved and we have to be careful to the amount of data send to it.

I've got the felling wekan was rather fluent before we added those 250 k attachments. But we have to try again. Knowing we already created users (+/- 20) and custom values, we then create the 30 k boards, 30 k swimlanes and 30 k x 3 lists all associated to all our users. At this time, we have the message mentioned at the beginning of this issue regarding "sort out of memory", but wekan works rather fast (not as fast as the other instance we use for collaboration). Then we add 430 k cards representing 2 Go in mongo. And then we add 250 k attachments. We have to redo and see where wekan really hangs.

Anyway I can't stand using technologies I can't master. So it's a useful time as long as my boss doesn't say STOP.

@e-gaulue
Copy link
Contributor Author

Snap has MongoDB logs disabled https://github.com/wekan/wekan/blob/master/snap-src/bin/mongodb-control#L92 , to see any logs you should use non-Snap install of Wekan.

I already read this, but as I could see logs from mongodb I tough it had changed since.

@xet7
Copy link
Member
xet7 commented May 10, 2021

@e-gaulue

About Wekan code, also see https://github.com/wekan/wekan/wiki/Directory-Structure

@xet7
Copy link
Member
xet7 commented May 10, 2021

According go OpenHub https://www.openhub.net/p/wekan , coding Wekan took an estimated combined 17 years of effort (COCOMO model) of 263 contributors.

When adding some big feature, it has required some time to get it it to fit into Wekan properly, fixing related corner cases, and getting all features to play with each other properly.

@e-gaulue
Copy link
Contributor Author

So, I rebuild wekan with cultofcoders:redis-oplog, then we start wekan on the locally installed MongoDB 4.4 => OK

I stopped wekan, I move db data with mongodump/mongorestore from snap to local mongo, then restarted wekan => KO

I installed nosqlbooster and connected it to localhost:27017, and I've been to profiling.

I changed ./start-wekan.sh, uncommenting DEBUG=true adding export KEY_ATTACHMENTS_STORE_PATH='/var/snap/wekan/common/attachments'. wekan start but I don't get a lot of information on screen:

(node:11372) [DEP0131] DeprecationWarning: The legacy HTTP parser is deprecated.
Presence started serverId=szzmcq2xj8F7gMEGP

But in the mongolog wekan keeps requesting for cfs.attachements as before. We are still unable to request anything on wekan as if nodes was not listening on port 2000, but we observed the same before. Usually after a long time it works, then it stops again for a long time.

@e-gaulue
Copy link
Contributor Author

I see those query:

{
  "op": "query",
  "ns": "wekan.cfs.attachments.filerecord",
  "command": {
    "find": "cfs.attachments.filerecord",
    "filter": {
      "$and": [
        {
          "chunks": {
            "$exists": true
          }
        },
        {
          "$or": [
            {
              "$and": [
                {
                  "copies.attachments": {
                    "$ne": null
                  }
                },
                {
                  "copies.attachments": {
                    "$ne": false
                  }
                }
              ]
            },
            {
              "failures.copies.attachments.doneTrying": true
            }
          ]
        }
      ]
    },
    "returnKey": false,
    "showRecordId": false,
    "lsid": {
      "id": "UUID(\"2b3ec1b9-8e68-4442-b918-6e118b9aee3b\")"
    },
    "$db": "wekan"
  },
  "keysExamined": 0,
  "docsExamined": 240964,
  "cursorExhausted": true,
  "numYield": 240,
  "nreturned": 0,
  "queryHash": "76EB52C9",
  "planCacheKey": "FE6CC654",
  "locks": {
    "ReplicationStateTransition": {
      "acquireCount": {
        "w": "242"
      }
    },
    "Global": {
      "acquireCount": {
        "r": "242"
      }
    },
    "Database": {
      "acquireCount": {
        "r": "241"
      }
    },
    "Collection": {
      "acquireCount": {
        "r": "241"
      }
    },
    "Mutex": {
      "acquireCount": {
        "r": "1"
      }
    }
  },
  "flowControl": {},
  "storage": {},
  "responseLength": 121,
  "protocol": "op_msg",
  "millis": 465,
  "planSummary": "COLLSCAN",
  "execStats": {
    "stage": "COLLSCAN",
    "filter": {
      "$and": [
        {
          "$or": [
            {
              "$and": [
                {
                  "copies.attachments": {
                    "$not": {
                      "$eq": null
                    }
                  }
                },
                {
                  "copies.attachments": {
                    "$not": {
                      "$eq": false
                    }
                  }
                }
              ]
            },
            {
              "failures.copies.attachments.doneTrying": {
                "$eq": true
              }
            }
          ]
        },
        {
          "chunks": {
            "$exists": true
          }
        }
      ]
    },
    "nReturned": 0,
    "executionTimeMillisEstimate": 215,
    "works": 240966,
    "advanced": 0,
    "needTime": 240965,
    "needYield": 0,
    "saveState": 240,
    "restoreState": 240,
    "isEOF": 1,
    "direction": "forward",
    "docsExamined": 240964
  },
  "ts": "2021-05-11T10:09:14.138Z",
  "client": "127.0.0.1",
  "allUsers": [],
  "user": ""
}

and

{
  "op": "query",
  "ns": "wekan.cfs.attachments.filerecord",
  "command": {
    "find": "cfs.attachments.filerecord",
    "filter": {
      "uploadedAt": {
        "$exists": true
      },
      "copies.attachments": null,
      "failures.copies.attachments.doneTrying": {
        "$ne": true
      }
    },
    "projection": {
      "copies": 0
    },
    "returnKey": false,
    "showRecordId": false,
    "lsid": {
      "id": "UUID(\"b913e0dd-2089-40a9-9cfc-f4ae4a323a81\")"
    },
    "$db": "wekan"
  },
  "keysExamined": 0,
  "docsExamined": 240964,
  "cursorExhausted": true,
  "numYield": 240,
  "nreturned": 0,
  "queryHash": "F7235C79",
  "planCacheKey": "BEC4D6F9",
  "locks": {
    "ReplicationStateTransition": {
      "acquireCount": {
        "w": "242"
      }
    },
    "Global": {
      "acquireCount": {
        "r": "242"
      }
    },
    "Database": {
      "acquireCount": {
        "r": "241"
      }
    },
    "Collection": {
      "acquireCount": {
        "r": "241"
      }
    },
    "Mutex": {
      "acquireCount": {
        "r": "1"
      }
    }
  },
  "flowControl": {},
  "storage": {},
  "responseLength": 121,
  "protocol": "op_msg",
  "millis": 258,
  "planSummary": "COLLSCAN",
  "execStats": {
    "stage": "PROJECTION_DEFAULT",
    "nReturned": 0,
    "executionTimeMillisEstimate": 82,
    "works": 240966,
    "advanced": 0,
    "needTime": 240965,
    "needYield": 0,
    "saveState": 240,
    "restoreState": 240,
    "isEOF": 1,
    "transformBy": {},
    "inputStage": {
      "stage": "COLLSCAN",
      "filter": {
        "$and": [
          {
            "copies.attachments": {
              "$eq": null
            }
          },
          {
            "uploadedAt": {
              "$exists": true
            }
          },
          {
            "failures.copies.attachments.doneTrying": {
              "$not": {
                "$eq": true
              }
            }
          }
        ]
      },
      "nReturned": 0,
      "executionTimeMillisEstimate": 80,
      "works": 240966,
      "advanced": 0,
      "needTime": 240965,
      "needYield": 0,
      "saveState": 240,
      "restoreState": 240,
      "isEOF": 1,
      "direction": "forward",
      "docsExamined": 240964
    }
  },
  "ts": "2021-05-11T10:09:13.927Z",
  "client": "127.0.0.1",
  "allUsers": [],
  "user": ""
}

then plenty of batch 1000 getmore.

Looks like wekan.cfs.attachments.filerecord keeps being fully parse.

Regards,

@xet7
Copy link
Member
xet7 commented May 29, 2023

Moved to #4791

@xet7 xet7 closed this as completed May 29, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

3 participants
0