{"level":"warn","ts":"2026-04-12T20:40:19.706794Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:40:19.706887Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-12T20:40:19.706951Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:40:19.706964Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-12T20:40:19.70698Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-12T20:40:19.707608Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-12T20:40:19.707687Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-12T20:40:19.713647Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.163925ms"} {"level":"info","ts":"2026-04-12T20:40:19.720849Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-12T20:40:19.720973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:40:19.721015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-12T20:40:19.721066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:40:19.721094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-12T20:40:19.721172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-12T20:40:19.727052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:40:19.728088Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:40:19.728941Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-12T20:40:19.732639Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:40:19.735351Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-12T20:40:19.73543Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-12T20:40:19.735444Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-12T20:40:19.735818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-12T20:40:19.733499Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:40:19.74146Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-12T20:40:19.743799Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-12T20:40:19.743914Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-12T20:40:19.743979Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-12T20:40:19.74413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:40:19.744208Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:40:20.421786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:40:20.421851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:40:20.421879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-12T20:40:20.421892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:40:20.421897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-12T20:40:20.421905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-12T20:40:20.421913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-12T20:40:20.423969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:20.426132Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:20.426279Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:20.426324Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:20.426415Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:40:20.429101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:40:20.429485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:40:20.430928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:40:20.432053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-12T20:40:20.435451Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:40:20.435468Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-12T20:40:26.5138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.920154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-12T20:40:26.513942Z","caller":"traceutil/trace.go:171","msg":"trace[840396161] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:313; }","duration":"357.167836ms","start":"2026-04-12T20:40:26.156744Z","end":"2026-04-12T20:40:26.513912Z","steps":["trace[840396161] 'range keys from in-memory index tree' (duration: 356.716802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:26.513998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:26.156715Z","time spent":"357.271656ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-12T20:40:26.645064Z","caller":"traceutil/trace.go:171","msg":"trace[1558156948] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"437.410608ms","start":"2026-04-12T20:40:26.20763Z","end":"2026-04-12T20:40:26.645041Z","steps":["trace[1558156948] 'process raft request' (duration: 437.316467ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:26.645154Z","caller":"traceutil/trace.go:171","msg":"trace[1397337591] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:323; }","duration":"437.349577ms","start":"2026-04-12T20:40:26.207719Z","end":"2026-04-12T20:40:26.645068Z","steps":["trace[1397337591] 'read index received' (duration: 437.322157ms)","trace[1397337591] 'applied index is now lower than readState.Index' (duration: 20.92µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:26.645308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.556988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-12T20:40:26.645414Z","caller":"traceutil/trace.go:171","msg":"trace[1141635763] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:313; }","duration":"437.728019ms","start":"2026-04-12T20:40:26.207666Z","end":"2026-04-12T20:40:26.645394Z","steps":["trace[1141635763] 'agreement among raft nodes before linearized reading' (duration: 437.513697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:26.645468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:26.207642Z","time spent":"437.81727ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-12T20:40:26.645505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:26.207612Z","time spent":"437.539248ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:40:26.871766Z","caller":"traceutil/trace.go:171","msg":"trace[2094799116] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"663.890024ms","start":"2026-04-12T20:40:26.207836Z","end":"2026-04-12T20:40:26.871726Z","steps":["trace[2094799116] 'process raft request' (duration: 663.635373ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:26.871825Z","caller":"traceutil/trace.go:171","msg":"trace[1383951503] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"353.496702ms","start":"2026-04-12T20:40:26.518291Z","end":"2026-04-12T20:40:26.871787Z","steps":["trace[1383951503] 'process raft request' (duration: 353.334581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:26.871948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:26.207806Z","time spent":"664.063086ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:40:26.871974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:26.518272Z","time spent":"353.640504ms","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:40:26.872029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.094704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-04-12T20:40:26.872132Z","caller":"traceutil/trace.go:171","msg":"trace[1571279589] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:314; }","duration":"221.226845ms","start":"2026-04-12T20:40:26.650882Z","end":"2026-04-12T20:40:26.872109Z","steps":["trace[1571279589] 'agreement among raft nodes before linearized reading' (duration: 221.001043ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:26.87184Z","caller":"traceutil/trace.go:171","msg":"trace[840178515] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:323; }","duration":"220.856503ms","start":"2026-04-12T20:40:26.650911Z","end":"2026-04-12T20:40:26.871768Z","steps":["trace[840178515] 'read index received' (duration: 220.611081ms)","trace[840178515] 'applied index is now lower than readState.Index' (duration: 243.942µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:27.058194Z","caller":"traceutil/trace.go:171","msg":"trace[1274832004] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"185.718799ms","start":"2026-04-12T20:40:26.872456Z","end":"2026-04-12T20:40:27.058175Z","steps":["trace[1274832004] 'read index received' (duration: 185.489337ms)","trace[1274832004] 'applied index is now lower than readState.Index' (duration: 228.822µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:27.058336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.625187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-12T20:40:27.058361Z","caller":"traceutil/trace.go:171","msg":"trace[563668134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:315; }","duration":"196.723138ms","start":"2026-04-12T20:40:26.861628Z","end":"2026-04-12T20:40:27.058351Z","steps":["trace[563668134] 'agreement among raft nodes before linearized reading' (duration: 196.648627ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:27.058339Z","caller":"traceutil/trace.go:171","msg":"trace[5088624] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"386.084963ms","start":"2026-04-12T20:40:26.672198Z","end":"2026-04-12T20:40:27.058283Z","steps":["trace[5088624] 'process raft request' (duration: 385.832082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:27.058477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.220406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"warn","ts":"2026-04-12T20:40:27.058517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.753748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:40:27.058575Z","caller":"traceutil/trace.go:171","msg":"trace[1248250388] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:315; }","duration":"185.359627ms","start":"2026-04-12T20:40:26.873193Z","end":"2026-04-12T20:40:27.058552Z","steps":["trace[1248250388] 'agreement among raft nodes before linearized reading' (duration: 185.163746ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:27.058577Z","caller":"traceutil/trace.go:171","msg":"trace[1816261965] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:315; }","duration":"183.856378ms","start":"2026-04-12T20:40:26.874703Z","end":"2026-04-12T20:40:27.058559Z","steps":["trace[1816261965] 'agreement among raft nodes before linearized reading' (duration: 183.757607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:27.0589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:26.672173Z","time spent":"386.316655ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:40:30.275151Z","caller":"traceutil/trace.go:171","msg":"trace[951285323] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"110.433546ms","start":"2026-04-12T20:40:30.16469Z","end":"2026-04-12T20:40:30.275124Z","steps":["trace[951285323] 'process raft request' (duration: 101.814374ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:30.462531Z","caller":"traceutil/trace.go:171","msg":"trace[1094500746] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"113.320533ms","start":"2026-04-12T20:40:30.349177Z","end":"2026-04-12T20:40:30.462498Z","steps":["trace[1094500746] 'read index received' (duration: 54.368868ms)","trace[1094500746] 'applied index is now lower than readState.Index' (duration: 58.950455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:30.462725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.524025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-12T20:40:30.462775Z","caller":"traceutil/trace.go:171","msg":"trace[415434423] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:379; }","duration":"113.619855ms","start":"2026-04-12T20:40:30.349139Z","end":"2026-04-12T20:40:30.462758Z","steps":["trace[415434423] 'agreement among raft nodes before linearized reading' (duration: 113.472044ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:30.462792Z","caller":"traceutil/trace.go:171","msg":"trace[1649958577] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"115.622208ms","start":"2026-04-12T20:40:30.347091Z","end":"2026-04-12T20:40:30.462713Z","steps":["trace[1649958577] 'process raft request' (duration: 56.496011ms)","trace[1649958577] 'compare' (duration: 58.704694ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:30.46379Z","caller":"traceutil/trace.go:171","msg":"trace[163444343] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"113.664967ms","start":"2026-04-12T20:40:30.350097Z","end":"2026-04-12T20:40:30.463762Z","steps":["trace[163444343] 'process raft request' (duration: 113.517195ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:30.463886Z","caller":"traceutil/trace.go:171","msg":"trace[73950255] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"105.407017ms","start":"2026-04-12T20:40:30.358449Z","end":"2026-04-12T20:40:30.463856Z","steps":["trace[73950255] 'process raft request' (duration: 105.363377ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:30.464159Z","caller":"traceutil/trace.go:171","msg":"trace[1125606664] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"113.935858ms","start":"2026-04-12T20:40:30.350213Z","end":"2026-04-12T20:40:30.464149Z","steps":["trace[1125606664] 'process raft request' (duration: 113.507104ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:30.464418Z","caller":"traceutil/trace.go:171","msg":"trace[66693156] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"107.468069ms","start":"2026-04-12T20:40:30.356941Z","end":"2026-04-12T20:40:30.464409Z","steps":["trace[66693156] 'process raft request' (duration: 106.813786ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:56.039233Z","caller":"traceutil/trace.go:171","msg":"trace[1680053651] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"193.931915ms","start":"2026-04-12T20:40:55.845229Z","end":"2026-04-12T20:40:56.039161Z","steps":["trace[1680053651] 'process raft request' (duration: 120.886531ms)","trace[1680053651] 'compare' (duration: 72.543762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:56.530277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.37613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-12T20:40:56.530482Z","caller":"traceutil/trace.go:171","msg":"trace[1088760319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"365.633771ms","start":"2026-04-12T20:40:56.164825Z","end":"2026-04-12T20:40:56.530459Z","steps":["trace[1088760319] 'range keys from in-memory index tree' (duration: 365.13907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:56.530547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:56.164808Z","time spent":"365.716602ms","remote":"127.0.0.1:43578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:40:56.530743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.471265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p68zh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-12T20:40:56.530843Z","caller":"traceutil/trace.go:171","msg":"trace[2146190357] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p68zh; range_end:; response_count:1; response_revision:609; }","duration":"233.619316ms","start":"2026-04-12T20:40:56.297199Z","end":"2026-04-12T20:40:56.530818Z","steps":["trace[2146190357] 'range keys from in-memory index tree' (duration: 233.258345ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:56.5733Z","caller":"traceutil/trace.go:171","msg":"trace[1517884516] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"102.703416ms","start":"2026-04-12T20:40:56.470548Z","end":"2026-04-12T20:40:56.573251Z","steps":["trace[1517884516] 'process raft request' (duration: 101.906283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:12.639715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.671184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pgsd6\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-12T20:41:12.639829Z","caller":"traceutil/trace.go:171","msg":"trace[738380259] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pgsd6; range_end:; response_count:1; response_revision:782; }","duration":"239.81299ms","start":"2026-04-12T20:41:12.399993Z","end":"2026-04-12T20:41:12.639806Z","steps":["trace[738380259] 'range keys from in-memory index tree' (duration: 239.395752ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:33.000653Z","caller":"traceutil/trace.go:171","msg":"trace[1555575287] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"272.856338ms","start":"2026-04-12T20:42:32.727767Z","end":"2026-04-12T20:42:33.000623Z","steps":["trace[1555575287] 'process raft request' (duration: 272.358729ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:33.023423Z","caller":"traceutil/trace.go:171","msg":"trace[273179735] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"219.743416ms","start":"2026-04-12T20:42:32.803661Z","end":"2026-04-12T20:42:33.023405Z","steps":["trace[273179735] 'read index received' (duration: 196.31208ms)","trace[273179735] 'applied index is now lower than readState.Index' (duration: 23.430356ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:42:33.023565Z","caller":"traceutil/trace.go:171","msg":"trace[454705816] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"295.224985ms","start":"2026-04-12T20:42:32.728325Z","end":"2026-04-12T20:42:33.02355Z","steps":["trace[454705816] 'process raft request' (duration: 294.97401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:42:33.023834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.980825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:42:33.024072Z","caller":"traceutil/trace.go:171","msg":"trace[1390464664] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1854; }","duration":"214.280371ms","start":"2026-04-12T20:42:32.80977Z","end":"2026-04-12T20:42:33.02405Z","steps":["trace[1390464664] 'agreement among raft nodes before linearized reading' (duration: 213.970394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:42:33.024468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.750406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a395e381-517a-4c0c-b566-764ddc9f67b4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-12T20:42:33.024708Z","caller":"traceutil/trace.go:171","msg":"trace[340634202] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a395e381-517a-4c0c-b566-764ddc9f67b4; range_end:; response_count:1; response_revision:1854; }","duration":"221.051821ms","start":"2026-04-12T20:42:32.803617Z","end":"2026-04-12T20:42:33.024668Z","steps":["trace[340634202] 'agreement among raft nodes before linearized reading' (duration: 220.172463ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:33.181713Z","caller":"traceutil/trace.go:171","msg":"trace[1485372283] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"129.551647ms","start":"2026-04-12T20:42:33.052116Z","end":"2026-04-12T20:42:33.181667Z","steps":["trace[1485372283] 'process raft request' (duration: 129.18671ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:42:33.506252Z","caller":"traceutil/trace.go:171","msg":"trace[1779708252] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"114.657484ms","start":"2026-04-12T20:42:33.391548Z","end":"2026-04-12T20:42:33.506205Z","steps":["trace[1779708252] 'process raft request' (duration: 113.619293ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:43:06.849747Z","caller":"traceutil/trace.go:171","msg":"trace[2146322063] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2177; }","duration":"112.622672ms","start":"2026-04-12T20:43:06.737092Z","end":"2026-04-12T20:43:06.849715Z","steps":["trace[2146322063] 'read index received' (duration: 51.450967ms)","trace[2146322063] 'applied index is now lower than readState.Index' (duration: 61.167265ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:43:06.849981Z","caller":"traceutil/trace.go:171","msg":"trace[1393241839] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"127.342344ms","start":"2026-04-12T20:43:06.722554Z","end":"2026-04-12T20:43:06.849896Z","steps":["trace[1393241839] 'process raft request' (duration: 126.969389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:43:06.850082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.964757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:43:06.850183Z","caller":"traceutil/trace.go:171","msg":"trace[1651888428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2099; }","duration":"113.092369ms","start":"2026-04-12T20:43:06.737054Z","end":"2026-04-12T20:43:06.850146Z","steps":["trace[1651888428] 'agreement among raft nodes before linearized reading' (duration: 112.954347ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:43:07.306901Z","caller":"traceutil/trace.go:171","msg":"trace[1901831195] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"117.992402ms","start":"2026-04-12T20:43:07.188879Z","end":"2026-04-12T20:43:07.306871Z","steps":["trace[1901831195] 'process raft request' (duration: 117.770718ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:43:07.35435Z","caller":"traceutil/trace.go:171","msg":"trace[790595074] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"164.439719ms","start":"2026-04-12T20:43:07.189873Z","end":"2026-04-12T20:43:07.354312Z","steps":["trace[790595074] 'process raft request' (duration: 164.125525ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:10.025999Z","caller":"traceutil/trace.go:171","msg":"trace[1046823461] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"282.331998ms","start":"2026-04-12T20:44:09.743643Z","end":"2026-04-12T20:44:10.025975Z","steps":["trace[1046823461] 'process raft request' (duration: 282.206837ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:10.026148Z","caller":"traceutil/trace.go:171","msg":"trace[1013807813] linearizableReadLoop","detail":"{readStateIndex:2634; appliedIndex:2634; }","duration":"242.876795ms","start":"2026-04-12T20:44:09.783126Z","end":"2026-04-12T20:44:10.026003Z","steps":["trace[1013807813] 'read index received' (duration: 242.862925ms)","trace[1013807813] 'applied index is now lower than readState.Index' (duration: 11.51µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:10.026565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.420982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:44:10.027292Z","caller":"traceutil/trace.go:171","msg":"trace[75142172] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"100.908176ms","start":"2026-04-12T20:44:09.926358Z","end":"2026-04-12T20:44:10.027266Z","steps":["trace[75142172] 'process raft request' (duration: 100.865056ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:10.026718Z","caller":"traceutil/trace.go:171","msg":"trace[2024643344] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2539; }","duration":"243.592045ms","start":"2026-04-12T20:44:09.783066Z","end":"2026-04-12T20:44:10.026658Z","steps":["trace[2024643344] 'agreement among raft nodes before linearized reading' (duration: 242.973767ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:10.028058Z","caller":"traceutil/trace.go:171","msg":"trace[1425847287] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"153.025104ms","start":"2026-04-12T20:44:09.875016Z","end":"2026-04-12T20:44:10.028041Z","steps":["trace[1425847287] 'process raft request' (duration: 152.091444ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:43.410613Z","caller":"traceutil/trace.go:171","msg":"trace[1196941928] linearizableReadLoop","detail":"{readStateIndex:2825; appliedIndex:2824; }","duration":"159.831541ms","start":"2026-04-12T20:44:43.250748Z","end":"2026-04-12T20:44:43.41058Z","steps":["trace[1196941928] 'read index received' (duration: 91.563018ms)","trace[1196941928] 'applied index is now lower than readState.Index' (duration: 68.267353ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:44:43.410684Z","caller":"traceutil/trace.go:171","msg":"trace[605849754] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"229.856043ms","start":"2026-04-12T20:44:43.180769Z","end":"2026-04-12T20:44:43.410625Z","steps":["trace[605849754] 'process raft request' (duration: 161.677521ms)","trace[605849754] 'compare' (duration: 67.99928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:44:43.411145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.362177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-12T20:44:43.411238Z","caller":"traceutil/trace.go:171","msg":"trace[1571345420] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2723; }","duration":"160.517929ms","start":"2026-04-12T20:44:43.250699Z","end":"2026-04-12T20:44:43.411217Z","steps":["trace[1571345420] 'agreement among raft nodes before linearized reading' (duration: 159.984354ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:44:48.586787Z","caller":"traceutil/trace.go:171","msg":"trace[56646310] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"122.398465ms","start":"2026-04-12T20:44:48.464364Z","end":"2026-04-12T20:44:48.586762Z","steps":["trace[56646310] 'process raft request' (duration: 118.932008ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:34.166611Z","caller":"traceutil/trace.go:171","msg":"trace[1054175891] linearizableReadLoop","detail":"{readStateIndex:4797; appliedIndex:4796; }","duration":"118.035275ms","start":"2026-04-12T20:48:34.048547Z","end":"2026-04-12T20:48:34.166583Z","steps":["trace[1054175891] 'read index received' (duration: 94.744152ms)","trace[1054175891] 'applied index is now lower than readState.Index' (duration: 23.289773ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:34.166798Z","caller":"traceutil/trace.go:171","msg":"trace[1083341845] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"144.697968ms","start":"2026-04-12T20:48:34.022039Z","end":"2026-04-12T20:48:34.166737Z","steps":["trace[1083341845] 'process raft request' (duration: 121.473276ms)","trace[1083341845] 'compare' (duration: 22.858055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:34.166986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.411042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-12T20:48:34.167137Z","caller":"traceutil/trace.go:171","msg":"trace[1700175576] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4619; }","duration":"118.635586ms","start":"2026-04-12T20:48:34.048475Z","end":"2026-04-12T20:48:34.16711Z","steps":["trace[1700175576] 'agreement among raft nodes before linearized reading' (duration: 118.26194ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:39.775898Z","caller":"traceutil/trace.go:171","msg":"trace[210248316] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"276.35117ms","start":"2026-04-12T20:48:39.4995Z","end":"2026-04-12T20:48:39.775851Z","steps":["trace[210248316] 'process raft request' (duration: 276.017573ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:39.775908Z","caller":"traceutil/trace.go:171","msg":"trace[1651848567] linearizableReadLoop","detail":"{readStateIndex:4910; appliedIndex:4909; }","duration":"276.188907ms","start":"2026-04-12T20:48:39.499657Z","end":"2026-04-12T20:48:39.775846Z","steps":["trace[1651848567] 'read index received' (duration: 275.735138ms)","trace[1651848567] 'applied index is now lower than readState.Index' (duration: 450.889µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:39.777043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.630349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:48:39.777155Z","caller":"traceutil/trace.go:171","msg":"trace[750410118] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4730; }","duration":"197.747581ms","start":"2026-04-12T20:48:39.579367Z","end":"2026-04-12T20:48:39.777114Z","steps":["trace[750410118] 'agreement among raft nodes before linearized reading' (duration: 196.900226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:39.777382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.704403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7581"} {"level":"info","ts":"2026-04-12T20:48:39.779582Z","caller":"traceutil/trace.go:171","msg":"trace[239057561] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4730; }","duration":"279.923083ms","start":"2026-04-12T20:48:39.499616Z","end":"2026-04-12T20:48:39.779539Z","steps":["trace[239057561] 'agreement among raft nodes before linearized reading' (duration: 276.35703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:39.777614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.602626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-12T20:48:39.779786Z","caller":"traceutil/trace.go:171","msg":"trace[2007102693] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4730; }","duration":"167.743463ms","start":"2026-04-12T20:48:39.611954Z","end":"2026-04-12T20:48:39.779698Z","steps":["trace[2007102693] 'agreement among raft nodes before linearized reading' (duration: 164.412415ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:39.969649Z","caller":"traceutil/trace.go:171","msg":"trace[1912377612] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"110.5461ms","start":"2026-04-12T20:48:39.859071Z","end":"2026-04-12T20:48:39.969618Z","steps":["trace[1912377612] 'process raft request' (duration: 110.271115ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:39.97013Z","caller":"traceutil/trace.go:171","msg":"trace[395559903] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"108.788159ms","start":"2026-04-12T20:48:39.861306Z","end":"2026-04-12T20:48:39.970094Z","steps":["trace[395559903] 'process raft request' (duration: 108.228419ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:39.97032Z","caller":"traceutil/trace.go:171","msg":"trace[978366171] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"107.009817ms","start":"2026-04-12T20:48:39.863245Z","end":"2026-04-12T20:48:39.970254Z","steps":["trace[978366171] 'process raft request' (duration: 106.746622ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:20.453496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-04-12T20:50:20.532719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"77.327889ms","hash":1506874240,"current-db-size-bytes":54439936,"current-db-size":"54 MB","current-db-size-in-use-bytes":29429760,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-12T20:50:20.532792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1506874240,"revision":2921,"compact-revision":-1} {"level":"info","ts":"2026-04-12T20:52:34.311246Z","caller":"traceutil/trace.go:171","msg":"trace[281158152] linearizableReadLoop","detail":"{readStateIndex:6760; appliedIndex:6759; }","duration":"100.472898ms","start":"2026-04-12T20:52:34.210739Z","end":"2026-04-12T20:52:34.311212Z","steps":["trace[281158152] 'read index received' (duration: 99.643027ms)","trace[281158152] 'applied index is now lower than readState.Index' (duration: 828.641µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:52:34.311348Z","caller":"traceutil/trace.go:171","msg":"trace[290645739] transaction","detail":"{read_only:false; response_revision:6520; number_of_response:1; }","duration":"120.058636ms","start":"2026-04-12T20:52:34.191253Z","end":"2026-04-12T20:52:34.311312Z","steps":["trace[290645739] 'process raft request' (duration: 119.216895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:52:34.311528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.728771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-12T20:52:34.31159Z","caller":"traceutil/trace.go:171","msg":"trace[1850888524] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6520; }","duration":"100.891553ms","start":"2026-04-12T20:52:34.210685Z","end":"2026-04-12T20:52:34.311576Z","steps":["trace[1850888524] 'agreement among raft nodes before linearized reading' (duration: 100.71849ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:55:20.460084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5638} {"level":"info","ts":"2026-04-12T20:55:20.541905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5638,"took":"79.755817ms","hash":3875411730,"current-db-size-bytes":54439936,"current-db-size":"54 MB","current-db-size-in-use-bytes":18210816,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-12T20:55:20.541964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3875411730,"revision":5638,"compact-revision":2921}