Skip to content

PBM-1414 Set Number of Parallel Collections to 1 by default #1084

PBM-1414 Set Number of Parallel Collections to 1 by default

PBM-1414 Set Number of Parallel Collections to 1 by default #1084

Triggered via pull request November 6, 2024 14:08
Status Success
Total duration 40s
Artifacts

trivy.yml

on: pull_request
Fit to window
Zoom out
Zoom in

Annotations

2 errors and 2 warnings
test_PBM-1211.test_pitr_PBM_T268[physical]: psmdb-testing/pbm-functional/pytest/test_PBM-1211.py#L79
AssertionError: Starting restore 2024-11-06T14:27:37.290270721Z to point-in-time 2024-11-06T14:26:15 from '2024-11-06T14:22:34Z'..........................Started physical restore. Waiting to finish.........Error: operation failed with: replay pitr oplog: reply oplog: replay chunk 1730902956.1730903020: apply oplog for chunk: applying an entry: op: {"Timestamp":{"T":1730902990,"I":18},"Term":1,"Hash":null,"Version":2,"Operation":"i","Namespace":"test.test","Object":[{"Key":"_id","Value":"672b7bce5943c69775382332"},{"Key":"doc","Value":1}],"Query":[{"Key":"_id","Value":"672b7bce5943c69775382332"}],"UI":{"Subtype":4,"Data":"F8kbQCFZTpCGkhXQAMqr3w=="},"LSID":"SAAAAAVpZAAQAAAABKMApSn7TUTDhCGz2ASnu8YFdWlkACAAAAAAY5mrDaxi8gv8RmdTsQ+1j7fmkr7JUsabhNmXAheU0fgA","TxnNumber":2,"PrevOpTime":"HAAAABF0cwAAAAAAAAAAABJ0AP//////////AA==","MultiOpType":null} | merr <nil>: applyOps: (NotWritablePrimary) not primary {"t":{"$date":"2024-11-06T14:28:11.515+00:00"},"s":"I", "c":"NETWORK", "id":4915701, "ctx":"-","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":"2024-11-06T14:28:11.517+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":"2024-11-06T14:28:11.518+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":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","namespace":"config.tenantMigrationDonors"}} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","namespace":"config.tenantMigrationRecipients"}} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"ShardSplitDonorService","namespace":"config.tenantSplitDonors"}} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":590,"port":27046,"dbPath":"/var/lib/mongo","architecture":"64-bit","host":"rs201"}} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"CONTROL", "id":23403, "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"6.0.18-15","gitVersion":"469d04ab3373d8c0241c93bb2e98b33869e13b1b","openSSLVersion":"OpenSSL 1.1.1k FIPS 25 Mar 2021","modules":[],"proFeatures":[],"allocator":"tcmalloc","environment":{"distarch":"x86_64","target_arch":"x86_64"}}}} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"CONTROL", "id":51765, "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Oracle Linux Server release 8.10","version":"Kernel 6.5.0-1025-azure"}}} {"t":{"$date":"2024-11-06T14:28:11.519+00:00"},"s":"I", "c":"CONTROL", "id":21951, "ctx":"initandlisten","msg":"Options set by command line","attr":{"options":{"config":"/tmp/pbmMongdTmpConf2041658301","net":{"bindIp":"localhost","port":27046},"replication":{"replSet":"rs2"},"setParameter":{"disableLogicalSessionCacheRefresh":"true","takeUnstableCheckpointOnShutdown":"true"},"storage":{"dbPath":"/var/lib/mongo","directoryPerDB":false,"wiredTiger":{"collectionConfig":{"blockCompressor":"snappy"},"engineConfig":{"directoryForIndexes":false,"journalCompressor":"snappy"},"indexConfig":{"prefixCompression":true}}},"systemLog":{"destination":"file","path":"/var/lib/mongo/pbm.restore.log"}}}} {"t":{"$date":"2024-11-06T14:28:11.520+00:00"},"s":"I", "c":"STORAGE", "id":22270, "ctx":"initandlisten","msg":"Storage engine to use detected by data files","attr":{"dbpath":"/var/lib/mongo","storageEngine":"wiredTiger"}} {"t":{"$date":"2024-11-06T14:28:11.520+00:00"},"s":"I", "c":"STORAGE", "id":22315, "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=7478M,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":"2024-11-06T14:28:11.659+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":659472,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Recovering log 5 through 6"}}} {"t":{"$date":"2024-11-06T14:28:11.689+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":689239,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Recovering log 6 through 6"}}} {"t":{"$date":"2024-11-06T14:28:11.743+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":743359,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Main recovery loop: starting at 5/118528 to 6/256"}}} {"t":{"$date":"2024-11-06T14:28:11.796+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":796669,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Recovering log 5 through 6"}}} {"t":{"$date":"2024-11-06T14:28:11.836+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":836813,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Recovering log 6 through 6"}}} {"t":{"$date":"2024-11-06T14:28:11.860+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":860404,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"recovery log replay has successfully finished and ran for 201 milliseconds"}}} {"t":{"$date":"2024-11-06T14:28:11.860+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":860499,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Set global recovery timestamp: (0, 0)"}}} {"t":{"$date":"2024-11-06T14:28:11.860+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":860517,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"Set global oldest timestamp: (0, 0)"}}} {"t":{"$date":"2024-11-06T14:28:11.861+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":861466,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"recovery rollback to stable has successfully finished and ran for 0 milliseconds"}}} {"t":{"$date":"2024-11-06T14:28:11.863+00:00"},"s":"I", "c":"WTCHKPT", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":863134,"thread":"590:0x7f6f8d21bc40","session_name":"WT_SESSION.checkpoint","category":"WT_VERB_CHECKPOINT_PROGRESS","category_id":6,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"saving checkpoint snapshot min: 1, snapshot max: 1 snapshot count: 0, oldest timestamp: (0, 0) , meta checkpoint timestamp: (0, 0) base write gen: 42"}}} {"t":{"$date":"2024-11-06T14:28:11.865+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":865343,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"recovery checkpoint has successfully finished and ran for 3 milliseconds"}}} {"t":{"$date":"2024-11-06T14:28:11.865+00:00"},"s":"I", "c":"WTRECOV", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":865398,"thread":"590:0x7f6f8d21bc40","session_name":"txn-recover","category":"WT_VERB_RECOVERY_PROGRESS","category_id":30,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"recovery was completed successfully and took 206ms, including 201ms for the log replay, 0ms for the rollback to stable, and 3ms for the checkpoint."}}} {"t":{"$date":"2024-11-06T14:28:11.866+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":346}} {"t":{"$date":"2024-11-06T14:28:11.866+00:00"},"s":"I", "c":"RECOVERY", "id":23987, "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}} {"t":{"$date":"2024-11-06T14:28:11.874+00:00"},"s":"I", "c":"STORAGE", "id":22383, "ctx":"initandlisten","msg":"The size storer reports that the oplog contains","attr":{"numRecords":42,"dataSize":10649}} {"t":{"$date":"2024-11-06T14:28:11.874+00:00"},"s":"I", "c":"STORAGE", "id":22384, "ctx":"initandlisten","msg":"Scanning the oplog to determine where to place markers for truncation"} {"t":{"$date":"2024-11-06T14:28:11.875+00:00"},"s":"I", "c":"STORAGE", "id":22382, "ctx":"initandlisten","msg":"WiredTiger record store oplog processing finished","attr":{"durationMillis":0}} {"t":{"$date":"2024-11-06T14:28:11.900+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. You can use percona-server-mongodb-enable-auth.sh to fix it","tags":["startupWarnings"]} {"t":{"$date":"2024-11-06T14:28:11.900+00:00"},"s":"W", "c":"CONTROL", "id":22178, "ctx":"initandlisten","msg":"/sys/kernel/mm/transparent_hugepage/enabled is 'always'. We suggest setting it to 'never' in this binary version","tags":["startupWarnings"]} {"t":{"$date":"2024-11-06T14:28:11.900+00:00"},"s":"W", "c":"CONTROL", "id":5123300, "ctx":"initandlisten","msg":"vm.max_map_count is too low","attr":{"currentValue":262144,"recommendedMinimum":1677720,"maxConns":838860},"tags":["startupWarnings"]} {"t":{"$date":"2024-11-06T14:28:11.910+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":"2024-11-06T14:28:11.910+00:00"},"s":"I", "c":"REPL", "id":5853300, "ctx":"initandlisten","msg":"current featureCompatibilityVersion value","attr":{"featureCompatibilityVersion":"6.0","context":"startup"}} {"t":{"$date":"2024-11-06T14:28:11.910+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"} {"t":{"$date":"2024-11-06T14:28:11.946+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"} {"t":{"$date":"2024-11-06T14:28:11.946+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/var/lib/mongo/diagnostic.data"}} {"t":{"$date":"2024-11-06T14:28:11.947+00:00"},"s":"W", "c":"SHARDING", "id":22075, "ctx":"initandlisten","msg":"Not started with --shardsvr, but a shardIdentity document was found on disk","attr":{"namespace":"admin.system.version","shardIdentityDocument":{"_id":"shardIdentity","shardName":"rs2","clusterId":{"$oid":"672b7b982d1c0088d359fbce"},"configsvrConnectionString":"rscfg/rscfg01:27017"}}} {"t":{"$date":"2024-11-06T14:28:11.949+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigStartingUp","oldState":"ConfigPreStart"}} {"t":{"$date":"2024-11-06T14:28:11.949+00:00"},"s":"I", "c":"REPL", "id":6005300, "ctx":"initandlisten","msg":"Starting up replica set aware services"} {"t":{"$date":"2024-11-06T14:28:11.949+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":"2024-11-06T14:28:11.950+00:00"},"s":"I", "c":"REPL", "id":4280500, "ctx":"initandlisten","msg":"Attempting to create internal replication collections"} {"t":{"$date":"2024-11-06T14:28:11.952+00:00"},"s":"I", "c":"REPL", "id":4280501, "ctx":"initandlisten","msg":"Attempting to load local voted for document"} {"t":{"$date":"2024-11-06T14:28:11.952+00:00"},"s":"I", "c":"REPL", "id":21311, "ctx":"initandlisten","msg":"Did not find local initialized voted for document at startup"} {"t":{"$date":"2024-11-06T14:28:11.952+00:00"},"s":"I", "c":"REPL", "id":4280502, "ctx":"initandlisten","msg":"Searching for local Rollback ID document"} {"t":{"$date":"2024-11-06T14:28:11.953+00:00"},"s":"I", "c":"REPL", "id":21529, "ctx":"initandlisten","msg":"Initializing rollback ID","attr":{"rbid":1}} {"t":{"$date":"2024-11-06T14:28:11.953+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":"2024-11-06T14:28:11.954+00:00"},"s":"I", "c":"CONTROL", "id":6608200, "ctx":"initandlisten","msg":"Initializing cluster server parameters from disk"} {"t":{"$date":"2024-11-06T14:28:11.954+00:00"},"s":"W", "c":"SHARDING", "id":22075, "ctx":"initandlisten","msg":"Not started with --shardsvr, but a shardIdentity document was found on disk","attr":{"namespace":"admin.system.version","shardIdentityDocument":{"_id":"shardIdentity","shardName":"rs2","clusterId":{"$oid":"672b7b982d1c0088d359fbce"},"configsvrConnectionString":"rscfg/rscfg01:27017"}}} {"t":{"$date":"2024-11-06T14:28:11.954+00:00"},"s":"I", "c":"REPL", "id":21546, "ctx":"initandlisten","msg":"Recovering from an unstable checkpoint","attr":{"topOfOplog":{"ts":{"$timestamp":{"t":1730902954,"i":59}},"t":1},"appliedThrough":{"ts":{"$timestamp":{"t":0,"i":0}},"t":-1}}} {"t":{"$date":"2024-11-06T14:28:11.954+00:00"},"s":"I", "c":"REPL", "id":21547, "ctx":"initandlisten","msg":"No oplog entries to apply for recovery. appliedThrough is null"} {"t":{"$date":"2024-11-06T14:28:11.956+00:00"},"s":"I", "c":"WTCHKPT", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":{"ts_sec":1730903291,"ts_usec":956546,"thread":"590:0x7f6f8d21bc40","session_name":"WT_SESSION.checkpoint","category":"WT_VERB_CHECKPOINT_PROGRESS","category_id":6,"verbose_level":"DEBUG","verbose_level_id":1,"msg":"saving checkpoint snapshot min: 46, snapshot max: 46 snapshot count: 0, oldest timestamp: (0, 0) , meta checkpoint timestamp: (0, 0) base write gen: 42"}}} {"t":{"$date":"2024-11-06T14:28:11.960+00:00"},"s":"I", "c":"REPL", "id":4280505, "ctx":"initandlisten","msg":"Creating any necessary TenantMigrationAccessBlockers for unfinished migrations"} {"t":{"$date":"2024-11-06T14:28:11.961+00:00"},"s":"I", "c":"REPL", "id":4280506, "ctx":"initandlisten","msg":"Reconstructing prepared transactions"} {"t":{"$date":"2024-11-06T14:28:11.962+00:00"},"s":"I", "c":"REPL", "id":4280507, "ctx":"initandlisten","msg":"Loaded replica set config, scheduled callback to set local config"} {"t":{"$date":"2024-11-06T14:28:11.962+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":"2024-11-06T14:28:11.962+00:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"} {"t":{"$date":"2024-11-06T14:28:11.963+00:00"},"s":"I", "c":"REPL", "id":40440, "ctx":"initandlisten","msg":"Starting the TopologyVersionObserver"} {"t":{"$date":"2024-11-06T14:28:11.963+00:00"},"s":"I", "c":"REPL", "id":40445, "ctx":"TopologyVersionObserver","msg":"Started TopologyVersionObserver"} {"t":{"$date":"2024-11-06T14:28:11.993+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-27046.sock"}} {"t":{"$date":"2024-11-06T14:28:11.993+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1"}} {"t":{"$date":"2024-11-06T14:28:11.993+00:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":27046,"ssl":"off"}} {"t":{"$date":"2024-11-06T14:28:11.993+00:00"},"s":"I", "c":"CONTROL", "id":8423403, "ctx":"initandlisten","msg":"mongod startup complete","attr":{"Summary of time elapsed":{"Startup from clean shutdown?":true,"Statistics":{"Transport layer setup":"0 ms","Run initial syncer crash recovery":"0 ms","Create storage engine lock file in the data directory":"0 ms","Create storage engine lock file in the data directory":"0 ms","Get metadata describing storage engine":"0 ms","Get metadata describing storage engine":"0 ms","Validate options in metadata against current startup options":"0 ms","Validate options in metadata against current startup options":"0 ms","Create storage engine":"0 ms","Create storage engine":"376 ms","Write current PID to file":"0 ms","Write current PID to file":"0 ms","Initialize FCV before rebuilding indexes":"0 ms","Initialize FCV before rebuilding indexes":"10 ms","Drop abandoned idents and get back indexes that need to be rebuilt or builds that need to be restarted":"0 ms","Drop abandoned idents and get back indexes that need to be rebuilt or builds that need to be restarted":"0 ms","Rebuild indexes for collections":"0 ms","Rebuild indexes for collections":"0 ms","Build user and roles graph":"0 ms","Verify indexes for admin.system.users collection":"0 ms","Verify indexes for admin.system.users collection":"0 ms","Verify indexes for admin.system.roles collection":"0 ms","Verify indexes for admin.system.roles collection":"0 ms","Set up the background thread pool responsible for waiting for opTimes to be majority committed":"0 ms","Start up cluster time keys manager with a local/direct keys client":"0 ms","Start up the replication coordinator":"13 ms","Create an oplog view for tenant migrations":"0 ms","Start transport layer":"29 ms","_initAndListen total elapsed time":"474 ms"}}}} {"t":{"$date":"2024-11-06T14:28:11.993+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:33732","uuid":"5bdee3dd-96d4-4d28-bd78-43fb6b1b7b68","connectionId":2,"connectionCount":1}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":4280509, "ctx":"ReplCoord-0","msg":"Local configuration validated for startup"} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"ReplCoord-0","msg":"Setting new configuration state","attr":{"newState":"ConfigSteady","oldState":"ConfigStartingUp"}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn2","msg":"Connection ended","attr":{"remote":"127.0.0.1:33732","uuid":"5bdee3dd-96d4-4d28-bd78-43fb6b1b7b68","connectionId":2,"connectionCount":0}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":21392, "ctx":"ReplCoord-0","msg":"New replica set config in use","attr":{"config":{"_id":"rs2","version":1,"members":[{"_id":0,"host":"localhost:27046","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}}}}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":21393, "ctx":"ReplCoord-0","msg":"Found self in config","attr":{"hostAndPort":"localhost:27046"}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"ReplCoord-0","msg":"Replica set state transition","attr":{"newState":"STARTUP2","oldState":"STARTUP"}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":21320, "ctx":"ReplCoord-0","msg":"Updated term","attr":{"term":1}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":21306, "ctx":"ReplCoord-0","msg":"Starting replication storage threads"} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":4280512, "ctx":"ReplCoord-0","msg":"No initial sync required. Attempting to begin steady replication"} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"ReplCoord-0","msg":"Replica set state transition","attr":{"newState":"RECOVERING","oldState":"STARTUP2"}} {"t":{"$date":"2024-11-06T14:28:11.994+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"ReplCoord-0","msg":"createCollection","attr":{"namespace":"local.replset.initialSyncId","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"bc8faf32-44f3-4ba8-ad43-70f3e6c8322d"}},"options":{}}} {"t":{"$date":"2024-11-06T14:28:11.997+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:33734","uuid":"096f9ef1-29cf-4f68-8bac-d67b5aceaae8","connectionId":3,"connectionCount":1}} {"t":{"$date":"2024-11-06T14:28:11.997+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn3","msg":"client metadata","attr":{"remote":"127.0.0.1:33734","client":"conn3","negotiatedCompressors":[],"doc":{"application":{"name":"pbm-physical-restore"},"driver":{"name":"mongo-go-driver","version":"1.16.0"},"os":{"type":"linux","architecture":"amd64"},"platform":"go1.22.8","env":{"container":{"runtime":"docker"}}}}} {"t":{"$date":"2024-11-06T14:28:11.997+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:33746","uuid":"68826309-378c-4a53-b64b-7b24aca4dae3","connectionId":4,"connectionCount":2}} {"t":{"$date":"2024-11-06T14:28:11.998+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:33750","uuid":"98b6557b-b9b4-4eec-9dfb-1ffb559c0adb","connectionId":5,"connectionCount":3}} {"t":{"$date":"2024-11-06T14:28:11.998+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn4","msg":"client metadata","attr":{"remote":"127.0.0.1:33746","client":"conn4","negotiatedCompressors":[],"doc":{"application":{"name":"pbm-physical-restore"},"driver":{"name":"mongo-go-driver","version":"1.16.0"},"os":{"type":"linux","architecture":"amd64"},"platform":"go1.22.8","env":{"container":{"runtime":"docker"}}}}} {"t":{"$date":"2024-11-06T14:28:11.998+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn5","msg":"client metadata","attr":{"remote":"127.0.0.1:33750","client":"conn5","negotiatedCompressors":[],"doc":{"application":{"name":"pbm-physical-restore"},"driver":{"name":"mongo-go-driver","version":"1.16.0"},"os":{"type":"linux","architecture":"amd64"},"platform":"go1.22.8","env":{"container":{"runtime":"docker"}}}}} {"t":{"$date":"2024-11-06T14:28:12.005+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"ReplCoord-0","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"bc8faf32-44f3-4ba8-ad43-70f3e6c8322d"}},"namespace":"local.replset.initialSyncId","index":"_id_","ident":"index-1-6912022594500596895","collectionIdent":"collection-0-6912022594500596895","commitTimestamp":null}} {"t":{"$date":"2024-11-06T14:28:12.006+00:00"},"s":"I", "c":"REPL", "id":21299, "ctx":"ReplCoord-0","msg":"Starting replication fetcher thread"} {"t":{"$date":"2024-11-06T14:28:12.007+00:00"},"s":"I", "c":"REPL", "id":21300, "ctx":"ReplCoord-0","msg":"Starting replication applier thread"} {"t":{"$date":"2024-11-06T14:28:12.007+00:00"},"s":"I", "c":"REPL", "id":21224, "ctx":"OplogApplier-0","msg":"Starting oplog application"} {"t":{"$date":"2024-11-06T14:28:12.008+00:00"},"s":"I", "c":"REPL", "id":21301, "ctx":"ReplCoord-0","msg":"Starting replication reporter thread"} {"t":{"$date":"2024-11-06T14:28:12.008+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"OplogApplier-0","msg":"Replica set state transition","attr":{"newState":"SECONDARY","oldState":"RECOVERING"}} {"t":{"$date":"2024-11-06T14:28:12.008+00:00"},"s":"I", "c":"REPL", "id":4280511, "ctx":"ReplCoord-0","msg":"Set local replica set config"} {"t":{"$date":"2024-11-06T14:28:12.008+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":"2024-11-06T14:28:12.008+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":1}} {"t":{"$date":"2024-11-06T14:28:12.008+00:00"},"s":"I", "c":"ELECTION", "id":21444, "ctx":"ReplCoord-0","msg":"Dry election run succeeded, running for election","attr":{"newTerm":2}} {"t":{"$date":"2024-11-06T14:28:12.009+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":2,"candidateIndex":0}}} {"t":{"$date":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"ELECTION", "id":21450, "ctx":"ReplCoord-0","msg":"Election succeeded, assuming primary role","attr":{"term":2}} {"t":{"$date":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"ReplCoord-0","msg":"Replica set state transition","attr":{"newState":"PRIMARY","oldState":"SECONDARY"}} {"t":{"$date":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"REPL", "id":21106, "ctx":"ReplCoord-0","msg":"Resetting sync source to empty","attr":{"previousSyncSource":":27017"}} {"t":{"$date":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"REPL", "id":21359, "ctx":"ReplCoord-0","msg":"Entering primary catch-up mode"} {"t":{"$date":"2024-11-06T14:28:12.009+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":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"REPL", "id":21363, "ctx":"ReplCoord-0","msg":"Exited primary catch-up mode"} {"t":{"$date":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"REPL", "id":21107, "ctx":"ReplCoord-0","msg":"Stopping replication producer"} {"t":{"$date":"2024-11-06T14:28:12.009+00:00"},"s":"I", "c":"REPL", "id":21239, "ctx":"ReplBatcher","msg":"Oplog buffer has been drained","attr":{"term":2}} {"t":{"$date":"2024-11-06T14:28:12.010+00:00"},"s":"I", "c":"REPL", "id":21343, "ctx":"RstlKillOpThread","msg":"Starting to kill user operations"} {"t":{"$date":"2024-11-06T14:28:12.010+00:00"},"s":"I", "c":"REPL", "id":21344, "ctx":"RstlKillOpThread","msg":"Stopped killing user operations"} {"t":{"$date":"2024-11-06T14:28:12.010+00:00"},"s":"I", "c":"REPL", "id":21340, "ctx":"RstlKillOpThread","msg":"State transition ops metrics","attr":{"metrics":{"lastStateTransition":"stepUp","userOpsKilled":0,"userOpsRunning":2}}} {"t":{"$date":"2024-11-06T14:28:12.010+00:00"},"s":"I", "c":"REPL", "id":6015310, "ctx":"OplogApplier-0","msg":"Starting to transition to primary."} {"t":{"$date":"2024-11-06T14:28:12.010+00:00"},"s":"I", "c":"REPL", "id":6015309, "ctx":"OplogApplier-0","msg":"Logging transition to primary to oplog on stepup"} {"t":{"$date":"2024-11-06T14:28:12.011+00:00"},"s":"I", "c":"STORAGE", "id":20657, "ctx":"OplogApplier-0","msg":"IndexBuildsCoordinator::onStepUp - this node is stepping up to primary"} {"t":{"$date":"2024-11-06T14:28:12.011+00:00"},"s":"I", "c":"REPL", "id":21331, "ctx":"OplogApplier-0","msg":"Transition to primary complete; database writes are now permitted"} {"t":{"$date":"2024-11-06T14:28:12.011+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"monitoring-keys-for-HMAC","msg":"createCollection","attr":{"namespace":"admin.system.keys","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"90ee851e-b96f-4c13-b571-3000688e085a"}},"options":{}}} {"t":{"$date":"2024-11-06T14:28:12.012+00:00"},"s":"I", "c":"STORAGE", "id":22310, "ctx":"JournalFlusher","msg":"Triggering the first stable checkpoint","attr":{"initialDataTimestamp":{"$timestamp":{"t":1730902954,"i":59}},"prevStableTimestamp":{"$timestamp":{"t":0,"i":0}},"currStableTimestamp":{"$timestamp":{"t":1730903292,"i":2}}}} {"t":{"$date":"2024-11-06T14:28:12.013+00:00"},"s":"I", "c":"REPL", "id":5123005, "ctx":"ShardSplitDonorService-0","msg":"Rebuilding PrimaryOnlyService due to stepUp","attr":{"service":"ShardSplitDonorService"}} {"t":{"$date":"2024-11-06T14:28:12.013+00:00"},"s":"I", "c":"REPL", "id":5123005, "ctx":"TenantMigrationRecipientService-0","msg":"Rebuilding PrimaryOnlyService due to stepUp","attr":{"service":"TenantMigrationRecipientService"}} {"t":{"$date":"2024-11-06T14:28:12.013+00:00"},"s":"I", "c":"REPL", "id":5123005, "ctx":"TenantMigrationDonorService-0","msg":"Rebuilding PrimaryOnlyService due to stepUp","attr":{"service":"TenantMigrationDonorService"}} {"t":{"$date":"2024-11-06T14:28:12.016+00:00"},"s":"E", "c":"WT", "id":22435, "ctx":"Checkpointer","msg":"WiredTiger error message","attr":{"error":2,"message":{"ts_sec":1730903292,"ts_usec":16702,"thread":"590:0x7f6f76531700","session_dhandle_name":"file:collection-2--4578689101365002138.wt","session_name":"WT_SESSION.checkpoint","category":"WT_VERB_DEFAULT","category_id":9,"verbose_level":"ERROR","verbose_level_id":-3,"msg":"__posix_fs_size:296:/var/lib/mongo/WiredTigerHS.wt: file-size: stat","error_str":"No such file or directory","error_code":2}}} {"t":{"$date":"2024-11-06T14:28:12.016+00:00"},"s":"E", "c":"WT", "id":22435, "ctx":"Checkpointer","msg":"WiredTiger error message","attr":{"error":2,"message":{"ts_sec":1730903292,"ts_usec":16790,"thread":"590:0x7f6f76531700","session_dhandle_name":"file:collection-2--4578689101365002138.wt","session_name":"WT_SESSION.checkpoint","category":"WT_VERB_DEFAULT","category_id":9,"verbose_level":"ERROR","verbose_level_id":-3,"msg":"__reconcile:377:reconciliation failed after building the disk image","error_str":"No such file or directory","error_code":2}}} {"t":{"$date":"2024-11-06T14:28:12.016+00:00"},"s":"E", "c":"WT", "id":22435, "ctx":"Checkpointer","msg":"WiredTiger error message","attr":{"error":-31804,"message":{"ts_sec":1730903292,"ts_usec":16819,"thread":"590:0x7f6f76531700","session_dhandle_name":"file:collection-2--4578689101365002138.wt","session_name":"WT_SESSION.checkpoint","category":"WT_VERB_DEFAULT","category_id":9,"verbose_level":"ERROR","verbose_level_id":-3,"msg":"__reconcile:377:the process must exit and restart","error_str":"WT_PANIC: WiredTiger library panic","error_code":-31804}}} {"t":{"$date":"2024-11-06T14:28:12.016+00:00"},"s":"F", "c":"ASSERT", "id":23089, "ctx":"Checkpointer","msg":"Fatal assertion","attr":{"msgid":50853,"file":"src/mongo/db/storage/wiredtiger/wiredtiger_util.cpp","line":715}} {"t":{"$date":"2024-11-06T14:28:12.016+00:00"},"s":"F", "c":"ASSERT", "id":23090, "ctx":"Checkpointer","msg":"\n\n***aborting after fassert() failure\n\n"} {"t":{"$date":"2024-11-06T14:28:12.016+00:00"},"s":"F", "c":"CONTROL", "id":6384300, "ctx":"Checkpointer","msg":"Writing fatal message","attr":{"message":"Got signal: 6 (Aborted).\n"}} {"t":{"$date":"2024-11-06T14:28:12.018+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"monitoring-keys-for-HMAC","msg":"Index build: done building","attr":{"buildUUID":null,"collectionUUID":{"uuid":{"$uuid":"90ee851e-b96f-4c13-b571-3000688e085a"}},"namespace":"admin.system.keys","index":"_id_","ident":"index-3-6912022594500596895","collectionIdent":"collection-2-6912022594500596895","commitTimestamp":{"$timestamp":{"t":1730903292,"i":3}}}} {"t":{"$date":"2024-11-06T14:28:12.023+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn5","msg":"Connection ended","attr":{"remote":"127.0.0.1:33750","uuid":"98b6557b-b9b4-4eec-9dfb-1ffb559c0adb","connectionId":5,"connectionCount":1}} {"t":{"$date":"2024-11-06T14:28:12.023+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn4","msg":"Connection ended","attr":{"remote":"127.0.0.1:33746","uuid":"68826309-378c-4a53-b64b-7b24aca4dae3","connectionId":4,"connectionCount":2}} {"t":{"$date":"2024-11-06T14:28:12.024+00:00"},"s":"I", "c":"-", "id":20883, "ctx":"conn3","msg":"Interrupted operation as its client disconnected","attr":{"opId":45}} {"t":{"$date":"2024-11-06T14:28:12.024+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn3","msg":"Connection ended","attr":{"remote":"127.0.0.1:33734","uuid":"096f9ef1-29cf-4f68-8bac-d67b5aceaae8","connectionId":3,"connectionCount":0}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31380, "ctx":"Checkpointer","msg":"BACKTRACE","attr":{"bt":{"backtrace":[{"a":"5624C16CF2D1","b":"5624BC064000","o":"566B2D1","s":"_ZN5mongo34StackTraceAddressMetadataGenerator4loadEPv","C":"mongo::StackTraceAddressMetadataGenerator::load(void*)","s+":"2691"},{"a":"5624C16D1658","b":"5624BC064000","o":"566D658","s":"_ZN5mongo15printStackTraceEv","C":"mongo::printStackTrace()","s+":"38"},{"a":"5624C16C9048","b":"5624BC064000","o":"5665048","s":"abruptQuit","s+":"68"},{"a":"7F6F8A998D70","b":"7F6F8A986000","o":"12D70","s":"funlockfile","s+":"50"},{"a":"7F6F8A5FE5EF","b":"7F6F8A5B0000","o":"4E5EF","s":"gsignal","s+":"10F"},{"a":"7F6F8A5D1E65","b":"7F6F8A5B0000","o":"21E65","s":"abort","s+":"127"},{"a":"5624BE15CF35","b":"5624BC064000","o":"20F8F35","s":"_ZN5mongo25fassertFailedWithLocationEiPKcj","C":"mongo::fassertFailedWithLocation(int, char const*, unsigned int)","s+":"F7"},{"a":"5624BDDA189F","b":"5624BC064000","o":"1D3D89F","s":"_ZN5mongo6StatusC2IRA77_KcLi0EEENS_10ErrorCodes5ErrorEOT_","C":"mongo::Status::Status<char const (&) [77], 0>(mongo::ErrorCodes::Error, char const (&) [77])","s+":"4E77"},{"a":"5624BF480129","b":"5624BC064000","o":"341C129","s":"__wt_encrypt_size","s+":"15A9"},{"a":"5624BDDB6E36","b":"5624BC064000","o":"1D52E36","s":"__wt_panic_func","s+":"13C"},{"a":"5624BF45CB24","b":"5624BC064000","o":"33F8B24","s":"__wt_reconcile","s+":"654"},{"a":"5624BF503EEA","b":"5624BC064000","o":"349FEEA","s":"__wt_sync_file","s+":"65A"},{"a":"5624BF49F36A","b":"5624BC064000","o":"343B36A","s":"__wt_checkpoint_tree_reconcile_update","s+":"1CA"},{"a":"5624BF4A04C8","b":"5624BC064000","o":"343C4C8","s":"__wt_checkpoint_sync","s+":"9F8"},{"a":"5624BF4A1EB6","b":"5624BC064000","o":"343DEB6","s":"__wt_txn_checkpoint","s+":"246"},{"a":"5624BDDB5F1A","b":"5624BC064000","o":"1D51F1A","s":"__wt_session_notsup","s+":"11B5"},{"a":"5624BF380B18","b":"5624BC064000","o":"331CB18","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_sessionb","C":"mongo::WiredTigerKVEngine::_checkpoint(__wt_session*, bool)","s+":"48"},{"a":"5624BF391DCD","b":"5624BC064000","o":"332DDCD","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_session","C":"mongo::WiredTigerKVEngine::_checkpoint(__wt_session*)","s+":"2BD"},{"a":"5624BF3949A1","b":"5624BC064000","o":"33309A1","s":"_ZN5mongo18WiredTigerKVEngine10checkpointEv","C":"mongo::WiredTigerKVEngine::checkpoint()","s+":"41"},{"a":"5624BF6EF749","b":"5624BC064000","o":"368B749","s":"_ZN5mongo12Checkpointer3runEv","C":"mongo::Checkpointer::run()","s+":"E79"},{"a":"5624C1488F6A","b":"5624BC064000","o":"5424F6A","s":"_ZN5mongo13BackgroundJob7jobBodyEv","C":"mongo::BackgroundJob::jobBody()","s+":"2AA"},{"a":"5624C1489462","b":"5624BC064000","o":"5425462","s":"_ZN5mongo13BackgroundJob7jobBodyEv","C":"mongo::BackgroundJob::jobBody()","s+":"7A2"},{"a":"7F6F8B203B23","b":"7F6F8B141000","o":"C2B23","s":"_ZNKSt10error_code23default_error_conditionEv","C":"std::error_code::default_error_condition() const","s+":"33"},{"a":"7F6F8A98E1DA","b":"7F6F8A986000","o":"81DA","s":"start_thread","s+":"EA"},{"a":"7F6F8A5E98D3","b":"7F6F8A5B0000","o":"398D3","s":"clone","s+":"43"}],"processInfo":{"mongodbVersion":"6.0.18-15","gitVersion":"469d04ab3373d8c0241c93bb2e98b33869e13b1b","compiledModules":[],"uname":{"sysname":"Linux","release":"6.5.0-1025-azure","version":"#26~22.04.1-Ubuntu SMP Thu Jul 11 22:33:04 UTC 2024","machine":"x86_64"},"somap":[{"b":"5624BC064000","elfType":3,"buildId":"2DFD145D81515944A203FD1DC618DF9E89215B46"},{"b":"7F6F8B141000","path":"/lib64/libstdc++.so.6","elfType":3,"buildId":"41809D0B18272BC525772714DA54108BF1C59253"},{"b":"7F6F8A986000","path":"/lib64/libpthread.so.0","elfType":3,"buildId":"E144DC0B1B2E7DA6C5B2191AC90367D60923235E"},{"b":"7F6F8A5B0000","path":"/lib64/libc.so.6","elfType":3,"buildId":"A3904D14B666122259AB466667C8100B8295FE16"}]}}},"tags":[]} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624C16CF2D1","b":"5624BC064000","o":"566B2D1","s":"_ZN5mongo34StackTraceAddressMetadataGenerator4loadEPv","C":"mongo::StackTraceAddressMetadataGenerator::load(void*)","s+":"2691"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624C16D1658","b":"5624BC064000","o":"566D658","s":"_ZN5mongo15printStackTraceEv","C":"mongo::printStackTrace()","s+":"38"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624C16C9048","b":"5624BC064000","o":"5665048","s":"abruptQuit","s+":"68"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F6F8A998D70","b":"7F6F8A986000","o":"12D70","s":"funlockfile","s+":"50"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F6F8A5FE5EF","b":"7F6F8A5B0000","o":"4E5EF","s":"gsignal","s+":"10F"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F6F8A5D1E65","b":"7F6F8A5B0000","o":"21E65","s":"abort","s+":"127"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BE15CF35","b":"5624BC064000","o":"20F8F35","s":"_ZN5mongo25fassertFailedWithLocationEiPKcj","C":"mongo::fassertFailedWithLocation(int, char const*, unsigned int)","s+":"F7"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BDDA189F","b":"5624BC064000","o":"1D3D89F","s":"_ZN5mongo6StatusC2IRA77_KcLi0EEENS_10ErrorCodes5ErrorEOT_","C":"mongo::Status::Status<char const (&) [77], 0>(mongo::ErrorCodes::Error, char const (&) [77])","s+":"4E77"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF480129","b":"5624BC064000","o":"341C129","s":"__wt_encrypt_size","s+":"15A9"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BDDB6E36","b":"5624BC064000","o":"1D52E36","s":"__wt_panic_func","s+":"13C"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF45CB24","b":"5624BC064000","o":"33F8B24","s":"__wt_reconcile","s+":"654"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF503EEA","b":"5624BC064000","o":"349FEEA","s":"__wt_sync_file","s+":"65A"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF49F36A","b":"5624BC064000","o":"343B36A","s":"__wt_checkpoint_tree_reconcile_update","s+":"1CA"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF4A04C8","b":"5624BC064000","o":"343C4C8","s":"__wt_checkpoint_sync","s+":"9F8"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF4A1EB6","b":"5624BC064000","o":"343DEB6","s":"__wt_txn_checkpoint","s+":"246"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BDDB5F1A","b":"5624BC064000","o":"1D51F1A","s":"__wt_session_notsup","s+":"11B5"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF380B18","b":"5624BC064000","o":"331CB18","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_sessionb","C":"mongo::WiredTigerKVEngine::_checkpoint(__wt_session*, bool)","s+":"48"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF391DCD","b":"5624BC064000","o":"332DDCD","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_session","C":"mongo::WiredTigerKVEngine::_checkpoint(__wt_session*)","s+":"2BD"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF3949A1","b":"5624BC064000","o":"33309A1","s":"_ZN5mongo18WiredTigerKVEngine10checkpointEv","C":"mongo::WiredTigerKVEngine::checkpoint()","s+":"41"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624BF6EF749","b":"5624BC064000","o":"368B749","s":"_ZN5mongo12Checkpointer3runEv","C":"mongo::Checkpointer::run()","s+":"E79"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624C1488F6A","b":"5624BC064000","o":"5424F6A","s":"_ZN5mongo13BackgroundJob7jobBodyEv","C":"mongo::BackgroundJob::jobBody()","s+":"2AA"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5624C1489462","b":"5624BC064000","o":"5425462","s":"_ZN5mongo13BackgroundJob7jobBodyEv","C":"mongo::BackgroundJob::jobBody()","s+":"7A2"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F6F8B203B23","b":"7F6F8B141000","o":"C2B23","s":"_ZNKSt10error_code23default_error_conditionEv","C":"std::error_code::default_error_condition() const","s+":"33"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F6F8A98E1DA","b":"7F6F8A986000","o":"81DA","s":"start_thread","s+":"EA"}}} {"t":{"$date":"2024-11-06T14:28:12.090+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F6F8A5E98D3","b":"7F6F8A5B0000","o":"398D3","s":"clone","s+":"43"}}}
test_PBM-1211.test_pitr_PBM_T268[physical]: psmdb-testing/pbm-functional/pytest/test_PBM-1211.py#L79
AssertionError: Starting restore 2024-11-06T14:27:08.676332345Z to point-in-time 2024-11-06T14:25:54 from '2024-11-06T14:22:15Z'...........................Started physical restore. Waiting to finish.............Error: operation failed with: replay pitr oplog: reply oplog: replay chunk 1730902937.1730903001: apply oplog for chunk: applying an entry: op: {"Timestamp":{"T":1730902971,"I":16},"Term":1,"Hash":null,"Version":2,"Operation":"i","Namespace":"test.test","Object":[{"Key":"_id","Value":"672b7bbb698b0a9631268ffc"},{"Key":"doc","Value":0}],"Query":null,"UI":{"Subtype":4,"Data":"K1d0z8sKQC2+KtuXajuRBw=="},"LSID":"SAAAAAVpZAAQAAAABFrr6VOjH00su6SNol0FbX8FdWlkACAAAAAAY5mrDaxi8gv8RmdTsQ+1j7fmkr7JUsabhNmXAheU0fgA","TxnNumber":1,"PrevOpTime":"HAAAABF0cwAAAAAAAAAAABJ0AP//////////AA==","MultiOpType":null} | merr <nil>: applyOps: (NotWritablePrimary) not primary {"t":{"$date":"2024-11-06T14:27:48.167+00:00"},"s":"I", "c":"CONTROL", "id":23285, "ctx":"-","msg":"Automatically disabling TLS 1.0, to force-enable TLS 1.0 specify --sslDisabledProtocols 'none'"} {"t":{"$date":"2024-11-06T14:27:48.170+00:00"},"s":"I", "c":"NETWORK", "id":4915701, "ctx":"main","msg":"Initialized wire specification","attr":{"spec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":13},"outgoing":{"minWireVersion":0,"maxWireVersion":13},"isInternalClient":true}}} {"t":{"$date":"2024-11-06T14:27:48.170+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"} {"t":{"$date":"2024-11-06T14:27:48.170+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":"2024-11-06T14:27:48.170+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"W", "c":"ASIO", "id":22601, "ctx":"main","msg":"No TransportLayer configured during NetworkInterface startup"} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationDonorService","ns":"config.tenantMigrationDonors"}} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"REPL", "id":5123008, "ctx":"main","msg":"Successfully registered PrimaryOnlyService","attr":{"service":"TenantMigrationRecipientService","ns":"config.tenantMigrationRecipients"}} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"CONTROL", "id":5945603, "ctx":"main","msg":"Multi threading initialized"} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"CONTROL", "id":4615611, "ctx":"initandlisten","msg":"MongoDB starting","attr":{"pid":513,"port":27955,"dbPath":"/var/lib/mongo","architecture":"64-bit","host":"rs101"}} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"CONTROL", "id":23403, "ctx":"initandlisten","msg":"Build Info","attr":{"buildInfo":{"version":"5.0.29-25","gitVersion":"6ed5ea3065f1daafa7661d1c05d20c50b614a96a","openSSLVersion":"OpenSSL 1.1.1k FIPS 25 Mar 2021","modules":[],"allocator":"tcmalloc","environment":{"distarch":"x86_64","target_arch":"x86_64"}}}} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"CONTROL", "id":51765, "ctx":"initandlisten","msg":"Operating System","attr":{"os":{"name":"Oracle Linux Server release 8.10","version":"Kernel 6.5.0-1025-azure"}}} {"t":{"$date":"2024-11-06T14:27:48.171+00:00"},"s":"I", "c":"CONTROL", "id":21951, "ctx":"initandlisten","msg":"Options set by command line","attr":{"options":{"config":"/tmp/pbmMongdTmpConf2292238028","net":{"bindIp":"localhost","port":27955},"replication":{"replSet":"rs1"},"setParameter":{"disableLogicalSessionCacheRefresh":"true","takeUnstableCheckpointOnShutdown":"true"},"storage":{"dbPath":"/var/lib/mongo","directoryPerDB":false,"wiredTiger":{"collectionConfig":{"blockCompressor":"snappy"},"engineConfig":{"directoryForIndexes":false,"journalCompressor":"snappy"},"indexConfig":{"prefixCompression":true}}},"systemLog":{"destination":"file","path":"/var/lib/mongo/pbm.restore.log"}}}} {"t":{"$date":"2024-11-06T14:27:48.172+00:00"},"s":"I", "c":"STORAGE", "id":22270, "ctx":"initandlisten","msg":"Storage engine to use detected by data files","attr":{"dbpath":"/var/lib/mongo","storageEngine":"wiredTiger"}} {"t":{"$date":"2024-11-06T14:27:48.172+00:00"},"s":"I", "c":"STORAGE", "id":22315, "ctx":"initandlisten","msg":"Opening WiredTiger","attr":{"config":"create,cache_size=7478M,session_max=33000,eviction=(threads_min=4,threads_max=4),config_base=false,statistics=(fast),log=(enabled=true,archive=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),verbose=[recovery_progress,checkpoint_progress,compact_progress],"}} {"t":{"$date":"2024-11-06T14:27:48.372+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:372549][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 5 through 6"}} {"t":{"$date":"2024-11-06T14:27:48.398+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:398750][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 6 through 6"}} {"t":{"$date":"2024-11-06T14:27:48.449+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:449186][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_ALL] Main recovery loop: starting at 5/47232 to 6/256"}} {"t":{"$date":"2024-11-06T14:27:48.492+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:492205][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 5 through 6"}} {"t":{"$date":"2024-11-06T14:27:48.525+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:525349][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] Recovering log 6 through 6"}} {"t":{"$date":"2024-11-06T14:27:48.546+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:546189][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] recovery log replay has successfully finished and ran for 173 milliseconds"}} {"t":{"$date":"2024-11-06T14:27:48.546+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:546268][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_ALL] Set global recovery timestamp: (0, 0)"}} {"t":{"$date":"2024-11-06T14:27:48.546+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:546280][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_ALL] Set global oldest timestamp: (0, 0)"}} {"t":{"$date":"2024-11-06T14:27:48.547+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:547150][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] recovery rollback to stable has successfully finished and ran for 0 milliseconds"}} {"t":{"$date":"2024-11-06T14:27:48.562+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:562203][513:0x7f9636fa3c00], WT_SESSION.checkpoint: [WT_VERB_CHECKPOINT_PROGRESS] saving checkpoint snapshot min: 1, snapshot max: 1 snapshot count: 0, oldest timestamp: (0, 0) , meta checkpoint timestamp: (0, 0) base write gen: 42"}} {"t":{"$date":"2024-11-06T14:27:48.564+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:564404][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] recovery checkpoint has successfully finished and ran for 4 milliseconds"}} {"t":{"$date":"2024-11-06T14:27:48.564+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:564443][513:0x7f9636fa3c00], txn-recover: [WT_VERB_RECOVERY_PROGRESS] recovery was completed successfully and took 192ms, including 173ms for the log replay, 0ms for the rollback to stable, and 4ms for the checkpoint."}} {"t":{"$date":"2024-11-06T14:27:48.565+00:00"},"s":"I", "c":"STORAGE", "id":4795906, "ctx":"initandlisten","msg":"WiredTiger opened","attr":{"durationMillis":393}} {"t":{"$date":"2024-11-06T14:27:48.565+00:00"},"s":"I", "c":"RECOVERY", "id":23987, "ctx":"initandlisten","msg":"WiredTiger recoveryTimestamp","attr":{"recoveryTimestamp":{"$timestamp":{"t":0,"i":0}}}} {"t":{"$date":"2024-11-06T14:27:48.573+00:00"},"s":"I", "c":"STORAGE", "id":22383, "ctx":"initandlisten","msg":"The size storer reports that the oplog contains","attr":{"numRecords":47,"dataSize":13138}} {"t":{"$date":"2024-11-06T14:27:48.573+00:00"},"s":"I", "c":"STORAGE", "id":22384, "ctx":"initandlisten","msg":"Scanning the oplog to determine where to place markers for truncation"} {"t":{"$date":"2024-11-06T14:27:48.574+00:00"},"s":"I", "c":"STORAGE", "id":22382, "ctx":"initandlisten","msg":"WiredTiger record store oplog processing finished","attr":{"durationMillis":0}} {"t":{"$date":"2024-11-06T14:27:48.587+00:00"},"s":"I", "c":"STORAGE", "id":22262, "ctx":"initandlisten","msg":"Timestamp monitor starting"} {"t":{"$date":"2024-11-06T14:27:48.589+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. You can use percona-server-mongodb-enable-auth.sh to fix it","tags":["startupWarnings"]} {"t":{"$date":"2024-11-06T14:27:48.589+00:00"},"s":"W", "c":"CONTROL", "id":22178, "ctx":"initandlisten","msg":"/sys/kernel/mm/transparent_hugepage/enabled is 'always'. We suggest setting it to 'never' in this binary version","tags":["startupWarnings"]} {"t":{"$date":"2024-11-06T14:27:48.597+00:00"},"s":"I", "c":"NETWORK", "id":4915702, "ctx":"initandlisten","msg":"Updated wire specification","attr":{"oldSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":0,"maxWireVersion":13},"outgoing":{"minWireVersion":0,"maxWireVersion":13},"isInternalClient":true},"newSpec":{"incomingExternalClient":{"minWireVersion":0,"maxWireVersion":13},"incomingInternalClient":{"minWireVersion":13,"maxWireVersion":13},"outgoing":{"minWireVersion":13,"maxWireVersion":13},"isInternalClient":true}}} {"t":{"$date":"2024-11-06T14:27:48.597+00:00"},"s":"I", "c":"STORAGE", "id":5071100, "ctx":"initandlisten","msg":"Clearing temp directory"} {"t":{"$date":"2024-11-06T14:27:48.616+00:00"},"s":"I", "c":"CONTROL", "id":20536, "ctx":"initandlisten","msg":"Flow Control is enabled on this deployment"} {"t":{"$date":"2024-11-06T14:27:48.617+00:00"},"s":"W", "c":"SHARDING", "id":22075, "ctx":"initandlisten","msg":"Not started with --shardsvr, but a shardIdentity document was found on disk","attr":{"namespace":"admin.system.version","shardIdentityDocument":{"_id":"shardIdentity","shardName":"rs1","clusterId":{"$oid":"672b7b8417d80ffb4a486a60"},"configsvrConnectionString":"rscfg/rscfg01:27017"}}} {"t":{"$date":"2024-11-06T14:27:48.617+00:00"},"s":"I", "c":"FTDC", "id":20625, "ctx":"initandlisten","msg":"Initializing full-time diagnostic data capture","attr":{"dataDirectory":"/var/lib/mongo/diagnostic.data"}} {"t":{"$date":"2024-11-06T14:27:48.619+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":"2024-11-06T14:27:48.619+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"initandlisten","msg":"Setting new configuration state","attr":{"newState":"ConfigStartingUp","oldState":"ConfigPreStart"}} {"t":{"$date":"2024-11-06T14:27:48.620+00:00"},"s":"I", "c":"REPL", "id":4280500, "ctx":"initandlisten","msg":"Attempting to create internal replication collections"} {"t":{"$date":"2024-11-06T14:27:48.622+00:00"},"s":"I", "c":"REPL", "id":4280501, "ctx":"initandlisten","msg":"Attempting to load local voted for document"} {"t":{"$date":"2024-11-06T14:27:48.622+00:00"},"s":"I", "c":"REPL", "id":21311, "ctx":"initandlisten","msg":"Did not find local initialized voted for document at startup"} {"t":{"$date":"2024-11-06T14:27:48.622+00:00"},"s":"I", "c":"REPL", "id":4280502, "ctx":"initandlisten","msg":"Searching for local Rollback ID document"} {"t":{"$date":"2024-11-06T14:27:48.623+00:00"},"s":"I", "c":"REPL", "id":21529, "ctx":"initandlisten","msg":"Initializing rollback ID","attr":{"rbid":1}} {"t":{"$date":"2024-11-06T14:27:48.623+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":"2024-11-06T14:27:48.624+00:00"},"s":"I", "c":"REPL", "id":21546, "ctx":"initandlisten","msg":"Recovering from an unstable checkpoint","attr":{"topOfOplog":{"ts":{"$timestamp":{"t":1730902934,"i":44}},"t":1},"appliedThrough":{"ts":{"$timestamp":{"t":1730902934,"i":44}},"t":1}}} {"t":{"$date":"2024-11-06T14:27:48.624+00:00"},"s":"I", "c":"REPL", "id":21548, "ctx":"initandlisten","msg":"Starting recovery oplog application at the appliedThrough through the top of the oplog","attr":{"appliedThrough":{"ts":{"$timestamp":{"t":1730902934,"i":44}},"t":1},"topOfOplog":{"ts":{"$timestamp":{"t":1730902934,"i":44}},"t":1}}} {"t":{"$date":"2024-11-06T14:27:48.624+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":"2024-11-06T14:27:48.626+00:00"},"s":"I", "c":"STORAGE", "id":22430, "ctx":"initandlisten","msg":"WiredTiger message","attr":{"message":"[1730903268:626506][513:0x7f9636fa3c00], WT_SESSION.checkpoint: [WT_VERB_CHECKPOINT_PROGRESS] saving checkpoint snapshot min: 37, snapshot max: 37 snapshot count: 0, oldest timestamp: (1730902934, 44) , meta checkpoint timestamp: (0, 0) base write gen: 42"}} {"t":{"$date":"2024-11-06T14:27:48.631+00:00"},"s":"I", "c":"REPL", "id":4280505, "ctx":"initandlisten","msg":"Creating any necessary TenantMigrationAccessBlockers for unfinished migrations"} {"t":{"$date":"2024-11-06T14:27:48.631+00:00"},"s":"I", "c":"REPL", "id":4280506, "ctx":"initandlisten","msg":"Reconstructing prepared transactions"} {"t":{"$date":"2024-11-06T14:27:48.632+00:00"},"s":"I", "c":"REPL", "id":4280507, "ctx":"initandlisten","msg":"Loaded replica set config, scheduled callback to set local config"} {"t":{"$date":"2024-11-06T14:27:48.632+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":"2024-11-06T14:27:48.632+00:00"},"s":"I", "c":"REPL", "id":40440, "ctx":"initandlisten","msg":"Starting the TopologyVersionObserver"} {"t":{"$date":"2024-11-06T14:27:48.633+00:00"},"s":"I", "c":"REPL", "id":40445, "ctx":"TopologyVersionObserver","msg":"Started TopologyVersionObserver"} {"t":{"$date":"2024-11-06T14:27:48.633+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"/tmp/mongodb-27955.sock"}} {"t":{"$date":"2024-11-06T14:27:48.633+00:00"},"s":"I", "c":"NETWORK", "id":23015, "ctx":"listener","msg":"Listening on","attr":{"address":"127.0.0.1"}} {"t":{"$date":"2024-11-06T14:27:48.633+00:00"},"s":"I", "c":"NETWORK", "id":23016, "ctx":"listener","msg":"Waiting for connections","attr":{"port":27955,"ssl":"off"}} {"t":{"$date":"2024-11-06T14:27:48.664+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46008","uuid":"d761eda2-8335-4cdf-80bd-1ddd5c166b06","connectionId":1,"connectionCount":1}} {"t":{"$date":"2024-11-06T14:27:48.664+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46018","uuid":"5dd622ef-8db4-4419-ba2c-a09dce8dfb31","connectionId":3,"connectionCount":2}} {"t":{"$date":"2024-11-06T14:27:48.664+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn1","msg":"client metadata","attr":{"remote":"127.0.0.1:46008","client":"conn1","negotiatedCompressors":[],"doc":{"application":{"name":"pbm-physical-restore"},"driver":{"name":"mongo-go-driver","version":"1.16.0"},"os":{"type":"linux","architecture":"amd64"},"platform":"go1.22.8","env":{"container":{"runtime":"docker"}}}}} {"t":{"$date":"2024-11-06T14:27:48.665+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46024","uuid":"4f0dd7a9-c9fc-4352-ae7a-d3f09325e895","connectionId":4,"connectionCount":3}} {"t":{"$date":"2024-11-06T14:27:48.665+00:00"},"s":"I", "c":"NETWORK", "id":22943, "ctx":"listener","msg":"Connection accepted","attr":{"remote":"127.0.0.1:46040","uuid":"86ca7dfc-6cdd-4a0c-8c91-1236ad103090","connectionId":5,"connectionCount":4}} {"t":{"$date":"2024-11-06T14:27:48.665+00:00"},"s":"W", "c":"COMMAND", "id":5578800, "ctx":"conn3","msg":"Deprecated operation requested. The client driver may require an upgrade in order to ensure compatibility with future server versions. For more details see https://dochub.mongodb.org/core/legacy-opcode-compatibility","attr":{"op":"query","clientInfo":{"address":"127.0.0.1:46018"}}} {"t":{"$date":"2024-11-06T14:27:48.665+00:00"},"s":"I", "c":"REPL", "id":4280509, "ctx":"ReplCoord-0","msg":"Local configuration validated for startup"} {"t":{"$date":"2024-11-06T14:27:48.665+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn5","msg":"client metadata","attr":{"remote":"127.0.0.1:46040","client":"conn5","negotiatedCompressors":[],"doc":{"application":{"name":"pbm-physical-restore"},"driver":{"name":"mongo-go-driver","version":"1.16.0"},"os":{"type":"linux","architecture":"amd64"},"platform":"go1.22.8","env":{"container":{"runtime":"docker"}}}}} {"t":{"$date":"2024-11-06T14:27:48.665+00:00"},"s":"I", "c":"REPL", "id":6015317, "ctx":"ReplCoord-0","msg":"Setting new configuration state","attr":{"newState":"ConfigSteady","oldState":"ConfigStartingUp"}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":21392, "ctx":"ReplCoord-0","msg":"New replica set config in use","attr":{"config":{"_id":"rs1","version":1,"members":[{"_id":0,"host":"localhost:27955","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}}}}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":21393, "ctx":"ReplCoord-0","msg":"Found self in config","attr":{"hostAndPort":"localhost:27955"}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"ReplCoord-0","msg":"Replica set state transition","attr":{"newState":"STARTUP2","oldState":"STARTUP"}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":21320, "ctx":"ReplCoord-0","msg":"Updated term","attr":{"term":1}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":21306, "ctx":"ReplCoord-0","msg":"Starting replication storage threads"} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn3","msg":"Connection ended","attr":{"remote":"127.0.0.1:46018","uuid":"5dd622ef-8db4-4419-ba2c-a09dce8dfb31","connectionId":3,"connectionCount":3}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":4280512, "ctx":"ReplCoord-0","msg":"No initial sync required. Attempting to begin steady replication"} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"ReplCoord-0","msg":"Replica set state transition","attr":{"newState":"RECOVERING","oldState":"STARTUP2"}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"ReplCoord-0","msg":"createCollection","attr":{"namespace":"local.replset.initialSyncId","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"5effadc9-aa2e-4193-aa4b-3eaa1e0388f5"}},"options":{}}} {"t":{"$date":"2024-11-06T14:27:48.666+00:00"},"s":"I", "c":"NETWORK", "id":51800, "ctx":"conn4","msg":"client metadata","attr":{"remote":"127.0.0.1:46024","client":"conn4","negotiatedCompressors":[],"doc":{"application":{"name":"pbm-physical-restore"},"driver":{"name":"mongo-go-driver","version":"1.16.0"},"os":{"type":"linux","architecture":"amd64"},"platform":"go1.22.8","env":{"container":{"runtime":"docker"}}}}} {"t":{"$date":"2024-11-06T14:27:48.676+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"ReplCoord-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"local.replset.initialSyncId","index":"_id_","commitTimestamp":null}} {"t":{"$date":"2024-11-06T14:27:48.677+00:00"},"s":"I", "c":"REPL", "id":21299, "ctx":"ReplCoord-0","msg":"Starting replication fetcher thread"} {"t":{"$date":"2024-11-06T14:27:48.677+00:00"},"s":"I", "c":"REPL", "id":21300, "ctx":"ReplCoord-0","msg":"Starting replication applier thread"} {"t":{"$date":"2024-11-06T14:27:48.677+00:00"},"s":"I", "c":"REPL", "id":21224, "ctx":"OplogApplier-0","msg":"Starting oplog application"} {"t":{"$date":"2024-11-06T14:27:48.678+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"OplogApplier-0","msg":"Replica set state transition","attr":{"newState":"SECONDARY","oldState":"RECOVERING"}} {"t":{"$date":"2024-11-06T14:27:48.678+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":"2024-11-06T14:27:48.678+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":1}} {"t":{"$date":"2024-11-06T14:27:48.678+00:00"},"s":"I", "c":"REPL", "id":21301, "ctx":"ReplCoord-0","msg":"Starting replication reporter thread"} {"t":{"$date":"2024-11-06T14:27:48.679+00:00"},"s":"I", "c":"REPL", "id":4280511, "ctx":"ReplCoord-0","msg":"Set local replica set config"} {"t":{"$date":"2024-11-06T14:27:48.679+00:00"},"s":"I", "c":"ELECTION", "id":21444, "ctx":"ReplCoord-0","msg":"Dry election run succeeded, running for election","attr":{"newTerm":2}} {"t":{"$date":"2024-11-06T14:27:48.679+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":2,"candidateIndex":0}}} {"t":{"$date":"2024-11-06T14:27:48.679+00:00"},"s":"I", "c":"ELECTION", "id":21450, "ctx":"ReplCoord-0","msg":"Election succeeded, assuming primary role","attr":{"term":2}} {"t":{"$date":"2024-11-06T14:27:48.679+00:00"},"s":"I", "c":"REPL", "id":21358, "ctx":"ReplCoord-0","msg":"Replica set state transition","attr":{"newState":"PRIMARY","oldState":"SECONDARY"}} {"t":{"$date":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21106, "ctx":"ReplCoord-0","msg":"Resetting sync source to empty","attr":{"previousSyncSource":":27017"}} {"t":{"$date":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21359, "ctx":"ReplCoord-0","msg":"Entering primary catch-up mode"} {"t":{"$date":"2024-11-06T14:27:48.680+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":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21363, "ctx":"ReplCoord-0","msg":"Exited primary catch-up mode"} {"t":{"$date":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21107, "ctx":"ReplCoord-0","msg":"Stopping replication producer"} {"t":{"$date":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21239, "ctx":"ReplBatcher","msg":"Oplog buffer has been drained","attr":{"term":2}} {"t":{"$date":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21343, "ctx":"RstlKillOpThread","msg":"Starting to kill user operations"} {"t":{"$date":"2024-11-06T14:27:48.680+00:00"},"s":"I", "c":"REPL", "id":21344, "ctx":"RstlKillOpThread","msg":"Stopped killing user operations"} {"t":{"$date":"2024-11-06T14:27:48.680+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":"2024-11-06T14:27:48.681+00:00"},"s":"I", "c":"REPL", "id":6015310, "ctx":"OplogApplier-0","msg":"Starting to transition to primary."} {"t":{"$date":"2024-11-06T14:27:48.681+00:00"},"s":"I", "c":"REPL", "id":6015309, "ctx":"OplogApplier-0","msg":"Logging transition to primary to oplog on stepup"} {"t":{"$date":"2024-11-06T14:27:48.681+00:00"},"s":"I", "c":"STORAGE", "id":20657, "ctx":"OplogApplier-0","msg":"IndexBuildsCoordinator::onStepUp - this node is stepping up to primary"} {"t":{"$date":"2024-11-06T14:27:48.681+00:00"},"s":"I", "c":"REPL", "id":21331, "ctx":"OplogApplier-0","msg":"Transition to primary complete; database writes are now permitted"} {"t":{"$date":"2024-11-06T14:27:48.682+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"monitoring-keys-for-HMAC","msg":"createCollection","attr":{"namespace":"admin.system.keys","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"82549385-13ed-47fc-afd8-b0bf94b9c696"}},"options":{}}} {"t":{"$date":"2024-11-06T14:27:48.685+00:00"},"s":"I", "c":"STORAGE", "id":22310, "ctx":"JournalFlusher","msg":"Triggering the first stable checkpoint","attr":{"initialDataTimestamp":{"$timestamp":{"t":1730902934,"i":44}},"prevStableTimestamp":{"$timestamp":{"t":0,"i":0}},"currStableTimestamp":{"$timestamp":{"t":1730903268,"i":2}}}} {"t":{"$date":"2024-11-06T14:27:48.687+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"TenantMigrationRecipientService-0","msg":"createCollection","attr":{"namespace":"config.tenantMigrationRecipients","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"02d8f8df-612e-48e9-922e-a1048751531e"}},"options":{}}} {"t":{"$date":"2024-11-06T14:27:48.686+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"TenantMigrationDonorService-0","msg":"createCollection","attr":{"namespace":"config.tenantMigrationDonors","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"c2513af4-d3b8-40fb-9224-0ef276c297d5"}},"options":{}}} {"t":{"$date":"2024-11-06T14:27:48.695+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"monitoring-keys-for-HMAC","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"admin.system.keys","index":"_id_","commitTimestamp":{"$timestamp":{"t":1730903268,"i":3}}}} {"t":{"$date":"2024-11-06T14:27:48.696+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn4","msg":"Connection ended","attr":{"remote":"127.0.0.1:46024","uuid":"4f0dd7a9-c9fc-4352-ae7a-d3f09325e895","connectionId":4,"connectionCount":2}} {"t":{"$date":"2024-11-06T14:27:48.696+00:00"},"s":"I", "c":"-", "id":20883, "ctx":"conn1","msg":"Interrupted operation as its client disconnected","attr":{"opId":41}} {"t":{"$date":"2024-11-06T14:27:48.697+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn1","msg":"Connection ended","attr":{"remote":"127.0.0.1:46008","uuid":"d761eda2-8335-4cdf-80bd-1ddd5c166b06","connectionId":1,"connectionCount":1}} {"t":{"$date":"2024-11-06T14:27:48.697+00:00"},"s":"I", "c":"NETWORK", "id":22944, "ctx":"conn5","msg":"Connection ended","attr":{"remote":"127.0.0.1:46040","uuid":"86ca7dfc-6cdd-4a0c-8c91-1236ad103090","connectionId":5,"connectionCount":0}} {"t":{"$date":"2024-11-06T14:27:48.703+00:00"},"s":"E", "c":"STORAGE", "id":22435, "ctx":"Checkpointer","msg":"WiredTiger error","attr":{"error":2,"message":"[1730903268:703119][513:0x7f961eeae700], file:collection-2-7430716945204129664.wt, WT_SESSION.checkpoint: __posix_fs_size, 296: /var/lib/mongo/WiredTigerHS.wt: file-size: stat: No such file or directory"}} {"t":{"$date":"2024-11-06T14:27:48.703+00:00"},"s":"E", "c":"STORAGE", "id":22435, "ctx":"Checkpointer","msg":"WiredTiger error","attr":{"error":2,"message":"[1730903268:703171][513:0x7f961eeae700], file:collection-2-7430716945204129664.wt, WT_SESSION.checkpoint: __reconcile, 351: reconciliation failed after building the disk image: No such file or directory"}} {"t":{"$date":"2024-11-06T14:27:48.703+00:00"},"s":"E", "c":"STORAGE", "id":22435, "ctx":"Checkpointer","msg":"WiredTiger error","attr":{"error":-31804,"message":"[1730903268:703187][513:0x7f961eeae700], file:collection-2-7430716945204129664.wt, WT_SESSION.checkpoint: __reconcile, 351: the process must exit and restart: WT_PANIC: WiredTiger library panic"}} {"t":{"$date":"2024-11-06T14:27:48.703+00:00"},"s":"F", "c":"-", "id":23089, "ctx":"Checkpointer","msg":"Fatal assertion","attr":{"msgid":50853,"file":"src/mongo/db/storage/wiredtiger/wiredtiger_util.cpp","line":574}} {"t":{"$date":"2024-11-06T14:27:48.703+00:00"},"s":"F", "c":"-", "id":23090, "ctx":"Checkpointer","msg":"\n\n***aborting after fassert() failure\n\n"} {"t":{"$date":"2024-11-06T14:27:48.703+00:00"},"s":"F", "c":"CONTROL", "id":6384300, "ctx":"Checkpointer","msg":"Writing fatal message","attr":{"message":"Got signal: 6 (Aborted).\n"}} {"t":{"$date":"2024-11-06T14:27:48.709+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"TenantMigrationRecipientService-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.tenantMigrationRecipients","index":"_id_","commitTimestamp":{"$timestamp":{"t":1730903268,"i":7}}}} {"t":{"$date":"2024-11-06T14:27:48.709+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"TenantMigrationRecipientService-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.tenantMigrationRecipients","index":"TenantMigrationRecipientTTLIndex","commitTimestamp":{"$timestamp":{"t":1730903268,"i":7}}}} {"t":{"$date":"2024-11-06T14:27:48.710+00:00"},"s":"I", "c":"REPL", "id":5123005, "ctx":"TenantMigrationRecipientService-0","msg":"Rebuilding PrimaryOnlyService due to stepUp","attr":{"service":"TenantMigrationRecipientService"}} {"t":{"$date":"2024-11-06T14:27:48.715+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"TenantMigrationDonorService-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.tenantMigrationDonors","index":"_id_","commitTimestamp":{"$timestamp":{"t":1730903268,"i":8}}}} {"t":{"$date":"2024-11-06T14:27:48.715+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"TenantMigrationDonorService-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.tenantMigrationDonors","index":"TenantMigrationDonorTTLIndex","commitTimestamp":{"$timestamp":{"t":1730903268,"i":8}}}} {"t":{"$date":"2024-11-06T14:27:48.715+00:00"},"s":"I", "c":"STORAGE", "id":20320, "ctx":"TenantMigrationDonorService-0","msg":"createCollection","attr":{"namespace":"config.external_validation_keys","uuidDisposition":"generated","uuid":{"uuid":{"$uuid":"ada28212-080e-469e-b348-ed657a14c4a3"}},"options":{}}} {"t":{"$date":"2024-11-06T14:27:48.734+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"TenantMigrationDonorService-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.external_validation_keys","index":"_id_","commitTimestamp":{"$timestamp":{"t":1730903268,"i":11}}}} {"t":{"$date":"2024-11-06T14:27:48.734+00:00"},"s":"I", "c":"INDEX", "id":20345, "ctx":"TenantMigrationDonorService-0","msg":"Index build: done building","attr":{"buildUUID":null,"namespace":"config.external_validation_keys","index":"ExternalKeysTTLIndex","commitTimestamp":{"$timestamp":{"t":1730903268,"i":11}}}} {"t":{"$date":"2024-11-06T14:27:48.734+00:00"},"s":"I", "c":"REPL", "id":5123005, "ctx":"TenantMigrationDonorService-1","msg":"Rebuilding PrimaryOnlyService due to stepUp","attr":{"service":"TenantMigrationDonorService"}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31380, "ctx":"Checkpointer","msg":"BACKTRACE","attr":{"bt":{"backtrace":[{"a":"5626F8BD29C3","b":"5626F49B5000","o":"421D9C3","s":"_ZN5mongo34StackTraceAddressMetadataGenerator4loadEPv","s+":"FC3"},{"a":"5626F8BD5417","b":"5626F49B5000","o":"4220417","s":"_ZN5mongo15printStackTraceEv","s+":"37"},{"a":"5626F8BCD5B6","b":"5626F49B5000","o":"42185B6","s":"abruptQuit","s+":"66"},{"a":"7F9634721D70","b":"7F963470F000","o":"12D70","s":"funlockfile","s+":"50"},{"a":"7F96343875EF","b":"7F9634339000","o":"4E5EF","s":"gsignal","s+":"10F"},{"a":"7F963435AE65","b":"7F9634339000","o":"21E65","s":"abort","s+":"127"},{"a":"5626F5F33D6D","b":"5626F49B5000","o":"157ED6D","s":"_ZN5mongo25fassertFailedWithLocationEiPKcj","s+":"F6"},{"a":"5626F59EFCF4","b":"5626F49B5000","o":"103ACF4","s":"_ZZN5mongo8RecordIdC4EPKciENKUlvE0_clEv","s+":"30B8"},{"a":"5626F62989F3","b":"5626F49B5000","o":"18E39F3","s":"__wt_event_handler_set","s+":"4A3"},{"a":"5626F5A023E3","b":"5626F49B5000","o":"104D3E3","s":"__wt_panic_func","s+":"114"},{"a":"5626F59FEA8D","b":"5626F49B5000","o":"1049A8D","s":"__wt_ext_map_windows_error","s+":"25CA"},{"a":"5626F632DDFA","b":"5626F49B5000","o":"1978DFA","s":"__wt_sync_file","s+":"6AA"},{"a":"5626F62B713B","b":"5626F49B5000","o":"190213B","s":"__wt_checkpoint_tree_reconcile_update","s+":"1CB"},{"a":"5626F62B823F","b":"5626F49B5000","o":"190323F","s":"__wt_checkpoint_sync","s+":"99F"},{"a":"5626F62B9B76","b":"5626F49B5000","o":"1904B76","s":"__wt_txn_checkpoint","s+":"246"},{"a":"5626F5A016EF","b":"5626F49B5000","o":"104C6EF","s":"__wt_session_notsup","s+":"FCC"},{"a":"5626F619E586","b":"5626F49B5000","o":"17E9586","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_sessionb","s+":"46"},{"a":"5626F61AB988","b":"5626F49B5000","o":"17F6988","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_session","s+":"2B8"},{"a":"5626F61AC050","b":"5626F49B5000","o":"17F7050","s":"_ZN5mongo18WiredTigerKVEngine10checkpointEv","s+":"40"},{"a":"5626F7151459","b":"5626F49B5000","o":"279C459","s":"_ZN5mongo12Checkpointer3runEv","s+":"E59"},{"a":"5626F898DE32","b":"5626F49B5000","o":"3FD8E32","s":"_ZN5mongo13BackgroundJob7jobBodyEv","s+":"E2"},{"a":"5626F898E54C","b":"5626F49B5000","o":"3FD954C","s":"_ZN5mongo13BackgroundJob7jobBodyEv","s+":"7FC"},{"a":"7F9634F8CB23","b":"7F9634ECA000","o":"C2B23","s":"_ZNKSt10error_code23default_error_conditionEv","s+":"33"},{"a":"7F96347171DA","b":"7F963470F000","o":"81DA","s":"start_thread","s+":"EA"},{"a":"7F96343728D3","b":"7F9634339000","o":"398D3","s":"clone","s+":"43"}],"processInfo":{"mongodbVersion":"5.0.29-25","gitVersion":"6ed5ea3065f1daafa7661d1c05d20c50b614a96a","compiledModules":[],"uname":{"sysname":"Linux","release":"6.5.0-1025-azure","version":"#26~22.04.1-Ubuntu SMP Thu Jul 11 22:33:04 UTC 2024","machine":"x86_64"},"somap":[{"b":"5626F49B5000","elfType":3,"buildId":"539E2C825673723B04D19874D3C9DF6EB9A69E4F"},{"b":"7F9634ECA000","path":"/lib64/libstdc++.so.6","elfType":3,"buildId":"41809D0B18272BC525772714DA54108BF1C59253"},{"b":"7F963470F000","path":"/lib64/libpthread.so.0","elfType":3,"buildId":"E144DC0B1B2E7DA6C5B2191AC90367D60923235E"},{"b":"7F9634339000","path":"/lib64/libc.so.6","elfType":3,"buildId":"A3904D14B666122259AB466667C8100B8295FE16"}]}}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F8BD29C3","b":"5626F49B5000","o":"421D9C3","s":"_ZN5mongo34StackTraceAddressMetadataGenerator4loadEPv","s+":"FC3"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F8BD5417","b":"5626F49B5000","o":"4220417","s":"_ZN5mongo15printStackTraceEv","s+":"37"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F8BCD5B6","b":"5626F49B5000","o":"42185B6","s":"abruptQuit","s+":"66"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F9634721D70","b":"7F963470F000","o":"12D70","s":"funlockfile","s+":"50"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F96343875EF","b":"7F9634339000","o":"4E5EF","s":"gsignal","s+":"10F"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F963435AE65","b":"7F9634339000","o":"21E65","s":"abort","s+":"127"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F5F33D6D","b":"5626F49B5000","o":"157ED6D","s":"_ZN5mongo25fassertFailedWithLocationEiPKcj","s+":"F6"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F59EFCF4","b":"5626F49B5000","o":"103ACF4","s":"_ZZN5mongo8RecordIdC4EPKciENKUlvE0_clEv","s+":"30B8"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F62989F3","b":"5626F49B5000","o":"18E39F3","s":"__wt_event_handler_set","s+":"4A3"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F5A023E3","b":"5626F49B5000","o":"104D3E3","s":"__wt_panic_func","s+":"114"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F59FEA8D","b":"5626F49B5000","o":"1049A8D","s":"__wt_ext_map_windows_error","s+":"25CA"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F632DDFA","b":"5626F49B5000","o":"1978DFA","s":"__wt_sync_file","s+":"6AA"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F62B713B","b":"5626F49B5000","o":"190213B","s":"__wt_checkpoint_tree_reconcile_update","s+":"1CB"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F62B823F","b":"5626F49B5000","o":"190323F","s":"__wt_checkpoint_sync","s+":"99F"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F62B9B76","b":"5626F49B5000","o":"1904B76","s":"__wt_txn_checkpoint","s+":"246"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F5A016EF","b":"5626F49B5000","o":"104C6EF","s":"__wt_session_notsup","s+":"FCC"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F619E586","b":"5626F49B5000","o":"17E9586","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_sessionb","s+":"46"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F61AB988","b":"5626F49B5000","o":"17F6988","s":"_ZN5mongo18WiredTigerKVEngine11_checkpointEP12__wt_session","s+":"2B8"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F61AC050","b":"5626F49B5000","o":"17F7050","s":"_ZN5mongo18WiredTigerKVEngine10checkpointEv","s+":"40"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F7151459","b":"5626F49B5000","o":"279C459","s":"_ZN5mongo12Checkpointer3runEv","s+":"E59"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F898DE32","b":"5626F49B5000","o":"3FD8E32","s":"_ZN5mongo13BackgroundJob7jobBodyEv","s+":"E2"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"5626F898E54C","b":"5626F49B5000","o":"3FD954C","s":"_ZN5mongo13BackgroundJob7jobBodyEv","s+":"7FC"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F9634F8CB23","b":"7F9634ECA000","o":"C2B23","s":"_ZNKSt10error_code23default_error_conditionEv","s+":"33"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F96347171DA","b":"7F963470F000","o":"81DA","s":"start_thread","s+":"EA"}}} {"t":{"$date":"2024-11-06T14:27:48.783+00:00"},"s":"I", "c":"CONTROL", "id":31445, "ctx":"Checkpointer","msg":"Frame","attr":{"frame":{"a":"7F96343728D3","b":"7F9634339000","o":"398D3","s":"clone","s+":"43"}}}
Trivy
The following actions use a deprecated Node.js version and will be forced to run on node20: github/codeql-action/upload-sarif@v2. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/
Trivy
CodeQL Action v2 will be deprecated on December 5th, 2024. Please update all occurrences of the CodeQL Action in your workflow files to v3. For more information, see https://github.blog/changelog/2024-01-12-code-scanning-deprecation-of-codeql-action-v2/