[SERVER-56929] Improve error message for improper downgrades resulting in invalid collection options Created: 13/May/21  Updated: 29/Oct/23  Resolved: 21/May/21

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

Type: Task Priority: Major - P3
Reporter: Connie Chen Assignee: Gregory Wlodarek
Resolution: Fixed Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Issue Links:
Related
related to WT-6011 Bump the compatibility version for Mo... Closed
is related to SERVER-23116 Add versioning scheme to the KVCatalo... Closed
is related to SERVER-36280 Create a new feature tracker bit indi... Closed
Backwards Compatibility: Fully Compatible
Sprint: Execution Team 2021-05-31
Participants:

 Description   

Follow-up to WT-6011

This ticket is to review whether this problem is possible.

The current assumption is that we do not think WT-6011 is necessary as we haven't run into any issues in our current testing for time-series, but we want to ensure the scenario that has been highlighted by Dan Gottlieb in that comment is not possible. 



 Comments   
Comment by Githook User [ 21/May/21 ]

Author:

{'name': 'Gregory Wlodarek', 'email': 'gregory.wlodarek@mongodb.com', 'username': 'GWlodarek'}

Message: SERVER-56929 Improve error message for improper downgrades resulting in invalid collection options
Branch: v4.4
https://github.com/mongodb/mongo/commit/8c6f26fa525ea49717bb0f510c64c44e8664196e

Comment by Daniel Gottlieb (Inactive) [ 14/May/21 ]

After running a timeseries jstest, this log is produced by starting a 4.4 mongodb binary:

2021-05-14T10:02:44.858-04:00 E  STORAGE  [initandlisten] DBException in initAndListen, terminating{"error":"InvalidOptions: The field 'clusteredIndex' is not a valid collection option. Options: { uuid: UUID(\"b061b847-dc39-45b8-a886-e142489910a8\"), clusteredIndex: {} }"}

Complete logs:

2021-05-14T10:02:43.593-04:00 I  CONTROL  [main] Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'
2021-05-14T10:02:43.594-04:00 W  ASIO     [main] No TransportLayer configured during NetworkInterface startup
2021-05-14T10:02:43.595-04:00 I  NETWORK  [main] Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set tcpFastOpenServer, tcpFastOpenClient, and tcpFastOpenQueueSize.
2021-05-14T10:02:43.595-04:00 I  STORAGE  [initandlisten] MongoDB starting{"pid":19686,"port":27017,"dbPath":"/data/db/job0/resmoke/","architecture":"64-bit","host":"chimichurri"}
2021-05-14T10:02:43.595-04:00 I  CONTROL  [initandlisten] Build Info{"buildInfo":{"version":"4.4.0","gitVersion":"563487e100c4215e2dce98d0af2a6a5a2d67c5cf","openSSLVersion":"OpenSSL 1.0.2g  1 Mar 2016","modules":[],"allocator":"tcmalloc","environment":{"distmod":"ubuntu1604","distarch":"x86_64","target_arch":"x86_64"}}}
2021-05-14T10:02:43.595-04:00 I  CONTROL  [initandlisten] Operating System{"os":{"name":"Ubuntu","version":"16.04"}}
2021-05-14T10:02:43.595-04:00 I  CONTROL  [initandlisten] Options set by command line{"options":{"storage":{"dbPath":"/data/db/job0/resmoke/"}}}
2021-05-14T10:02:43.596-04:00 I  STORAGE  [initandlisten] Storage engine to use detected by data files{"dbpath":"/data/db/job0/resmoke/","storageEngine":"wiredTiger"}
2021-05-14T10:02:43.596-04:00 I  STORAGE  [initandlisten] Using the XFS filesystem is strongly recommended with the WiredTiger storage engine. See http://dochub.mongodb.org/core/prodnotes-filesystem
2021-05-14T10:02:43.596-04:00 I  STORAGE  [initandlisten] Opening WiredTiger{"config":"create,cache_size=31635M,session_max=33000,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,close_scan_interval=10,close_handle_minimum=250),statistics_log=(wait=0),verbose=[recovery_progress,checkpoint_progress,compact_progress],"}
2021-05-14T10:02:44.446-04:00 I  STORAGE  [initandlisten] WiredTiger message{"message":"[1621000964:446374][19686:0x7f91d61b0a80], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 3 through 4"}
2021-05-14T10:02:44.506-04:00 I  STORAGE  [initandlisten] WiredTiger message{"message":"[1621000964:506140][19686:0x7f91d61b0a80], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 4 through 4"}
2021-05-14T10:02:44.595-04:00 I  STORAGE  [initandlisten] WiredTiger message{"message":"[1621000964:595328][19686:0x7f91d61b0a80], txn-recover: [WT_VERB_RECOVERY | WT_VERB_RECOVERY_PROGRESS] Main recovery loop: starting at 3/896 to 4/256"}
2021-05-14T10:02:44.710-04:00 I  STORAGE  [initandlisten] WiredTiger message{"message":"[1621000964:709990][19686:0x7f91d61b0a80], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 3 through 4"}
2021-05-14T10:02:44.779-04:00 I  STORAGE  [initandlisten] WiredTiger message{"message":"[1621000964:779847][19686:0x7f91d61b0a80], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 4 through 4"}
2021-05-14T10:02:44.836-04:00 I  STORAGE  [initandlisten] WiredTiger message{"message":"[1621000964:836033][19686:0x7f91d61b0a80], txn-recover: [WT_VERB_RECOVERY | WT_VERB_RECOVERY_PROGRESS] Set global recovery timestamp: (0, 0)"}
2021-05-14T10:02:44.843-04:00 I  STORAGE  [initandlisten] WiredTiger opened{"durationMillis":1247}
2021-05-14T10:02:44.843-04:00 I  RECOVERY [initandlisten] WiredTiger recoveryTimestamp{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}
2021-05-14T10:02:44.847-04:00 I  STORAGE  [initandlisten] WiredTigerKVEngine shutting down
2021-05-14T10:02:44.847-04:00 I  STORAGE  [initandlisten] Shutting down session sweeper thread
2021-05-14T10:02:44.847-04:00 I  STORAGE  [initandlisten] Finished shutting down session sweeper thread
2021-05-14T10:02:44.847-04:00 I  STORAGE  [initandlisten] Closing WiredTiger{"closeConfig":"leak_memory=true,"}
2021-05-14T10:02:44.857-04:00 I  STORAGE  [initandlisten] WiredTiger closed{"durationMillis":10}
2021-05-14T10:02:44.858-04:00 E  STORAGE  [initandlisten] DBException in initAndListen, terminating{"error":"InvalidOptions: The field 'clusteredIndex' is not a valid collection option. Options: { uuid: UUID(\"b061b847-dc39-45b8-a886-e142489910a8\"), clusteredIndex: {} }"}
2021-05-14T10:02:44.858-04:00 I  NETWORK  [initandlisten] Shutdown: going to close listening sockets
2021-05-14T10:02:44.858-04:00 I  -        [initandlisten] Stopping further Flow Control ticket acquisitions.
2021-05-14T10:02:44.858-04:00 I  CONTROL  [initandlisten] Now exiting
2021-05-14T10:02:44.858-04:00 I  CONTROL  [initandlisten] Shutting down{"exitCode":100}

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