[SERVER-48210] create profile collection failed Created: 14/May/20  Updated: 06/Dec/22  Resolved: 14/May/20

Status: Closed
Project: Core Server
Component/s: Storage
Affects Version/s: 4.2.0
Fix Version/s: None

Type: Bug Priority: Major - P3
Reporter: vinllen chen Assignee: Backlog - Storage Execution Team
Resolution: Duplicate Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Issue Links:
Duplicate
duplicates SERVER-47714 Secondary asserts on system.profile c... Closed
Assigned Teams:
Storage Execution
Operating System: ALL
Participants:

 Description   

The profile level is 1 so that when `find` slow log happens, it'll insert into the profile collection: "iiisland.system.profile".
But at first, this collection does not exist, so it may create the collection first. However, this operation is failed with wiredTiger reports: "Operation not supported". It looks like this operation is mixed in the `find` operation which is a read-only transaction in wriedTiger.

2020-05-13T21:41:02.975+0800 I COMMAND [conn104564] warning: log line attempted (33kB) over max size (10kB), printing beginning and end ... command iiisland.feed command: find { find: "feed", filter: { passport.$id:

{ $in: [ ObjectId('5d07ae812ed84312892d5b0f'), ObjectId('5d08f4982ed84312a5b83e71'), ObjectId('5d0881002ed84312892d5b6c'), ObjectId('5d08f5d02ed84312a5b83e97'), ObjectId('5d08f4052ed84312a5b83e6c'), ObjectId('5d08febb2ed84312a5b84028'), ObjectId('5d08848f2ed84312892d5b81'), ObjectId('5d08f9662ed84312a5b83edd'), ObjectId('5d08fbfc2ed84312a5b83f63'), ObjectId('5d08f8fc2ed84312a5b83ecb'), ObjectId('5d08fc962ed84312a5b83f8c'), ObjectId('5d08fb8c2ed84312a5b83f4c'), ObjectId('5d08fa952ed84312a5b83f0f'), ObjectId('5d08f95d2ed84312a5b83edb'), ObjectId('5d08f8db2ed84312a5b83ec9'), ObjectId('5d08f7a62ed84312a5b83ea0'), ObjectId('5d08f4ce2ed84312a5b83e78'), ObjectId('5d04bf732ed8431290b6c6d2'), ObjectId('5d04bf732ed8431290b6c6d0'), ObjectId('5d09b85e2ed84312a5b84230'), ObjectId('5d09b9f92ed84312a5b8423f'), ObjectId('5d09b3402ed84312a5b84220'), ObjectId('5d09a6732ed84312a5b841fa'), ObjectId('5d0a051b2ed84312a5b84370'), ObjectId('5d0a07ab2ed84312a5b84381'), ObjectId('5d04bf722ed8431290b6c6ca'), ObjectId('5d04bf742ed8431290b6c6d8'), ObjectId('5d04bf742ed8431290b6c6d6'), ObjectId('5d09dcc12ed84312a5b842fb'), ObjectId('5d09eb292ed84312a5b8431c'), ObjectId('5d0a3e972ed84312a5b846fe'), ObjectId('5d09b2ba2ed84312a5b8421d'), ObjectId('5d0a390b2ed84312a5b845ba'), ObjectId('5d0a5b422ed84312a5b84c6b'), ObjectId('5d0a69932ed84312a5b84f26'), ObjectId('5d04bf782ed8431290b6c6e8'), ObjectId('5d0a62622ed84312a5b84e06'), ObjectId('5d0a3a522ed84312a5b84610'), ObjectId('5d0a3f8f2ed84312a5b84747'), ObjectId('5d0a4d682ed84312a5b84990'), ObjectId('5d07ca7b2ed84312892d5b47'), ObjectId('5d08fedf2ed84312a5b84035'), ObjectId('5d0a38082ed84312a5b84597'), ObjectId('5d0a3db92ed84312a5b846c4'), ObjectId('5d0a3ceb2ed84312a5b84686'), ObjectId('5d0a3e802ed84312a5b846f6'), ObjectId('5d0a3dde2ed84312a5b846d7'), ObjectId('5d0a4c872ed84312a5b84973'), ObjectId('5d0a43322ed84312a5b8481f'), ObjectId('5d0922db2ed84312a5b841a0'), ObjectId('5d0a4e8f2ed84312a5b849d2'), ObjectId('5d0a4bb42ed84312a5b84958'), ObjectId('5d0a4ada2ed84312a5b84936'), ObjectId('5d0a48fc2ed84312a5b84915'), ObjectId('5d0a47b42ed84312a5b848d4'), ObjectId('5d0a430e2ed84312a5b84816'), ObjectId('5d0a453e2ed84312a5b84873'), ObjectId('5d0a42b42ed84312a5b84804'), ObjectId('5d0a42af2ed84312a5b847fe'), ObjectId('5d0a59ef2ed84312a5b84c0c'), ObjectId('5d04bf832ed8431290b6c724'), ObjectId('5d0ad6c72ed84312a5b850df'), ObjectId('5d09eee42ed84312a5b8431f'), ObjectId('5d09c4b72ed84312a5b84282'), ObjectId('5d0af81b2ed84312a5b852d5'), ObjectId('5d0b129a2ed84312a5b85553'), ObjectId('5d0883292ed84312892d5b7e'), ObjectId('5d0a395a2ed84312a5b845cf'), ObjectId('5d0b37202ed84312a5b8591c'), ObjectId('5d0a3a5a2ed84312a5b84613'), ObjectId('5d0b9db32ed843136c5c4846'), ObjectId('5d1087352ed843136c5c58d9'), ObjectId('5d0af3e12ed84312a5b85245'), ObjectId('5d07c03c2ed84312892d5b2d'), ObjectId('5d2ef8362ed8435ec9120cdb'), ObjectId('5d2f3c122ed8435ec91211a1'), ObjectId('5d28786b2ed84329975be90f'), ObjectId('5d2846d32ed84329975be3ac'), ObjectId('5d42b3692ed8430e01096ce9'), ObjectId('5d430daf2ed8430f975eb6ff'), ObjectId('5d41857b2ed8430e01096034'), ObjectId('5d1ebb192ed84338f04bc69e'), ObjectId('5d22b2002ed8430353c3ad49'), ObjectId('5d287b3a2ed84329975be977'), ObjectId('5d19d2b42ed84338f04bb24f'), ObjectId('5d3932852ed8430f975e416f'), ObjectId('5d47d8602ed8430f975ef247'), ObjectId('5d2c39ee2ed8 .......... 37b4e4f2c0'), ObjectId('5e935d033c4a1a08bd6cd543'), ObjectId('5e4cef5d073b870ca2d92ee9'), ObjectId('5ea45ba43c4a1a1e2a4f1c1c'), ObjectId('5d282f2b2ed843285eac0cf1'), ObjectId('5e63a111073b871f96ab855f'), ObjectId('5db26ba42ed8437560262dbf'), ObjectId('5e51f054073b870c8ce1566d'), ObjectId('5ea6a057e166752fb335cf4d'), ObjectId('5ea306e2e166751e550811ad'), ObjectId('5ea3b1a83c4a1a1e2a4acb85'), ObjectId('5d14cd852ed84337b4e4c947'), ObjectId('5eaade2e3c4a1a035791d53c'), ObjectId('5dc4a59a2ed843671b36b52a'), ObjectId('5e14ff8d2ed843395d1ef9da'), ObjectId('5e860d0f073b870614a264f8'), ObjectId('5eb01a933c4a1a2f646c0daa'), ObjectId('5d8624de2ed84352fdeceebf'), ObjectId('5dc595ad2ed8436d7337cc24'), ObjectId('5eb079492ed84326836b9918'), ObjectId('5e4542ea073b8715986dd829'), ObjectId('5eb0273a3c4a1a7a56e8dc22'), ObjectId('5db191cc2ed843059b4b8d4a'), ObjectId('5e6b522f3c4a1a52217ded01'), ObjectId('5eb189563c4a1a7a56015e49'), ObjectId('5e3cf4a7073b871f62d1e134'), ObjectId('5ead2a4f2ed843555c36bfc1'), ObjectId('5eadf1913c4a1a2f6450b07d'), ObjectId('5eb15336073b874fa4841da9'), ObjectId('5dcff6932ed84356e79e5f64'), ObjectId('5e627c752ed84328c70d203d'), ObjectId('5eb2d7aa073b874fa49e6c0c'), ObjectId('5eb0329de166750e4c27a4aa'), ObjectId('5d7a3fdf2ed8430ed7c609e7'), ObjectId('5eb61bbce166750e4c821912'), ObjectId('5eb2d7d73c4a1a7a56164de7'), ObjectId('5eb183f43c4a1a7a5600a6a2'), ObjectId('5ea39a143c4a1a20433c2101'), ObjectId('5eb431dc2ed8432683a79173'), ObjectId('5e57b099073b870cba4fa2bb'), ObjectId('5eb4e1922ed8432683af3bab'), ObjectId('5eaae5273c4a1a03579269d4'), ObjectId('5eb305f5e166750e4c576987'), ObjectId('5eb15c903c4a1a7a56fc94d0'), ObjectId('5eb035e1e166750e4c2816a7'), ObjectId('5ea448432ed8434e7ac26b68'), ObjectId('5d1c710c2ed84337b4e4e9a6'), ObjectId('5e62a9883c4a1a5091f58c69'), ObjectId('5eab44203c4a1a0357976c89'), ObjectId('5dc6a18e2ed8436b5fe937ac'), ObjectId('5eb004aa2ed8435ad519dd96'), ObjectId('5e9c2706e1667543eaaf5d2a'), ObjectId('5e4410772ed84350e49b3408'), ObjectId('5eb36f183c4a1a7a561ddb66'), ObjectId('5eb06de8073b874fa47787c0'), ObjectId('5e4414c32ed84347061e030f'), ObjectId('5eb416a63c4a1a7a56291cdd'), ObjectId('5d4006d82ed8430f975e8eea') ] }

, version:

{ $lte: 3 }

, $or: [

{ status: 1 }

,

{ status: 2, passport.$id: ObjectId('5d07ae812ed84312892d5b0f') }

], _id:

{ $lt: ObjectId('5ebbb7d7e166755049ea69c6') }

}, sort:

{ _id: -1 }

, projection:

{ _id: 1, version: 1, text: 1, background: 1, backgroundColor: 1, units: 1, createTime: 1, passport: 1, island: 1, commentCount: 1, likeCount: 1, unlikeCount: 1, topicCount: 1, score: 1, likes: 1, topics: 1, status: 1, audit: 1 }

, limit: 20, $db: "iiisland", $clusterTime: { clusterTime: Timestamp(1589377262, 60), signature:

{ hash: BinData(0, A5DB8F2CB4AB293729449BB9844EC974BBC30E44), keyId: 6825423329424310275 }

}, lsid:

{ id: UUID("e66c3425-5ade-4d2c-9a75-e2e51f11b200") }

, $readPreference:

{ mode: "secondaryPreferred" }

} planSummary: IXSCAN

{ _id: 1 }

keysExamined:916 docsExamined:916 fromMultiPlanner:1 replanned:1 cursorExhausted:1 numYields:31 nreturned:20 queryHash:EC5A06C4 planCacheKey:41FA6045 reslen:22485 locks:{ ReplicationStateTransition: { acquireCount:

{ w: 32 }

}, Global: { acquireCount:

{ r: 32 }

}, Database: { acquireCount:

{ r: 32 }

}, Collection: { acquireCount:

{ r: 32 }

}, Mutex: { acquireCount:

{ r: 1 }

} } storage:{ data:

{ bytesRead: 28463234, timeReadingMicros: 44992 }

} protocol:op_msg 106ms
2020-05-13T21:41:02.976+0800 I - [conn104564] Creating profile collection: iiisland.system.profile
2020-05-13T21:41:02.976+0800 I STORAGE [conn104564] createCollection: iiisland.system.profile with generated UUID: 559201f2-607f-4d30-811c-456ab0452fb3 and options:

{ capped: true, size: 1048576 }

2020-05-13T21:41:02.976+0800 E STORAGE [conn104564] WiredTiger error (95) [1589377262:976452][43339:0x7f89a1b92700], file:_mdb_catalog.wt, WT_CURSOR.insert: __wt_txn_modify, 465: Transactions with ignore_prepare=true cannot perform updates: Operation not supported Raw: [1589377262:976452][43339:0x7f89a1b92700], file:_mdb_catalog.wt, WT_CURSOR.insert: __wt_txn_modify, 465: Transactions with ignore_prepare=true cannot perform updates: Operation not supported
2020-05-13T21:41:02.976+0800 E - [conn104564] Assertion: UnknownError: WiredTigerRecordStore::insertRecord 95: Operation not supported src/mongo/db/catalog/database_impl.cpp 655
2020-05-13T21:41:02.976+0800 W - [conn104564] Caught Assertion while trying to profile msg against iiisland.feed: UnknownError: WiredTigerRecordStore::insertRecord 95: Operation not supported



 Comments   
Comment by Eric Milkie [ 14/May/20 ]

Thank you for reporting this problem! I believe it is a duplicate of SERVER-47714 which I have linked here. Please let us know if you don't believe this is correct; otherwise, follow that ticket for updates on this issue.

Generated at Thu Feb 08 05:16:27 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.