Description
Hi,
i have now installed several Times the Rocketchat. I got it running, but after an Systemreboot the Mongodb Server wont start anymore. I found some Logfiles, but i found nothing what can help me. I have attached the Log files and i hope you have an idea.
i have used this manual.
Server Setup Information
- Version of Rocket.Chat Server: 5.3.0
- Operating System: Ubuntu 20.04
- Deployment Method: Virtual Maschine Ubuntu
- Number of Running Instances: 1
- DB Replicaset Oplog:
- NodeJS Version: 14.20
- MongoDB Version: 6.0.2
- Firewalls involved: no
Any additional Information
● mongod.service - MongoDB Database Server
Loaded: loaded (/lib/systemd/system/mongod.service; enabled; vendor preset: enabled)
Active: inactive (dead) since Fri 2022-10-28 07:11:17 UTC; 54min ago
Docs: https://docs.mongodb.org/manual
Process: 787 ExecStart=/usr/bin/mongod --config /etc/mongod.conf (code=exited, status=0/SUCCESS)
Main PID: 787 (code=exited, status=0/SUCCESS)
Okt 28 07:11:09 lsrocketchat systemd[1]: Started MongoDB Database Server.
Okt 28 07:11:10 lsrocketchat mongod[787]: about to fork child process, waiting until server is ready for connections.
Okt 28 07:11:10 lsrocketchat mongod[899]: forked process: 899
Okt 28 07:11:16 lsrocketchat mongod[787]: child process started successfully, parent exiting
Okt 28 07:11:17 lsrocketchat systemd[1]: mongod.service: Succeeded.
Main PID: 787 (code=exited, status=0/SUCCESS)
Okt 28 07:11:09 lsrocketchat systemd[1]: Started MongoDB Database Server.
Okt 28 07:11:10 lsrocketchat mongod[787]: about to fork child process, waiting until server is ready for connections.
Okt 28 07:11:10 lsrocketchat mongod[899]: forked process: 899
Okt 28 07:11:16 lsrocketchat mongod[787]: child process started successfully, parent exiting
Okt 28 07:11:17 lsrocketchat systemd[1]: mongod.service: Succeeded.
if i look in the Monogdb log i got This. i hope it got not so long 
0"},“s”:“I”, “c”:“CONTROL”, “id”:20698, “ctx”:“-”,“msg”:“***** SERVER RESTARTED *****”}
{“t”:{“$date”:“2022-10-28T07:11:10.359+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:4915701, “ctx”:“main”,“msg”:“Initialized wire specification”,“attr”:{“spec”:{“incomingExternalClient”:{“minWireVersion”:0,“maxWireVersion”:17},“incomingInternalClient”:{“minWireVersion”:0,“maxWireVersion”:17},“outgoing”:{“minWireVersion”:6,“maxWireVersion”:17},“isInternalClient”:true}}}
{“t”:{“$date”:“2022-10-28T07:11:10.362+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:23285, “ctx”:“main”,“msg”:“Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols ‘none’”}
{“t”:{“$date”:“2022-10-28T07:11:10.392+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:4648601, “ctx”:“main”,“msg”:“Implicit TCP FastOpen unavailable. If TCP FastOpen is required, set tcpFastOpenServer, tcpFastOpenClient, and tcpFastOpenQueueSize.”}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123008, “ctx”:“main”,“msg”:“Successfully registered PrimaryOnlyService”,“attr”:{“service”:“TenantMigrationDonorService”,“namespace”:“config.tenantMigrationDonors”}}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123008, “ctx”:“main”,“msg”:“Successfully registered PrimaryOnlyService”,“attr”:{“service”:“TenantMigrationRecipientService”,“namespace”:“config.tenantMigrationRecipients”}}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123008, “ctx”:“main”,“msg”:“Successfully registered PrimaryOnlyService”,“attr”:{“service”:“ShardSplitDonorService”,“namespace”:“config.tenantSplitDonors”}}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:5945603, “ctx”:“main”,“msg”:“Multi threading initialized”}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:4615611, “ctx”:“initandlisten”,“msg”:“MongoDB starting”,“attr”:{“pid”:899,“port”:27017,“dbPath”:“/var/lib/mongodb”,“architecture”:“64-bit”,“host”:“lsrocketchat”}}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:23403, “ctx”:“initandlisten”,“msg”:“Build Info”,“attr”:{“buildInfo”:{“version”:“6.0.2”,“gitVersion”:“94fb7dfc8b974f1f5343e7ea394d0d9deedba50e”,“openSSLVersion”:“OpenSSL 1.1.1f 31 Mar 2020”,“modules”:,“allocator”:“tcmalloc”,“environment”:{“distmod”:“ubuntu2004”,“distarch”:“x86_64”,“target_arch”:“x86_64”}}}}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:51765, “ctx”:“initandlisten”,“msg”:“Operating System”,“attr”:{“os”:{“name”:“Ubuntu”,“version”:“20.04”}}}
{“t”:{“$date”:“2022-10-28T07:11:10.599+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:21951, “ctx”:“initandlisten”,“msg”:“Options set by command line”,“attr”:{“options”:{“config”:“/etc/mongod.conf”,“net”:{“bindIp”:“127.0.0.1”,“port”:27017},“processManagement”:{“fork”:true,“timeZoneInfo”:“/usr/share/zoneinfo”},“replication”:{“replSetName”:“rs01”},“storage”:{“dbPath”:“/var/lib/mongodb”,“engine”:“wiredTiger”,“journal”:{“enabled”:true}},“systemLog”:{“destination”:“file”,“logAppend”:true,“path”:“/var/log/mongodb/mongod.log”}}}}
{“t”:{“$date”:“2022-10-28T07:11:10.618+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:22297, “ctx”:“initandlisten”,“msg”:“Using the XFS filesystem is strongly recommended with the WiredTiger storage engine. See http://dochub.mongodb.org/core/prodnotes-filesystem",“tags”:["startupWarnings”]}
{“t”:{“$date”:“2022-10-28T07:11:10.618+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:22315, “ctx”:“initandlisten”,“msg”:“Opening WiredTiger”,“attr”:{“config”:“create,cache_size=3466M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,remove=true,path=journal,compressor=snappy),builtin_extension_config=(zstd=(compression_level=6)),file_manager=(close_idle_time=600,close_scan_interval=10,close_handle_minimum=2000),statistics_log=(wait=0),json_output=(error,message),verbose=[recovery_progress:1,checkpoint_progress:1,compact_progress:1,backup:0,checkpoint:0,compact:0,evict:0,history_store:0,recovery:0,rts:0,salvage:0,tiered:0,timestamp:0,transaction:0,verify:0,log:0],”}}
{“t”:{“$date”:“2022-10-28T07:11:15.991+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:4795906, “ctx”:“initandlisten”,“msg”:“WiredTiger opened”,“attr”:{“durationMillis”:5373}}
{“t”:{“$date”:“2022-10-28T07:11:15.991+00:00”},“s”:“I”, “c”:“RECOVERY”, “id”:23987, “ctx”:“initandlisten”,“msg”:“WiredTiger recoveryTimestamp”,“attr”:{“recoveryTimestamp”:{“$timestamp”:{“t”:1666940823,“i”:2}}}}
{“t”:{“$date”:“2022-10-28T07:11:15.992+00:00”},“s”:“I”, “c”:“RECOVERY”, “id”:5380106, “ctx”:“initandlisten”,“msg”:“WiredTiger oldestTimestamp”,“attr”:{“oldestTimestamp”:{“$timestamp”:{“t”:1666940523,“i”:2}}}}
{“t”:{“$date”:“2022-10-28T07:11:16.011+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:22383, “ctx”:“initandlisten”,“msg”:“The size storer reports that the oplog contains”,“attr”:{“numRecords”:8171,“dataSize”:1884993}}
{“t”:{“$date”:“2022-10-28T07:11:16.011+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:22384, “ctx”:“initandlisten”,“msg”:“Scanning the oplog to determine where to place markers for truncation”}
{“t”:{“$date”:“2022-10-28T07:11:16.037+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:22382, “ctx”:“initandlisten”,“msg”:“WiredTiger record store oplog processing finished”,“attr”:{“durationMillis”:26}}
{“t”:{“$date”:“2022-10-28T07:11:16.150+00:00”},“s”:“W”, “c”:“CONTROL”, “id”:22120, “ctx”:“initandlisten”,“msg”:“Access control is not enabled for the database. Read and write access to data and configuration is unrestricted”,“tags”:[“startupWarnings”]}
{“t”:{“$date”:“2022-10-28T07:11:16.158+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:4915702, “ctx”:“initandlisten”,“msg”:“Updated wire specification”,“attr”:{“oldSpec”:{“incomingExternalClient”:{“minWireVersion”:0,“maxWireVersion”:17},“incomingInternalClient”:{“minWireVersion”:0,“maxWireVersion”:17},“outgoing”:{“minWireVersion”:6,“maxWireVersion”:17},“isInternalClient”:true},“newSpec”:{“incomingExternalClient”:{“minWireVersion”:0,“maxWireVersion”:17},“incomingInternalClient”:{“minWireVersion”:17,“maxWireVersion”:17},“outgoing”:{“minWireVersion”:17,“maxWireVersion”:17},“isInternalClient”:true}}}
{“t”:{“$date”:“2022-10-28T07:11:16.158+00:00”},“s”:“I”, “c”:“REPL”, “id”:5853300, “ctx”:“initandlisten”,“msg”:“current featureCompatibilityVersion value”,“attr”:{“featureCompatibilityVersion”:“6.0”,“context”:“startup”}}
{“t”:{“$date”:“2022-10-28T07:11:16.160+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:5071100, “ctx”:“initandlisten”,“msg”:“Clearing temp directory”}
{“t”:{“$date”:“2022-10-28T07:11:16.476+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:20536, “ctx”:“initandlisten”,“msg”:“Flow Control is enabled on this deployment”}
{“t”:{“$date”:“2022-10-28T07:11:16.476+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:5380103, “ctx”:“initandlisten”,“msg”:“Unpin oldest timestamp request”,“attr”:{“service”:“_wt_startup”,“requestedTs”:{“$timestamp”:{“t”:1666940523,“i”:2}}}}
{“t”:{“$date”:“2022-10-28T07:11:16.476+00:00”},“s”:“I”, “c”:“FTDC”, “id”:20625, “ctx”:“initandlisten”,“msg”:“Initializing full-time diagnostic data capture”,“attr”:{“dataDirectory”:“/var/lib/mongodb/diagnostic.data”}}
{“t”:{“$date”:“2022-10-28T07:11:16.489+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015317, “ctx”:“initandlisten”,“msg”:“Setting new configuration state”,“attr”:{“newState”:“ConfigStartingUp”,“oldState”:“ConfigPreStart”}}
{“t”:{“$date”:“2022-10-28T07:11:16.490+00:00”},“s”:“I”, “c”:“REPL”, “id”:6005300, “ctx”:“initandlisten”,“msg”:“Starting up replica set aware services”}
{“t”:{“$date”:“2022-10-28T07:11:16.490+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280500, “ctx”:“initandlisten”,“msg”:“Attempting to create internal replication collections”}
{“t”:{“$date”:“2022-10-28T07:11:16.495+00: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”:“2022-10-28T07:11:16.495+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280501, “ctx”:“initandlisten”,“msg”:“Attempting to load local voted for document”}
{“t”:{“$date”:“2022-10-28T07:11:16.495+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280502, “ctx”:“initandlisten”,“msg”:“Searching for local Rollback ID document”}
{“t”:{“$date”:“2022-10-28T07:11:16.498+00:00”},“s”:“I”, “c”:“REPL”, “id”:21529, “ctx”:“initandlisten”,“msg”:“Initializing rollback ID”,“attr”:{“rbid”:1}}
{“t”:{“$date”:“2022-10-28T07:11:16.498+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280504, “ctx”:“initandlisten”,“msg”:“Cleaning up any partially applied oplog batches & reading last op from oplog”}
{“t”:{“$date”:“2022-10-28T07:11:16.499+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:6608200, “ctx”:“initandlisten”,“msg”:“Initializing cluster server parameters from disk”}
{“t”:{“$date”:“2022-10-28T07:11:16.499+00:00”},“s”:“I”, “c”:“REPL”, “id”:21544, “ctx”:“initandlisten”,“msg”:“Recovering from stable timestamp”,“attr”:{“stableTimestamp”:{“$timestamp”:{“t”:1666940823,“i”:2}},“topOfOplog”:{“ts”:{“$timestamp”:{“t”:1666940823,“i”:2}},“t”:5},“appliedThrough”:{“ts”:{“$timestamp”:{“t”:0,“i”:0}},“t”:-1}}}
{“t”:{“$date”:“2022-10-28T07:11:16.499+00:00”},“s”:“I”, “c”:“REPL”, “id”:21545, “ctx”:“initandlisten”,“msg”:“Starting recovery oplog application at the stable timestamp”,“attr”:{“stableTimestamp”:{“$timestamp”:{“t”:1666940823,“i”:2}}}}
{“t”:{“$date”:“2022-10-28T07:11:16.499+00:00”},“s”:“I”, “c”:“REPL”, “id”:5466604, “ctx”:“initandlisten”,“msg”:“Start point for recovery oplog application exists in oplog. No adjustment necessary”,“attr”:{“startPoint”:{“$timestamp”:{“t”:1666940823,“i”:2}}}}
{“t”:{“$date”:“2022-10-28T07:11:16.499+00:00”},“s”:“I”, “c”:“REPL”, “id”:21549, “ctx”:“initandlisten”,“msg”:“No oplog entries to apply for recovery. Start point is at the top of the oplog”}
{“t”:{“$date”:“2022-10-28T07:11:16.499+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280505, “ctx”:“initandlisten”,“msg”:“Creating any necessary TenantMigrationAccessBlockers for unfinished migrations”}
{“t”:{“$date”:“2022-10-28T07:11:16.501+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280506, “ctx”:“initandlisten”,“msg”:“Reconstructing prepared transactions”}
{“t”:{“$date”:“2022-10-28T07:11:16.502+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280508, “ctx”:“ReplCoord-0”,“msg”:“Attempting to set local replica set config; validating config for startup”}
{“t”:{“$date”:“2022-10-28T07:11:16.502+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280509, “ctx”:“ReplCoord-0”,“msg”:“Local configuration validated for startup”}
{“t”:{“$date”:“2022-10-28T07:11:16.502+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015317, “ctx”:“ReplCoord-0”,“msg”:“Setting new configuration state”,“attr”:{“newState”:“ConfigSteady”,“oldState”:“ConfigStartingUp”}}
{“t”:{“$date”:“2022-10-28T07:11:16.502+00:00”},“s”:“I”, “c”:“REPL”, “id”:21392, “ctx”:“ReplCoord-0”,“msg”:“New replica set config in use”,“attr”:{“config”:{“_id”:“rs01”,“version”:1,“term”:5,“members”:[{“_id”:0,“host”:“127.0.0.1:27017”,“arbiterOnly”:false,“buildIndexes”:true,“hidden”:false,“priority”:1,“tags”:{},“secondaryDelaySecs”:0,“votes”:1}],“protocolVersion”:1,“writeConcernMajorityJournalDefault”:true,“settings”:{“chainingAllowed”:true,“heartbeatIntervalMillis”:2000,“heartbeatTimeoutSecs”:10,“electionTimeoutMillis”:10000,“catchUpTimeoutMillis”:-1,“catchUpTakeoverDelayMillis”:30000,“getLastErrorModes”:{},“getLastErrorDefaults”:{“w”:1,“wtimeout”:0},“replicaSetId”:{“$oid”:“635934063e8ef6387bb84510”}}}}}
{“t”:{“$date”:“2022-10-28T07:11:16.503+00:00”},“s”:“I”, “c”:“REPL”, “id”:21393, “ctx”:“ReplCoord-0”,“msg”:“Found self in config”,“attr”:{“hostAndPort”:“127.0.0.1:27017”}}
{“t”:{“$date”:“2022-10-28T07:11:16.503+00:00”},“s”:“I”, “c”:“REPL”, “id”:21358, “ctx”:“ReplCoord-0”,“msg”:“Replica set state transition”,“attr”:{“newState”:“STARTUP2”,“oldState”:“STARTUP”}}
{“t”:{“$date”:“2022-10-28T07:11:16.503+00:00”},“s”:“I”, “c”:“REPL”, “id”:21320, “ctx”:“ReplCoord-0”,“msg”:“Updated term”,“attr”:{“term”:5}}
{“t”:{“$date”:“2022-10-28T07:11:16.503+00:00”},“s”:“I”, “c”:“REPL”, “id”:21306, “ctx”:“ReplCoord-0”,“msg”:“Starting replication storage threads”}
{“t”:{“$date”:“2022-10-28T07:11:16.504+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280512, “ctx”:“ReplCoord-0”,“msg”:“No initial sync required. Attempting to begin steady replication”}
{“t”:{“$date”:“2022-10-28T07:11:16.504+00:00”},“s”:“I”, “c”:“REPL”, “id”:21358, “ctx”:“ReplCoord-0”,“msg”:“Replica set state transition”,“attr”:{“newState”:“RECOVERING”,“oldState”:“STARTUP2”}}
{“t”:{“$date”:“2022-10-28T07:11:16.504+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280507, “ctx”:“initandlisten”,“msg”:“Loaded replica set config, scheduled callback to set local config”}
{“t”:{“$date”:“2022-10-28T07:11:16.505+00:00”},“s”:“I”, “c”:“REPL”, “id”:21299, “ctx”:“ReplCoord-0”,“msg”:“Starting replication fetcher thread”}
{“t”:{“$date”:“2022-10-28T07:11:16.505+00:00”},“s”:“I”, “c”:“REPL”, “id”:21300, “ctx”:“ReplCoord-0”,“msg”:“Starting replication applier thread”}
{“t”:{“$date”:“2022-10-28T07:11:16.505+00:00”},“s”:“I”, “c”:“REPL”, “id”:21301, “ctx”:“ReplCoord-0”,“msg”:“Starting replication reporter thread”}
{“t”:{“$date”:“2022-10-28T07:11:16.505+00:00”},“s”:“I”, “c”:“REPL”, “id”:4280511, “ctx”:“ReplCoord-0”,“msg”:“Set local replica set config”}
{“t”:{“$date”:“2022-10-28T07:11:16.505+00:00”},“s”:“I”, “c”:“REPL”, “id”:21224, “ctx”:“OplogApplier-0”,“msg”:“Starting oplog application”}
{“t”:{“$date”:“2022-10-28T07:11:16.505+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:22262, “ctx”:“initandlisten”,“msg”:“Timestamp monitor starting”}
{“t”:{“$date”:“2022-10-28T07:11:16.506+00:00”},“s”:“I”, “c”:“REPL”, “id”:21358, “ctx”:“OplogApplier-0”,“msg”:“Replica set state transition”,“attr”:{“newState”:“SECONDARY”,“oldState”:“RECOVERING”}}
{“t”:{“$date”:“2022-10-28T07:11:16.506+00:00”},“s”:“I”, “c”:“ELECTION”, “id”:4615652, “ctx”:“OplogApplier-0”,“msg”:“Starting an election, since we’ve seen no PRIMARY in election timeout period”,“attr”:{“electionTimeoutPeriodMillis”:10000}}
{“t”:{“$date”:“2022-10-28T07:11:16.506+00:00”},“s”:“I”, “c”:“ELECTION”, “id”:21438, “ctx”:“OplogApplier-0”,“msg”:“Conducting a dry run election to see if we could be elected”,“attr”:{“currentTerm”:5}}
{“t”:{“$date”:“2022-10-28T07:11:16.506+00:00”},“s”:“I”, “c”:“ELECTION”, “id”:21444, “ctx”:“ReplCoord-0”,“msg”:“Dry election run succeeded, running for election”,“attr”:{“newTerm”:6}}
{“t”:{“$date”:“2022-10-28T07:11:16.506+00:00”},“s”:“I”, “c”:“ELECTION”, “id”:6015300, “ctx”:“ReplCoord-0”,“msg”:“Storing last vote document in local storage for my election”,“attr”:{“lastVote”:{“term”:6,“candidateIndex”:0}}}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“ELECTION”, “id”:21450, “ctx”:“ReplCoord-0”,“msg”:“Election succeeded, assuming primary role”,“attr”:{“term”:6}}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21358, “ctx”:“ReplCoord-0”,“msg”:“Replica set state transition”,“attr”:{“newState”:“PRIMARY”,“oldState”:“SECONDARY”}}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21106, “ctx”:“ReplCoord-0”,“msg”:“Resetting sync source to empty”,“attr”:{“previousSyncSource”:“:27017”}}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21359, “ctx”:“ReplCoord-0”,“msg”:“Entering primary catch-up mode”}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015304, “ctx”:“ReplCoord-0”,“msg”:“Skipping primary catchup since we are the only node in the replica set.”}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21363, “ctx”:“ReplCoord-0”,“msg”:“Exited primary catch-up mode”}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21107, “ctx”:“ReplCoord-0”,“msg”:“Stopping replication producer”}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21239, “ctx”:“ReplBatcher”,“msg”:“Oplog buffer has been drained”,“attr”:{“term”:6}}
{“t”:{“$date”:“2022-10-28T07:11:16.508+00:00”},“s”:“I”, “c”:“REPL”, “id”:21239, “ctx”:“ReplBatcher”,“msg”:“Oplog buffer has been drained”,“attr”:{“term”:6}}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:21343, “ctx”:“RstlKillOpThread”,“msg”:“Starting to kill user operations”}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:21344, “ctx”:“RstlKillOpThread”,“msg”:“Stopped killing user operations”}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:21340, “ctx”:“RstlKillOpThread”,“msg”:“State transition ops metrics”,“attr”:{“metrics”:{“lastStateTransition”:“stepUp”,“userOpsKilled”:0,“userOpsRunning”:1}}}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:4508103, “ctx”:“OplogApplier-0”,“msg”:“Increment the config term via reconfig”}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015313, “ctx”:“OplogApplier-0”,“msg”:“Replication config state is Steady, starting reconfig”}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015317, “ctx”:“OplogApplier-0”,“msg”:“Setting new configuration state”,“attr”:{“newState”:“ConfigReconfiguring”,“oldState”:“ConfigSteady”}}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:21353, “ctx”:“OplogApplier-0”,“msg”:“replSetReconfig config object parses ok”,“attr”:{“numMembers”:1}}
{“t”:{“$date”:“2022-10-28T07:11:16.509+00:00”},“s”:“I”, “c”:“REPL”, “id”:51814, “ctx”:“OplogApplier-0”,“msg”:“Persisting new config to disk”}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:40440, “ctx”:“initandlisten”,“msg”:“Starting the TopologyVersionObserver”}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015315, “ctx”:“OplogApplier-0”,“msg”:“Persisted new config to disk”}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015317, “ctx”:“OplogApplier-0”,“msg”:“Setting new configuration state”,“attr”:{“newState”:“ConfigSteady”,“oldState”:“ConfigReconfiguring”}}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:21392, “ctx”:“OplogApplier-0”,“msg”:“New replica set config in use”,“attr”:{“config”:{“_id”:“rs01”,“version”:1,“term”:6,“members”:[{“_id”:0,“host”:“127.0.0.1:27017”,“arbiterOnly”:false,“buildIndexes”:true,“hidden”:false,“priority”:1,“tags”:{},“secondaryDelaySecs”:0,“votes”:1}],“protocolVersion”:1,“writeConcernMajorityJournalDefault”:true,“settings”:{“chainingAllowed”:true,“heartbeatIntervalMillis”:2000,“heartbeatTimeoutSecs”:10,“electionTimeoutMillis”:10000,“catchUpTimeoutMillis”:-1,“catchUpTakeoverDelayMillis”:30000,“getLastErrorModes”:{},“getLastErrorDefaults”:{“w”:1,“wtimeout”:0},“replicaSetId”:{“$oid”:“635934063e8ef6387bb84510”}}}}}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:21393, “ctx”:“OplogApplier-0”,“msg”:“Found self in config”,“attr”:{“hostAndPort”:“127.0.0.1:27017”}}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015310, “ctx”:“OplogApplier-0”,“msg”:“Starting to transition to primary.”}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015309, “ctx”:“OplogApplier-0”,“msg”:“Logging transition to primary to oplog on stepup”}
{“t”:{“$date”:“2022-10-28T07:11:16.510+00:00”},“s”:“I”, “c”:“STORAGE”, “id”:20657, “ctx”:“OplogApplier-0”,“msg”:“IndexBuildsCoordinator::onStepUp - this node is stepping up to primary”}
{“t”:{“$date”:“2022-10-28T07:11:16.512+00:00”},“s”:“I”, “c”:“REPL”, “id”:21331, “ctx”:“OplogApplier-0”,“msg”:“Transition to primary complete; database writes are now permitted”}
{“t”:{“$date”:“2022-10-28T07:11:16.512+00:00”},“s”:“I”, “c”:“REPL”, “id”:6015306, “ctx”:“OplogApplier-0”,“msg”:“Applier already left draining state, exiting.”}
{“t”:{“$date”:“2022-10-28T07:11:16.512+00: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”:400}}
{“t”:{“$date”:“2022-10-28T07:11:16.515+00:00”},“s”:“I”, “c”:“REPL”, “id”:40445, “ctx”:“TopologyVersionObserver”,“msg”:“Started TopologyVersionObserver”}
{“t”:{“$date”:“2022-10-28T07:11:16.516+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:23015, “ctx”:“listener”,“msg”:“Listening on”,“attr”:{“address”:“/tmp/mongodb-27017.sock”}}
{“t”:{“$date”:“2022-10-28T07:11:16.516+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:23015, “ctx”:“listener”,“msg”:“Listening on”,“attr”:{“address”:“127.0.0.1”}}
{“t”:{“$date”:“2022-10-28T07:11:16.516+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:23016, “ctx”:“listener”,“msg”:“Waiting for connections”,“attr”:{“port”:27017,“ssl”:“off”}}
{“t”:{“$date”:“2022-10-28T07:11:16.519+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:23377, “ctx”:“SignalHandler”,“msg”:“Received signal”,“attr”:{“signal”:15,“error”:“Terminated”}}
{“t”:{“$date”:“2022-10-28T07:11:16.519+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:23378, “ctx”:“SignalHandler”,“msg”:“Signal was sent by kill(2)”,“attr”:{“pid”:1,“uid”:0}}
{“t”:{“$date”:“2022-10-28T07:11:16.519+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:23381, “ctx”:“SignalHandler”,“msg”:“will terminate after current cmd ends”}
{“t”:{“$date”:“2022-10-28T07:11:16.519+00:00”},“s”:“I”, “c”:“REPL”, “id”:4784900, “ctx”:“SignalHandler”,“msg”:“Stepping down the ReplicationCoordinator for shutdown”,“attr”:{“waitTimeMillis”:15000}}
{“t”:{“$date”:“2022-10-28T07:11:16.519+00:00”},“s”:“I”, “c”:“REPL”, “id”:4794602, “ctx”:“SignalHandler”,“msg”:“Attempting to enter quiesce mode”}
{“t”:{“$date”:“2022-10-28T07:11:16.519+00:00”},“s”:“I”, “c”:“-”, “id”:6371601, “ctx”:“SignalHandler”,“msg”:“Shutting down the FLE Crud thread pool”}
{“t”:{“$date”:“2022-10-28T07:11:16.520+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“FLECrudNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:16.520+00:00”},“s”:“I”, “c”:“COMMAND”, “id”:4784901, “ctx”:“SignalHandler”,“msg”:“Shutting down the MirrorMaestro”}
{“t”:{“$date”:“2022-10-28T07:11:16.520+00:00”},“s”:“I”, “c”:“REPL”, “id”:40441, “ctx”:“SignalHandler”,“msg”:“Stopping TopologyVersionObserver”}
{“t”:{“$date”:“2022-10-28T07:11:16.520+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123005, “ctx”:“ShardSplitDonorService-0”,“msg”:“Rebuilding PrimaryOnlyService due to stepUp”,“attr”:{“service”:“ShardSplitDonorService”}}
{“t”:{“$date”:“2022-10-28T07:11:16.521+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123005, “ctx”:“TenantMigrationDonorService-0”,“msg”:“Rebuilding PrimaryOnlyService due to stepUp”,“attr”:{“service”:“TenantMigrationDonorService”}}
{“t”:{“$date”:“2022-10-28T07:11:16.521+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123005, “ctx”:“TenantMigrationRecipientService-0”,“msg”:“Rebuilding PrimaryOnlyService due to stepUp”,“attr”:{“service”:“TenantMigrationRecipientService”}}
{“t”:{“$date”:“2022-10-28T07:11:16.522+00:00”},“s”:“I”, “c”:“REPL”, “id”:40447, “ctx”:“TopologyVersionObserver”,“msg”:“Stopped TopologyVersionObserver”}
{“t”:{“$date”:“2022-10-28T07:11:16.522+00:00”},“s”:“I”, “c”:“SHARDING”, “id”:4784902, “ctx”:“SignalHandler”,“msg”:“Shutting down the WaitForMajorityService”}
{“t”:{“$date”:“2022-10-28T07:11:16.523+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:4784903, “ctx”:“SignalHandler”,“msg”:“Shutting down the LogicalSessionCache”}
{“t”:{“$date”:“2022-10-28T07:11:16.523+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:20562, “ctx”:“SignalHandler”,“msg”:“Shutdown: going to close listening sockets”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:23017, “ctx”:“listener”,“msg”:“removing socket file”,“attr”:{“path”:“/tmp/mongodb-27017.sock”}}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:4784905, “ctx”:“SignalHandler”,“msg”:“Shutting down the global connection pool”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:4784906, “ctx”:“SignalHandler”,“msg”:“Shutting down the FlowControlTicketholder”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“-”, “id”:20520, “ctx”:“SignalHandler”,“msg”:“Stopping further Flow Control ticket acquisitions.”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:4784907, “ctx”:“SignalHandler”,“msg”:“Shutting down the replica set node executor”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“ReplNodeDbWorkerNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“CONTROL”, “id”:4784908, “ctx”:“SignalHandler”,“msg”:“Shutting down the PeriodicThreadToAbortExpiredTransactions”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:4784909, “ctx”:“SignalHandler”,“msg”:“Shutting down the ReplicationCoordinator”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:5074000, “ctx”:“SignalHandler”,“msg”:“Shutting down the replica set aware services.”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123006, “ctx”:“SignalHandler”,“msg”:“Shutting down PrimaryOnlyService”,“attr”:{“service”:“TenantMigrationDonorService”,“numInstances”:0,“numOperationContexts”:0}}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“TenantMigrationDonorServiceNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123006, “ctx”:“SignalHandler”,“msg”:“Shutting down PrimaryOnlyService”,“attr”:{“service”:“TenantMigrationRecipientService”,“numInstances”:0,“numOperationContexts”:0}}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“TenantMigrationRecipientServiceNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:5123006, “ctx”:“SignalHandler”,“msg”:“Shutting down PrimaryOnlyService”,“attr”:{“service”:“ShardSplitDonorService”,“numInstances”:0,“numOperationContexts”:0}}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“ShardSplitDonorServiceNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:16.527+00:00”},“s”:“I”, “c”:“REPL”, “id”:21328, “ctx”:“SignalHandler”,“msg”:“Shutting down replication subsystems”}
{“t”:{“$date”:“2022-10-28T07:11:16.528+00:00”},“s”:“I”, “c”:“REPL”, “id”:21302, “ctx”:“SignalHandler”,“msg”:“Stopping replication reporter thread”}
{“t”:{“$date”:“2022-10-28T07:11:16.528+00:00”},“s”:“I”, “c”:“REPL”, “id”:21303, “ctx”:“SignalHandler”,“msg”:“Stopping replication fetcher thread”}
{“t”:{“$date”:“2022-10-28T07:11:16.528+00:00”},“s”:“I”, “c”:“REPL”, “id”:21304, “ctx”:“SignalHandler”,“msg”:“Stopping replication applier thread”}
{“t”:{“$date”:“2022-10-28T07:11:16.913+00:00”},“s”:“I”, “c”:“-”, “id”:4939300, “ctx”:“monitoring-keys-for-HMAC”,“msg”:“Failed to refresh key cache”,“attr”:{“error”:“ShutdownInProgress: Shutdown in progress”,“nextWakeupMillis”:600}}
{“t”:{“$date”:“2022-10-28T07:11:17.507+00:00”},“s”:“I”, “c”:“REPL”, “id”:21107, “ctx”:“BackgroundSync”,“msg”:“Stopping replication producer”}
{“t”:{“$date”:“2022-10-28T07:11:17.512+00:00”},“s”:“I”, “c”:“REPL”, “id”:21225, “ctx”:“OplogApplier-0”,“msg”:“Finished oplog application”}
{“t”:{“$date”:“2022-10-28T07:11:17.512+00:00”},“s”:“I”, “c”:“REPL”, “id”:5698300, “ctx”:“SignalHandler”,“msg”:“Stopping replication applier writer pool”}
{“t”:{“$date”:“2022-10-28T07:11:17.512+00:00”},“s”:“I”, “c”:“REPL”, “id”:21307, “ctx”:“SignalHandler”,“msg”:“Stopping replication storage threads”}
{“t”:{“$date”:“2022-10-28T07:11:17.512+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“OplogApplierNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:17.512+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“SignalHandler”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:17.512+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“ReplCoordExternNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“ASIO”, “id”:22582, “ctx”:“ReplNetwork”,“msg”:“Killing all outstanding egress activity.”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“SHARDING”, “id”:4784910, “ctx”:“SignalHandler”,“msg”:“Shutting down the ShardingInitializationMongoD”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“REPL”, “id”:4784911, “ctx”:“SignalHandler”,“msg”:“Enqueuing the ReplicationStateTransitionLock for shutdown”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“-”, “id”:4784912, “ctx”:“SignalHandler”,“msg”:“Killing all operations for shutdown”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“-”, “id”:4695300, “ctx”:“SignalHandler”,“msg”:“Interrupted all currently running operations”,“attr”:{“opsKilled”:5}}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“TENANT_M”, “id”:5093807, “ctx”:“SignalHandler”,“msg”:“Shutting down all TenantMigrationAccessBlockers on global shutdown”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“COMMAND”, “id”:4784913, “ctx”:“SignalHandler”,“msg”:“Shutting down all open transactions”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“REPL”, “id”:4784914, “ctx”:“SignalHandler”,“msg”:“Acquiring the ReplicationStateTransitionLock for shutdown”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“INDEX”, “id”:4784915, “ctx”:“SignalHandler”,“msg”:“Shutting down the IndexBuildsCoordinator”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“NETWORK”, “id”:4784918, “ctx”:“SignalHandler”,“msg”:“Shutting down the ReplicaSetMonitor”}
{“t”:{“$date”:“2022-10-28T07:11:17.513+00:00”},“s”:“I”, “c”:“REPL”,
and i thnk this point could be the Problem, but i didnt found any good soloution.
{“t”:{“$date”:“2022-10-28T07:11:16.913+00:00”},“s”:“I”, “c”:“-”, “id”:4939300, “ctx”:“monitoring-keys-for-HMAC”,“msg”:“Failed to refresh key cache”,“attr”:{“error”:“ShutdownInProgress: Shutdown in progress”,“nextWakeupMillis”:600}}