[SERVER-74346] Cannot start MongoDB 5.0.15 with systemd Created: 24/Feb/23 Updated: 24/Feb/23 Resolved: 24/Feb/23 |
|
| Status: | Closed |
| Project: | Core Server |
| Component/s: | None |
| Affects Version/s: | None |
| Fix Version/s: | None |
| Type: | Bug | Priority: | Major - P3 |
| Reporter: | Vy Nguyen Tan | Assignee: | Chris Kelly |
| Resolution: | Duplicate | Votes: | 0 |
| Labels: | None | ||
| Remaining Estimate: | Not Specified | ||
| Time Spent: | Not Specified | ||
| Original Estimate: | Not Specified | ||
| Issue Links: |
|
||||||||
| Operating System: | ALL | ||||||||
| Participants: | |||||||||
| Description |
|
Hi, After upgrade MongoDB from version 5.0.14 to version 5.0.15, the mongod cannot be started via systemd. System info:
Mongod.conf ------ storage: systemLog: processManagement: net: replication: operationProfiling: security: /usr/lib/systemd/system/mongod.service.d/custom.conf --------------- Everything works fine with MogoDB 5.0.14 with the same configuration. yum update mongodb-org ----------- ============================================================================================================================================================= Transaction Summary
The error messages from mongod.log ------- {"t":\{"$date":"2023-02-24T16:04:05.100+07:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"} {"t":\{"$date":"2023-02-24T16:04:05.102+07:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"} {"t":\{"$date":"2023-02-24T16:04:05.105+07:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"} {"t":\{"$date":"2023-02-24T16:04:05.106+07:00"},"s":"I", "c":"SHARDING", "id":20997, "ctx":"initandlisten","msg":"Refreshed RWC defaults","attr":{"newDefaults":{}}} {"t":\{"$date":"2023-02-24T16:04:05.106+07:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/tiki/mongodb/data/diagnostic.data"}} {"t":\{"$date":"2023-02-24T16:04:05.109+07:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigStartingUp","oldState":"ConfigPreStart"}} {"t":\{"$date":"2023-02-24T16:04:05.109+07:00"},"s":"I", "c":"-", "id":4939300, "ctx":"monitoring-keys-for-HMAC","msg":"Failed to refresh key cache","attr":{"error":"ReadConcernMajorityNotAvailableYet: Read concern majority reads are currently not possible.","nextWakeupMillis":200}} {"t":\{"$date":"2023-02-24T16:04:05.109+07:00"},"s":"I", "c":"REPL", "id":4280500, "ctx":"initandlisten","msg":"Attempting to create internal replication collections"} {"t":\{"$date":"2023-02-24T16:04:05.111+07:00"},"s":"I", "c":"REPL", "id":4280501, "ctx":"initandlisten","msg":"Attempting to load local voted for document"} {"t":\{"$date":"2023-02-24T16:04:05.111+07:00"},"s":"I", "c":"REPL", "id":21311, "ctx":"initandlisten","msg":"Did not find local initialized voted for document at startup"} {"t":\{"$date":"2023-02-24T16:04:05.111+07:00"},"s":"I", "c":"REPL", "id":4280502, "ctx":"initandlisten","msg":"Searching for local Rollback ID document"} {"t":\{"$date":"2023-02-24T16:04:05.112+07:00"},"s":"I", "c":"REPL", "id":21529, "ctx":"initandlisten","msg":"Initializing rollback ID","attr":{"rbid":1}} {"t":\{"$date":"2023-02-24T16:04:05.112+07:00"},"s":"I", "c":"REPL", "id":21313, "ctx":"initandlisten","msg":"Did not find local replica set configuration document at startup","attr":{"error": {"code":47,"codeName":"NoMatchingDocument","errmsg":"Did not find replica set configuration document in local.system.replset"}}} {"t":\{"$date":"2023-02-24T16:04:05.112+07:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigUninitialized","oldState":"ConfigStartingUp"}} {"t":\{"$date":"2023-02-24T16:04:05.112+07:00"},"s":"I", "c":"CONTROL", "id":20714, "ctx":"LogicalSessionCacheRefresh","msg":"Failed to refresh session cache, will try again at the next refresh interval","attr":{"error":"NotYetInitialized: Replication has not yet been configured"}} {"t":\{"$date":"2023-02-24T16:04:05.113+07:00"},"s":"I", "c":"REPL", "id":40440, "ctx":"initandlisten","msg":"Starting the TopologyVersionObserver"} {"t":\{"$date":"2023-02-24T16:04:05.113+07:00"},"s":"I", "c":"CONTROL", "id":20712, "ctx":"LogicalSessionCacheReap","msg":"Sessions collection is not set up; waiting until next sessions reap interval","attr":{"error":"NamespaceNotFound: config.system.sessions does not exist"}} {"t":\{"$date":"2023-02-24T16:04:05.113+07:00"},"s":"I", "c":"REPL", "id":40445, "ctx":"TopologyVersionObserver","msg":"Started TopologyVersionObserver"} {"t":\{"$date":"2023-02-24T16:04:05.113+07:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-27017.sock"}} {"t":\{"$date":"2023-02-24T16:04:05.113+07:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"0.0.0.0"}} {"t":\{"$date":"2023-02-24T16:04:05.113+07:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":27017,"ssl":"off"}} {"t":\{"$date":"2023-02-24T16:04:05.115+07:00"},"s":"I", "c":"CONTROL", "id":23377, "ctx":"SignalHandler","msg":"Received signal","attr":{"signal":15,"error":"Terminated"}} {"t":\{"$date":"2023-02-24T16:04:05.115+07:00"},"s":"I", "c":"CONTROL", "id":23378, "ctx":"SignalHandler","msg":"Signal was sent by kill(2)","attr":{"pid":1,"uid":0}} {"t":\{"$date":"2023-02-24T16:04:05.115+07:00"},"s":"I", "c":"CONTROL", "id":23381, "ctx":"SignalHandler","msg":"will terminate after current cmd ends"} {"t":\{"$date":"2023-02-24T16:04:05.115+07:00"},"s":"I", "c":"REPL", "id":4784900, "ctx":"SignalHandler","msg":"Stepping down the ReplicationCoordinator for shutdown","attr":{"waitTimeMillis":15000}} {"t":\{"$date":"2023-02-24T16:04:05.115+07:00"},"s":"I", "c":"COMMAND", "id":4784901, "ctx":"SignalHandler","msg":"Shutting down the MirrorMaestro"} {"t":\{"$date":"2023-02-24T16:04:05.115+07:00"},"s":"I", "c":"REPL", "id":40441, "ctx":"SignalHandler","msg":"Stopping TopologyVersionObserver"} {"t":\{"$date":"2023-02-24T16:04:05.116+07:00"},"s":"I", "c":"REPL", "id":40447, "ctx":"TopologyVersionObserver","msg":"Stopped TopologyVersionObserver"} {"t":\{"$date":"2023-02-24T16:04:05.116+07:00"},"s":"I", "c":"SHARDING", "id":4784902, "ctx":"SignalHandler","msg":"Shutting down the WaitForMajorityService"} {"t":\{"$date":"2023-02-24T16:04:05.116+07:00"},"s":"I", "c":"CONTROL", "id":4784903, "ctx":"SignalHandler","msg":"Shutting down the LogicalSessionCache"} {"t":\{"$date":"2023-02-24T16:04:05.116+07:00"},"s":"I", "c":"NETWORK", "id":20562, "ctx":"SignalHandler","msg":"Shutdown: going to close listening sockets"} {"t":\{"$date":"2023-02-24T16:04:05.117+07:00"},"s":"I", "c":"NETWORK", "id":23017, "ctx":"listener","msg":"removing socket file","attr":{"path":"/tmp/mongodb-27017.sock"}} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"NETWORK", "id":4784905, "ctx":"SignalHandler","msg":"Shutting down the global connection pool"} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"CONTROL", "id":4784906, "ctx":"SignalHandler","msg":"Shutting down the FlowControlTicketholder"} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"-", "id":20520, "ctx":"SignalHandler","msg":"Stopping further Flow Control ticket acquisitions."} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"REPL", "id":4784907, "ctx":"SignalHandler","msg":"Shutting down the replica set node executor"} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"ASIO", "id":22582, "ctx":"ReplNodeDbWorkerNetwork","msg":"Killing all outstanding egress activity."} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"CONTROL", "id":4784908, "ctx":"SignalHandler","msg":"Shutting down the PeriodicThreadToAbortExpiredTransactions"} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"REPL", "id":4784909, "ctx":"SignalHandler","msg":"Shutting down the ReplicationCoordinator"} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"REPL", "id":5074000, "ctx":"SignalHandler","msg":"Shutting down the replica set aware services."} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"REPL", "id":5123006, "ctx":"SignalHandler","msg":"Shutting down PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","numInstances":0,"numOperationContexts":0}} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"ASIO", "id":22582, "ctx":"TenantMigrationDonorServiceNetwork","msg":"Killing all outstanding egress activity."} {"t":\{"$date":"2023-02-24T16:04:05.118+07:00"},"s":"I", "c":"REPL", "id":5123006, "ctx":"SignalHandler","msg":"Shutting down PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","numInstances":0,"numOperationContexts":0}}
|
| Comments |
| Comment by Chris Kelly [ 24/Feb/23 ] |
|
Thanks for reporting this - I'm closing this as a duplicate of
|
| Comment by Pedro Moranga [ 24/Feb/23 ] |
|
I had the same issue with `4.4.19`, had to rollback to `4.4.18`. Same as |