{"level":"warn","ts":"2026-08-24T15:29:26.842906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:29:26.842992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.111: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.19.213.111:2380","--initial-cluster=instance=https://199.19.213.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.111: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-08-24T15:29:26.843055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:29:26.843062Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-08-24T15:29:26.843082Z","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-08-24T15:29:26.843702Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"]} {"level":"info","ts":"2026-08-24T15:29:26.843793Z","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":16,"max-cpu-available":16,"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.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.111: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-08-24T15:29:26.847823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.812965ms"} {"level":"info","ts":"2026-08-24T15:29:26.853408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4edbe0dc99dceaa8","cluster-id":"9f725a00b80fcc82"} {"level":"info","ts":"2026-08-24T15:29:26.853511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:29:26.853548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 0"} {"level":"info","ts":"2026-08-24T15:29:26.853566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4edbe0dc99dceaa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:29:26.853588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 1"} {"level":"info","ts":"2026-08-24T15:29:26.853638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"warn","ts":"2026-08-24T15:29:26.858801Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:29:26.860522Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:29:26.86224Z","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-08-24T15:29:26.86494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4edbe0dc99dceaa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:29:26.865181Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4edbe0dc99dceaa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:29:26.865212Z","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-08-24T15:29:26.865373Z","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-08-24T15:29:26.865406Z","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-08-24T15:29:26.866388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"info","ts":"2026-08-24T15:29:26.866736Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","added-peer-id":"4edbe0dc99dceaa8","added-peer-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-08-24T15:29:26.869421Z","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-08-24T15:29:26.869548Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-08-24T15:29:26.869646Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-08-24T15:29:26.869676Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4edbe0dc99dceaa8","initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:29:26.869736Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:29:27.354288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:29:27.354333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:29:27.354356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgPreVoteResp from 4edbe0dc99dceaa8 at term 1"} {"level":"info","ts":"2026-08-24T15:29:27.354366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:29:27.354372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgVoteResp from 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-08-24T15:29:27.354381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became leader at term 2"} {"level":"info","ts":"2026-08-24T15:29:27.354389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4edbe0dc99dceaa8 elected leader 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-08-24T15:29:27.35532Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4edbe0dc99dceaa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.111:2379]}","request-path":"/0/members/4edbe0dc99dceaa8/attributes","cluster-id":"9f725a00b80fcc82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:29:27.355385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:29:27.355398Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:29:27.355461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:29:27.355672Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:29:27.355717Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:29:27.356459Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:29:27.356586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:29:27.356627Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:29:27.35853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:29:27.359266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.111:2379"} {"level":"info","ts":"2026-08-24T15:29:43.017334Z","caller":"traceutil/trace.go:171","msg":"trace[1791760729] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"104.440784ms","start":"2026-08-24T15:29:42.912848Z","end":"2026-08-24T15:29:43.017289Z","steps":["trace[1791760729] 'process raft request' (duration: 104.160977ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:02.866784Z","caller":"traceutil/trace.go:171","msg":"trace[1195735372] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"106.935173ms","start":"2026-08-24T15:30:02.759802Z","end":"2026-08-24T15:30:02.866737Z","steps":["trace[1195735372] 'process raft request' (duration: 82.67743ms)","trace[1195735372] 'compare' (duration: 24.085138ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:02.866858Z","caller":"traceutil/trace.go:171","msg":"trace[93599036] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"105.219946ms","start":"2026-08-24T15:30:02.761619Z","end":"2026-08-24T15:30:02.866839Z","steps":["trace[93599036] 'process raft request' (duration: 105.104373ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:02.866906Z","caller":"traceutil/trace.go:171","msg":"trace[1868413628] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"105.074212ms","start":"2026-08-24T15:30:02.761797Z","end":"2026-08-24T15:30:02.866872Z","steps":["trace[1868413628] 'process raft request' (duration: 104.97594ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:02.866998Z","caller":"traceutil/trace.go:171","msg":"trace[1192876260] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"105.671178ms","start":"2026-08-24T15:30:02.761312Z","end":"2026-08-24T15:30:02.866983Z","steps":["trace[1192876260] 'process raft request' (duration: 105.37914ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:02.867241Z","caller":"traceutil/trace.go:171","msg":"trace[245408262] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:524; }","duration":"104.931278ms","start":"2026-08-24T15:30:02.762282Z","end":"2026-08-24T15:30:02.867213Z","steps":["trace[245408262] 'read index received' (duration: 30.370242ms)","trace[245408262] 'applied index is now lower than readState.Index' (duration: 74.560456ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:02.86726Z","caller":"traceutil/trace.go:171","msg":"trace[1067464200] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"102.248644ms","start":"2026-08-24T15:30:02.765Z","end":"2026-08-24T15:30:02.867249Z","steps":["trace[1067464200] 'process raft request' (duration: 102.142271ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:02.867328Z","caller":"traceutil/trace.go:171","msg":"trace[901123198] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"104.940597ms","start":"2026-08-24T15:30:02.762332Z","end":"2026-08-24T15:30:02.867273Z","steps":["trace[901123198] 'process raft request' (duration: 104.473565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:02.867397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.099823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-08-24T15:30:02.867568Z","caller":"traceutil/trace.go:171","msg":"trace[1943280470] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:509; }","duration":"105.299218ms","start":"2026-08-24T15:30:02.762254Z","end":"2026-08-24T15:30:02.867553Z","steps":["trace[1943280470] 'agreement among raft nodes before linearized reading' (duration: 105.036871ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:11.283108Z","caller":"traceutil/trace.go:171","msg":"trace[2135023237] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"342.404749ms","start":"2026-08-24T15:30:10.940677Z","end":"2026-08-24T15:30:11.283081Z","steps":["trace[2135023237] 'read index received' (duration: 341.289678ms)","trace[2135023237] 'applied index is now lower than readState.Index' (duration: 1.114081ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:11.283169Z","caller":"traceutil/trace.go:171","msg":"trace[729902307] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"384.464454ms","start":"2026-08-24T15:30:10.898663Z","end":"2026-08-24T15:30:11.283128Z","steps":["trace[729902307] 'process raft request' (duration: 383.568919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:11.283339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.642235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:30:11.283446Z","caller":"traceutil/trace.go:171","msg":"trace[2001543056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"342.802369ms","start":"2026-08-24T15:30:10.940623Z","end":"2026-08-24T15:30:11.283425Z","steps":["trace[2001543056] 'agreement among raft nodes before linearized reading' (duration: 342.564032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:11.283492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:30:10.940601Z","time spent":"342.880382ms","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:30:11.283384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.949077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-scbs4\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-24T15:30:11.28361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.949645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.111\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:30:11.283639Z","caller":"traceutil/trace.go:171","msg":"trace[860006456] range","detail":"{range_begin:/registry/pods/kube-system/cilium-scbs4; range_end:; response_count:1; response_revision:631; }","duration":"289.281047ms","start":"2026-08-24T15:30:10.994348Z","end":"2026-08-24T15:30:11.283629Z","steps":["trace[860006456] 'agreement among raft nodes before linearized reading' (duration: 288.901296ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:11.283676Z","caller":"traceutil/trace.go:171","msg":"trace[1343218346] range","detail":"{range_begin:/registry/masterleases/199.19.213.111; range_end:; response_count:1; response_revision:631; }","duration":"163.062658ms","start":"2026-08-24T15:30:11.120598Z","end":"2026-08-24T15:30:11.28366Z","steps":["trace[1343218346] 'agreement among raft nodes before linearized reading' (duration: 162.904664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:11.283807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:30:10.898621Z","time spent":"384.624298ms","remote":"127.0.0.1:55210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:30:12.210497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.163312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:30:12.210611Z","caller":"traceutil/trace.go:171","msg":"trace[1290119099] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"862.491391ms","start":"2026-08-24T15:30:11.348103Z","end":"2026-08-24T15:30:12.210595Z","steps":["trace[1290119099] 'read index received' (duration: 445.933268ms)","trace[1290119099] 'applied index is now lower than readState.Index' (duration: 416.556843ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:12.210673Z","caller":"traceutil/trace.go:171","msg":"trace[837036381] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"863.901989ms","start":"2026-08-24T15:30:11.34673Z","end":"2026-08-24T15:30:12.210632Z","steps":["trace[837036381] 'process raft request' (duration: 447.386908ms)","trace[837036381] 'compare' (duration: 416.001127ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:30:12.210764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.661165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T15:30:12.210797Z","caller":"traceutil/trace.go:171","msg":"trace[1407009109] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:638; }","duration":"862.696986ms","start":"2026-08-24T15:30:11.34809Z","end":"2026-08-24T15:30:12.210787Z","steps":["trace[1407009109] 'agreement among raft nodes before linearized reading' (duration: 862.594813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:12.210823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:30:11.346714Z","time spent":"864.030603ms","remote":"127.0.0.1:55342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:30:12.210833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:30:11.348048Z","time spent":"862.776918ms","remote":"127.0.0.1:55392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-08-24T15:30:12.210871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.295047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:30:12.210993Z","caller":"traceutil/trace.go:171","msg":"trace[120253333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"101.482722ms","start":"2026-08-24T15:30:12.109481Z","end":"2026-08-24T15:30:12.210964Z","steps":["trace[120253333] 'agreement among raft nodes before linearized reading' (duration: 101.280907ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:12.355574Z","caller":"traceutil/trace.go:171","msg":"trace[963642982] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"101.453481ms","start":"2026-08-24T15:30:12.254091Z","end":"2026-08-24T15:30:12.355545Z","steps":["trace[963642982] 'process raft request' (duration: 75.245385ms)","trace[963642982] 'compare' (duration: 26.046242ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:12.370237Z","caller":"traceutil/trace.go:171","msg":"trace[1848093766] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"114.505753ms","start":"2026-08-24T15:30:12.255711Z","end":"2026-08-24T15:30:12.370216Z","steps":["trace[1848093766] 'process raft request' (duration: 114.278407ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:12.370276Z","caller":"traceutil/trace.go:171","msg":"trace[913205802] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"114.124422ms","start":"2026-08-24T15:30:12.25613Z","end":"2026-08-24T15:30:12.370255Z","steps":["trace[913205802] 'process raft request' (duration: 114.04893ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:12.370339Z","caller":"traceutil/trace.go:171","msg":"trace[424405353] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"114.542864ms","start":"2026-08-24T15:30:12.255777Z","end":"2026-08-24T15:30:12.37032Z","steps":["trace[424405353] 'process raft request' (duration: 114.327738ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:15.221039Z","caller":"traceutil/trace.go:171","msg":"trace[171510369] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"114.535014ms","start":"2026-08-24T15:30:15.106474Z","end":"2026-08-24T15:30:15.221009Z","steps":["trace[171510369] 'process raft request' (duration: 114.358859ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:17.700519Z","caller":"traceutil/trace.go:171","msg":"trace[800290572] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"101.171833ms","start":"2026-08-24T15:30:17.599319Z","end":"2026-08-24T15:30:17.700491Z","steps":["trace[800290572] 'process raft request' (duration: 74.224556ms)","trace[800290572] 'compare' (duration: 26.811403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:30:59.984538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.67761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:30:59.984661Z","caller":"traceutil/trace.go:171","msg":"trace[779667938] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"234.999485ms","start":"2026-08-24T15:30:59.749641Z","end":"2026-08-24T15:30:59.984641Z","steps":["trace[779667938] 'process raft request' (duration: 76.13202ms)","trace[779667938] 'compare' (duration: 158.506925ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:31:15.42367Z","caller":"traceutil/trace.go:171","msg":"trace[1332250906] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"104.878805ms","start":"2026-08-24T15:31:15.318751Z","end":"2026-08-24T15:31:15.42363Z","steps":["trace[1332250906] 'process raft request' (duration: 104.666789ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:31:15.431558Z","caller":"traceutil/trace.go:171","msg":"trace[1690951377] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"109.299418ms","start":"2026-08-24T15:31:15.322228Z","end":"2026-08-24T15:31:15.431528Z","steps":["trace[1690951377] 'process raft request' (duration: 109.173414ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:31:22.508213Z","caller":"traceutil/trace.go:171","msg":"trace[1739179278] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"101.407946ms","start":"2026-08-24T15:31:22.406783Z","end":"2026-08-24T15:31:22.508191Z","steps":["trace[1739179278] 'process raft request' (duration: 101.226811ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:31:47.778215Z","caller":"traceutil/trace.go:171","msg":"trace[146749544] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"197.166668ms","start":"2026-08-24T15:31:47.580994Z","end":"2026-08-24T15:31:47.778161Z","steps":["trace[146749544] 'process raft request' (duration: 174.467062ms)","trace[146749544] 'compare' (duration: 22.292013ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:31:47.77824Z","caller":"traceutil/trace.go:171","msg":"trace[1383840990] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"106.51814ms","start":"2026-08-24T15:31:47.671665Z","end":"2026-08-24T15:31:47.778183Z","steps":["trace[1383840990] 'process raft request' (duration: 106.423018ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:36.299905Z","caller":"traceutil/trace.go:171","msg":"trace[1856651318] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"136.88395ms","start":"2026-08-24T15:32:36.163Z","end":"2026-08-24T15:32:36.299884Z","steps":["trace[1856651318] 'process raft request' (duration: 136.728916ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:36.314233Z","caller":"traceutil/trace.go:171","msg":"trace[2044037860] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"150.249263ms","start":"2026-08-24T15:32:36.163944Z","end":"2026-08-24T15:32:36.314193Z","steps":["trace[2044037860] 'process raft request' (duration: 150.164811ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:36.314295Z","caller":"traceutil/trace.go:171","msg":"trace[556821426] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"151.19838ms","start":"2026-08-24T15:32:36.163057Z","end":"2026-08-24T15:32:36.314255Z","steps":["trace[556821426] 'process raft request' (duration: 150.84953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:32:42.33814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.880145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:32:42.338265Z","caller":"traceutil/trace.go:171","msg":"trace[38696097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2234; }","duration":"184.077761ms","start":"2026-08-24T15:32:42.154163Z","end":"2026-08-24T15:32:42.338241Z","steps":["trace[38696097] 'range keys from in-memory index tree' (duration: 183.66887ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:42.46881Z","caller":"traceutil/trace.go:171","msg":"trace[1501272324] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"127.075325ms","start":"2026-08-24T15:32:42.341708Z","end":"2026-08-24T15:32:42.468783Z","steps":["trace[1501272324] 'process raft request' (duration: 126.925681ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:42.609802Z","caller":"traceutil/trace.go:171","msg":"trace[1040690392] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"267.527545ms","start":"2026-08-24T15:32:42.342254Z","end":"2026-08-24T15:32:42.609782Z","steps":["trace[1040690392] 'process raft request' (duration: 267.471284ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:42.609837Z","caller":"traceutil/trace.go:171","msg":"trace[1201491822] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"267.540006ms","start":"2026-08-24T15:32:42.34225Z","end":"2026-08-24T15:32:42.60979Z","steps":["trace[1201491822] 'process raft request' (duration: 267.35103ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:32:42.609956Z","caller":"traceutil/trace.go:171","msg":"trace[222645000] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2325; }","duration":"125.07033ms","start":"2026-08-24T15:32:42.484836Z","end":"2026-08-24T15:32:42.609907Z","steps":["trace[222645000] 'read index received' (duration: 124.658779ms)","trace[222645000] 'applied index is now lower than readState.Index' (duration: 405.831µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:32:42.61003Z","caller":"traceutil/trace.go:171","msg":"trace[318297642] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"266.185158ms","start":"2026-08-24T15:32:42.343825Z","end":"2026-08-24T15:32:42.61001Z","steps":["trace[318297642] 'process raft request' (duration: 265.932011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:32:42.61037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.512662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:32:42.61045Z","caller":"traceutil/trace.go:171","msg":"trace[86156147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2238; }","duration":"125.632085ms","start":"2026-08-24T15:32:42.4848Z","end":"2026-08-24T15:32:42.610432Z","steps":["trace[86156147] 'agreement among raft nodes before linearized reading' (duration: 125.243565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:53.953863Z","caller":"traceutil/trace.go:171","msg":"trace[1790949967] transaction","detail":"{read_only:false; response_revision:3640; number_of_response:1; }","duration":"105.123419ms","start":"2026-08-24T15:35:53.848704Z","end":"2026-08-24T15:35:53.953827Z","steps":["trace[1790949967] 'process raft request' (duration: 91.48102ms)","trace[1790949967] 'compare' (duration: 13.549327ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:35:53.973799Z","caller":"traceutil/trace.go:171","msg":"trace[1384311018] transaction","detail":"{read_only:false; response_revision:3641; number_of_response:1; }","duration":"113.758905ms","start":"2026-08-24T15:35:53.86001Z","end":"2026-08-24T15:35:53.973769Z","steps":["trace[1384311018] 'process raft request' (duration: 113.21351ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:53.270505Z","caller":"traceutil/trace.go:171","msg":"trace[101141656] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"140.035337ms","start":"2026-08-24T15:37:53.130426Z","end":"2026-08-24T15:37:53.270462Z","steps":["trace[101141656] 'process raft request' (duration: 134.762778ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:37:53.433423Z","caller":"traceutil/trace.go:171","msg":"trace[889190609] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5220; }","duration":"108.953433ms","start":"2026-08-24T15:37:53.324442Z","end":"2026-08-24T15:37:53.433396Z","steps":["trace[889190609] 'read index received' (duration: 108.753688ms)","trace[889190609] 'applied index is now lower than readState.Index' (duration: 198.355µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:37:53.433469Z","caller":"traceutil/trace.go:171","msg":"trace[361018489] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"154.140883ms","start":"2026-08-24T15:37:53.27928Z","end":"2026-08-24T15:37:53.433421Z","steps":["trace[361018489] 'process raft request' (duration: 153.946068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:37:53.4343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.837558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa7f6603-ee4f-40a7-b8aa-dc46637a3e3c\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-24T15:37:53.434399Z","caller":"traceutil/trace.go:171","msg":"trace[1866958251] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa7f6603-ee4f-40a7-b8aa-dc46637a3e3c; range_end:; response_count:1; response_revision:5028; }","duration":"110.058464ms","start":"2026-08-24T15:37:53.324305Z","end":"2026-08-24T15:37:53.434363Z","steps":["trace[1866958251] 'agreement among raft nodes before linearized reading' (duration: 109.256692ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:38:05.051707Z","caller":"traceutil/trace.go:171","msg":"trace[896335121] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"116.577977ms","start":"2026-08-24T15:38:04.935099Z","end":"2026-08-24T15:38:05.051677Z","steps":["trace[896335121] 'process raft request' (duration: 81.174452ms)","trace[896335121] 'compare' (duration: 35.276312ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:38:05.693421Z","caller":"traceutil/trace.go:171","msg":"trace[210972452] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"173.026794ms","start":"2026-08-24T15:38:05.520368Z","end":"2026-08-24T15:38:05.693394Z","steps":["trace[210972452] 'process raft request' (duration: 157.381224ms)","trace[210972452] 'compare' (duration: 15.544097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:38:05.693668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.455753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-h27nr\" ","response":"range_response_count:1 size:7666"} {"level":"info","ts":"2026-08-24T15:38:05.693748Z","caller":"traceutil/trace.go:171","msg":"trace[656845545] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-h27nr; range_end:; response_count:1; response_revision:5265; }","duration":"191.540006ms","start":"2026-08-24T15:38:05.502183Z","end":"2026-08-24T15:38:05.693723Z","steps":["trace[656845545] 'range keys from in-memory index tree' (duration: 190.830395ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:27.396308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-08-24T15:39:27.4816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"83.719399ms","hash":176733516,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T15:39:27.481683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176733516,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-08-24T15:44:22.287693Z","caller":"traceutil/trace.go:171","msg":"trace[415303063] linearizableReadLoop","detail":"{readStateIndex:8574; appliedIndex:8573; }","duration":"145.796555ms","start":"2026-08-24T15:44:22.141874Z","end":"2026-08-24T15:44:22.287671Z","steps":["trace[415303063] 'read index received' (duration: 145.61337ms)","trace[415303063] 'applied index is now lower than readState.Index' (duration: 182.295µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:44:22.287949Z","caller":"traceutil/trace.go:171","msg":"trace[1576528054] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"149.003335ms","start":"2026-08-24T15:44:22.138932Z","end":"2026-08-24T15:44:22.287935Z","steps":["trace[1576528054] 'process raft request' (duration: 148.610444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:22.288224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.33678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:44:22.288266Z","caller":"traceutil/trace.go:171","msg":"trace[39592572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8279; }","duration":"146.389671ms","start":"2026-08-24T15:44:22.141867Z","end":"2026-08-24T15:44:22.288257Z","steps":["trace[39592572] 'agreement among raft nodes before linearized reading' (duration: 146.265178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:22.288553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.722313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:44:22.288647Z","caller":"traceutil/trace.go:171","msg":"trace[121277506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8279; }","duration":"145.850357ms","start":"2026-08-24T15:44:22.142776Z","end":"2026-08-24T15:44:22.288627Z","steps":["trace[121277506] 'agreement among raft nodes before linearized reading' (duration: 145.568089ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:27.404342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-08-24T15:44:27.501713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"95.650083ms","hash":3731069922,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":23339008,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-24T15:44:27.501783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3731069922,"revision":6119,"compact-revision":3008} {"level":"info","ts":"2026-08-24T15:44:33.660346Z","caller":"traceutil/trace.go:171","msg":"trace[234519369] transaction","detail":"{read_only:false; response_revision:8339; number_of_response:1; }","duration":"133.270032ms","start":"2026-08-24T15:44:33.527035Z","end":"2026-08-24T15:44:33.660305Z","steps":["trace[234519369] 'process raft request' (duration: 133.083787ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:34.000067Z","caller":"traceutil/trace.go:171","msg":"trace[1864037589] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"229.682297ms","start":"2026-08-24T15:44:33.770347Z","end":"2026-08-24T15:44:34.000029Z","steps":["trace[1864037589] 'process raft request' (duration: 229.396229ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:34.023103Z","caller":"traceutil/trace.go:171","msg":"trace[358682346] linearizableReadLoop","detail":"{readStateIndex:8640; appliedIndex:8639; }","duration":"171.193561ms","start":"2026-08-24T15:44:33.851883Z","end":"2026-08-24T15:44:34.023076Z","steps":["trace[358682346] 'read index received' (duration: 148.926694ms)","trace[358682346] 'applied index is now lower than readState.Index' (duration: 22.265466ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:44:34.023201Z","caller":"traceutil/trace.go:171","msg":"trace[71007159] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"241.949652ms","start":"2026-08-24T15:44:33.781214Z","end":"2026-08-24T15:44:34.023163Z","steps":["trace[71007159] 'process raft request' (duration: 241.696645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:34.023297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.398036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:44:34.023384Z","caller":"traceutil/trace.go:171","msg":"trace[458769353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8341; }","duration":"171.507829ms","start":"2026-08-24T15:44:33.851833Z","end":"2026-08-24T15:44:34.023341Z","steps":["trace[458769353] 'agreement among raft nodes before linearized reading' (duration: 171.399446ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:34.549155Z","caller":"traceutil/trace.go:171","msg":"trace[92302058] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"124.202388ms","start":"2026-08-24T15:44:34.424913Z","end":"2026-08-24T15:44:34.549116Z","steps":["trace[92302058] 'process raft request' (duration: 123.90881ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:04.019625Z","caller":"traceutil/trace.go:171","msg":"trace[2061919535] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8860; }","duration":"167.072096ms","start":"2026-08-24T15:45:03.852527Z","end":"2026-08-24T15:45:04.019599Z","steps":["trace[2061919535] 'read index received' (duration: 166.731676ms)","trace[2061919535] 'applied index is now lower than readState.Index' (duration: 338.85µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:04.019782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.23085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:04.01976Z","caller":"traceutil/trace.go:171","msg":"trace[412314127] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"178.118057ms","start":"2026-08-24T15:45:03.841601Z","end":"2026-08-24T15:45:04.019719Z","steps":["trace[412314127] 'process raft request' (duration: 177.797599ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:04.019834Z","caller":"traceutil/trace.go:171","msg":"trace[642665763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8554; }","duration":"167.336473ms","start":"2026-08-24T15:45:03.852482Z","end":"2026-08-24T15:45:04.019819Z","steps":["trace[642665763] 'agreement among raft nodes before linearized reading' (duration: 167.238141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:06.325048Z","caller":"traceutil/trace.go:171","msg":"trace[1893155079] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"175.861114ms","start":"2026-08-24T15:45:06.149162Z","end":"2026-08-24T15:45:06.325023Z","steps":["trace[1893155079] 'process raft request' (duration: 175.628506ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:14.728246Z","caller":"traceutil/trace.go:171","msg":"trace[1527764473] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"109.209076ms","start":"2026-08-24T15:45:14.619006Z","end":"2026-08-24T15:45:14.728215Z","steps":["trace[1527764473] 'process raft request' (duration: 109.085552ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:14.728455Z","caller":"traceutil/trace.go:171","msg":"trace[1619379243] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"109.158055ms","start":"2026-08-24T15:45:14.619271Z","end":"2026-08-24T15:45:14.728429Z","steps":["trace[1619379243] 'process raft request' (duration: 108.891927ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:14.728498Z","caller":"traceutil/trace.go:171","msg":"trace[225743481] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"109.488753ms","start":"2026-08-24T15:45:14.618967Z","end":"2026-08-24T15:45:14.728456Z","steps":["trace[225743481] 'process raft request' (duration: 90.918131ms)","trace[225743481] 'compare' (duration: 18.082869ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:16.485606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.34223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rlmkj\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-24T15:45:16.48573Z","caller":"traceutil/trace.go:171","msg":"trace[1200823899] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rlmkj; range_end:; response_count:1; response_revision:8632; }","duration":"126.513094ms","start":"2026-08-24T15:45:16.359196Z","end":"2026-08-24T15:45:16.485709Z","steps":["trace[1200823899] 'range keys from in-memory index tree' (duration: 126.238507ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:29.325468Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002377.wal"} {"level":"info","ts":"2026-08-24T15:47:09.213304Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4edbe0dc99dceaa8","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T15:47:09.219334Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T15:47:09.219575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T15:49:27.412338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8307} {"level":"info","ts":"2026-08-24T15:49:27.492083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8307,"took":"77.837731ms","hash":2783683307,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":25071616,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-24T15:49:27.492142Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2783683307,"revision":8307,"compact-revision":6119} {"level":"info","ts":"2026-08-24T15:51:01.718511Z","caller":"traceutil/trace.go:171","msg":"trace[213849494] transaction","detail":"{read_only:false; response_revision:11154; number_of_response:1; }","duration":"128.08154ms","start":"2026-08-24T15:51:01.590396Z","end":"2026-08-24T15:51:01.718478Z","steps":["trace[213849494] 'process raft request' (duration: 127.879065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:52:00.777752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.312637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:52:00.777852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.78152ms","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-08-24T15:52:00.777951Z","caller":"traceutil/trace.go:171","msg":"trace[1861836343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11814; }","duration":"111.896643ms","start":"2026-08-24T15:52:00.666034Z","end":"2026-08-24T15:52:00.777931Z","steps":["trace[1861836343] 'range keys from in-memory index tree' (duration: 111.554304ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:52:00.777874Z","caller":"traceutil/trace.go:171","msg":"trace[915537431] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:11814; }","duration":"139.496882ms","start":"2026-08-24T15:52:00.638359Z","end":"2026-08-24T15:52:00.777856Z","steps":["trace[915537431] 'count revisions from in-memory index tree' (duration: 139.162482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:52:23.36797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.362549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:52:23.368291Z","caller":"traceutil/trace.go:171","msg":"trace[258358462] linearizableReadLoop","detail":"{readStateIndex:12367; appliedIndex:12364; }","duration":"217.251612ms","start":"2026-08-24T15:52:23.151021Z","end":"2026-08-24T15:52:23.368273Z","steps":["trace[258358462] 'read index received' (duration: 67.468091ms)","trace[258358462] 'applied index is now lower than readState.Index' (duration: 149.782401ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:52:23.368389Z","caller":"traceutil/trace.go:171","msg":"trace[760446040] transaction","detail":"{read_only:false; response_revision:11952; number_of_response:1; }","duration":"293.421239ms","start":"2026-08-24T15:52:23.074946Z","end":"2026-08-24T15:52:23.368367Z","steps":["trace[760446040] 'process raft request' (duration: 293.280826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:52:23.368426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.389136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:52:23.368404Z","caller":"traceutil/trace.go:171","msg":"trace[127070060] transaction","detail":"{read_only:false; response_revision:11951; number_of_response:1; }","duration":"293.492551ms","start":"2026-08-24T15:52:23.074875Z","end":"2026-08-24T15:52:23.368367Z","steps":["trace[127070060] 'process raft request' (duration: 293.213164ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:52:23.368475Z","caller":"traceutil/trace.go:171","msg":"trace[1327421216] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:11952; }","duration":"217.494839ms","start":"2026-08-24T15:52:23.150966Z","end":"2026-08-24T15:52:23.368461Z","steps":["trace[1327421216] 'agreement among raft nodes before linearized reading' (duration: 217.379416ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:52:23.368409Z","caller":"traceutil/trace.go:171","msg":"trace[1917932444] transaction","detail":"{read_only:false; response_revision:11950; number_of_response:1; }","duration":"293.983235ms","start":"2026-08-24T15:52:23.074381Z","end":"2026-08-24T15:52:23.368365Z","steps":["trace[1917932444] 'process raft request' (duration: 144.138153ms)","trace[1917932444] 'compare' (duration: 149.202454ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:52:24.921851Z","caller":"traceutil/trace.go:171","msg":"trace[917258825] transaction","detail":"{read_only:false; response_revision:11956; number_of_response:1; }","duration":"168.51837ms","start":"2026-08-24T15:52:24.753297Z","end":"2026-08-24T15:52:24.921816Z","steps":["trace[917258825] 'process raft request' (duration: 168.118359ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:27.419048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10529} {"level":"info","ts":"2026-08-24T15:54:27.498324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10529,"took":"77.259987ms","hash":2556233787,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-24T15:54:27.498433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2556233787,"revision":10529,"compact-revision":8307} {"level":"info","ts":"2026-08-24T15:59:27.427381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12803} {"level":"info","ts":"2026-08-24T15:59:27.504697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12803,"took":"74.646779ms","hash":3678791582,"current-db-size-bytes":58470400,"current-db-size":"58 MB","current-db-size-in-use-bytes":24432640,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-24T15:59:27.504755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3678791582,"revision":12803,"compact-revision":10529}