{"level":"warn","ts":"2026-05-07T02:03:27.256313Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T02:03:27.257037Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-05-07T02:03:27.257148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T02:03:27.257157Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-07T02:03:27.257173Z","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-05-07T02:03:27.257836Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-05-07T02:03:27.257917Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-05-07T02:03:27.262588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.462197ms"} {"level":"info","ts":"2026-05-07T02:03:27.273076Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-05-07T02:03:27.273193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T02:03:27.273216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-05-07T02:03:27.273231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T02:03:27.27324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-05-07T02:03:27.273276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-05-07T02:03:27.280553Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T02:03:27.282647Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T02:03:27.283586Z","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-05-07T02:03:27.286146Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T02:03:27.286336Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T02:03:27.286511Z","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-05-07T02:03:27.286617Z","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-05-07T02:03:27.286643Z","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-05-07T02:03:27.290163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-05-07T02:03:27.290378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-05-07T02:03:27.292069Z","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-05-07T02:03:27.292178Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-07T02:03:27.292201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-05-07T02:03:27.292423Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T02:03:27.292458Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T02:03:27.973836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T02:03:27.974239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T02:03:27.974269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-05-07T02:03:27.974292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-05-07T02:03:27.974304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-07T02:03:27.974321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-05-07T02:03:27.974343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-05-07T02:03:27.975577Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T02:03:27.97567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T02:03:27.97595Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T02:03:27.97601Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T02:03:27.975638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T02:03:27.981291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-05-07T02:03:27.975619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:03:27.982643Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:03:27.982832Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:03:27.982882Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:03:27.986041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T02:03:43.688053Z","caller":"traceutil/trace.go:171","msg":"trace[1102238092] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"279.756389ms","start":"2026-05-07T02:03:43.408271Z","end":"2026-05-07T02:03:43.688028Z","steps":["trace[1102238092] 'read index received' (duration: 279.595015ms)","trace[1102238092] 'applied index is now lower than readState.Index' (duration: 160.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:43.688161Z","caller":"traceutil/trace.go:171","msg":"trace[1063914202] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"323.474943ms","start":"2026-05-07T02:03:43.364633Z","end":"2026-05-07T02:03:43.688108Z","steps":["trace[1063914202] 'process raft request' (duration: 323.251287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:43.688239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.952354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T02:03:43.68836Z","caller":"traceutil/trace.go:171","msg":"trace[743650699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"280.098248ms","start":"2026-05-07T02:03:43.40822Z","end":"2026-05-07T02:03:43.688319Z","steps":["trace[743650699] 'agreement among raft nodes before linearized reading' (duration: 279.961324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:43.688419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.800672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:43.688545Z","caller":"traceutil/trace.go:171","msg":"trace[629693266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"263.895463ms","start":"2026-05-07T02:03:43.424563Z","end":"2026-05-07T02:03:43.688459Z","steps":["trace[629693266] 'agreement among raft nodes before linearized reading' (duration: 263.735449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:43.688871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:43.364616Z","time spent":"323.625566ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:03:44.116155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.666272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:44.116241Z","caller":"traceutil/trace.go:171","msg":"trace[816188969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"426.795526ms","start":"2026-05-07T02:03:43.689426Z","end":"2026-05-07T02:03:44.116221Z","steps":["trace[816188969] 'range keys from in-memory index tree' (duration: 426.59294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:44.116281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:43.689408Z","time spent":"426.863308ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:03:44.117246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.768129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:03:44.11732Z","caller":"traceutil/trace.go:171","msg":"trace[470417639] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"179.004642ms","start":"2026-05-07T02:03:43.938303Z","end":"2026-05-07T02:03:44.117308Z","steps":["trace[470417639] 'read index received' (duration: 150.644µs)","trace[470417639] 'applied index is now lower than readState.Index' (duration: 178.853268ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:44.117415Z","caller":"traceutil/trace.go:171","msg":"trace[1890849113] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"423.386017ms","start":"2026-05-07T02:03:43.693956Z","end":"2026-05-07T02:03:44.117342Z","steps":["trace[1890849113] 'process raft request' (duration: 190.34512ms)","trace[1890849113] 'compare' (duration: 231.881316ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:03:44.11751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.200278ms","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-05-07T02:03:44.117613Z","caller":"traceutil/trace.go:171","msg":"trace[1709048679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"179.341461ms","start":"2026-05-07T02:03:43.93825Z","end":"2026-05-07T02:03:44.117591Z","steps":["trace[1709048679] 'agreement among raft nodes before linearized reading' (duration: 179.175226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:44.117634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:43.693935Z","time spent":"423.571331ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:03:44.302643Z","caller":"traceutil/trace.go:171","msg":"trace[906417454] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"180.725578ms","start":"2026-05-07T02:03:44.121875Z","end":"2026-05-07T02:03:44.3026Z","steps":["trace[906417454] 'process raft request' (duration: 161.795982ms)","trace[906417454] 'compare' (duration: 18.664068ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:06.865258Z","caller":"traceutil/trace.go:171","msg":"trace[1599914483] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"236.452056ms","start":"2026-05-07T02:04:06.628775Z","end":"2026-05-07T02:04:06.865227Z","steps":["trace[1599914483] 'process raft request' (duration: 189.512968ms)","trace[1599914483] 'compare' (duration: 46.750534ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:06.865921Z","caller":"traceutil/trace.go:171","msg":"trace[1174403431] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"236.98439ms","start":"2026-05-07T02:04:06.628898Z","end":"2026-05-07T02:04:06.865882Z","steps":["trace[1174403431] 'process raft request' (duration: 236.289452ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.866045Z","caller":"traceutil/trace.go:171","msg":"trace[1408211674] transaction","detail":"{read_only:false; number_of_response:1; response_revision:549; }","duration":"232.884363ms","start":"2026-05-07T02:04:06.633148Z","end":"2026-05-07T02:04:06.866032Z","steps":["trace[1408211674] 'process raft request' (duration: 232.734219ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.866155Z","caller":"traceutil/trace.go:171","msg":"trace[959139419] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"237.120444ms","start":"2026-05-07T02:04:06.629021Z","end":"2026-05-07T02:04:06.866141Z","steps":["trace[959139419] 'process raft request' (duration: 236.823096ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.866162Z","caller":"traceutil/trace.go:171","msg":"trace[980255283] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"237.131754ms","start":"2026-05-07T02:04:06.628959Z","end":"2026-05-07T02:04:06.866091Z","steps":["trace[980255283] 'process raft request' (duration: 236.813686ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.866241Z","caller":"traceutil/trace.go:171","msg":"trace[7252371] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"228.766475ms","start":"2026-05-07T02:04:06.637457Z","end":"2026-05-07T02:04:06.866224Z","steps":["trace[7252371] 'process raft request' (duration: 228.533199ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.873978Z","caller":"traceutil/trace.go:171","msg":"trace[1973389778] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"205.154787ms","start":"2026-05-07T02:04:06.668793Z","end":"2026-05-07T02:04:06.873948Z","steps":["trace[1973389778] 'process raft request' (duration: 204.816538ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.874301Z","caller":"traceutil/trace.go:171","msg":"trace[1383517512] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"231.178797ms","start":"2026-05-07T02:04:06.643095Z","end":"2026-05-07T02:04:06.874273Z","steps":["trace[1383517512] 'process raft request' (duration: 230.144431ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.874353Z","caller":"traceutil/trace.go:171","msg":"trace[2053271559] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"230.392827ms","start":"2026-05-07T02:04:06.643944Z","end":"2026-05-07T02:04:06.874337Z","steps":["trace[2053271559] 'process raft request' (duration: 229.458142ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.874452Z","caller":"traceutil/trace.go:171","msg":"trace[769567887] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"221.228707ms","start":"2026-05-07T02:04:06.653218Z","end":"2026-05-07T02:04:06.874447Z","steps":["trace[769567887] 'process raft request' (duration: 220.281872ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.874506Z","caller":"traceutil/trace.go:171","msg":"trace[1973080635] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"221.253928ms","start":"2026-05-07T02:04:06.653245Z","end":"2026-05-07T02:04:06.874498Z","steps":["trace[1973080635] 'process raft request' (duration: 220.311714ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:06.874711Z","caller":"traceutil/trace.go:171","msg":"trace[58727749] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:567; }","duration":"231.512436ms","start":"2026-05-07T02:04:06.643174Z","end":"2026-05-07T02:04:06.874686Z","steps":["trace[58727749] 'read index received' (duration: 175.193773ms)","trace[58727749] 'applied index is now lower than readState.Index' (duration: 56.318003ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:06.874881Z","caller":"traceutil/trace.go:171","msg":"trace[78270567] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"184.59096ms","start":"2026-05-07T02:04:06.690281Z","end":"2026-05-07T02:04:06.874872Z","steps":["trace[78270567] 'process raft request' (duration: 183.512301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:06.875031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.842526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-07T02:04:06.875079Z","caller":"traceutil/trace.go:171","msg":"trace[1857346562] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:557; }","duration":"231.914118ms","start":"2026-05-07T02:04:06.643155Z","end":"2026-05-07T02:04:06.875069Z","steps":["trace[1857346562] 'agreement among raft nodes before linearized reading' (duration: 231.60154ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:13.481239Z","caller":"traceutil/trace.go:171","msg":"trace[553740864] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"253.183023ms","start":"2026-05-07T02:04:13.228025Z","end":"2026-05-07T02:04:13.481208Z","steps":["trace[553740864] 'process raft request' (duration: 171.001384ms)","trace[553740864] 'compare' (duration: 82.007754ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:13.481392Z","caller":"traceutil/trace.go:171","msg":"trace[1568574036] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"111.505076ms","start":"2026-05-07T02:04:13.36986Z","end":"2026-05-07T02:04:13.481365Z","steps":["trace[1568574036] 'read index received' (duration: 29.283816ms)","trace[1568574036] 'applied index is now lower than readState.Index' (duration: 82.22003ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:13.481568Z","caller":"traceutil/trace.go:171","msg":"trace[483925863] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"236.20965ms","start":"2026-05-07T02:04:13.245278Z","end":"2026-05-07T02:04:13.481488Z","steps":["trace[483925863] 'process raft request' (duration: 235.896402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:13.481782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.912028ms","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-05-07T02:04:13.481868Z","caller":"traceutil/trace.go:171","msg":"trace[442601429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"112.02622ms","start":"2026-05-07T02:04:13.369826Z","end":"2026-05-07T02:04:13.481852Z","steps":["trace[442601429] 'agreement among raft nodes before linearized reading' (duration: 111.786554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:13.978236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.12204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:13.978313Z","caller":"traceutil/trace.go:171","msg":"trace[1684378475] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:649; }","duration":"397.273643ms","start":"2026-05-07T02:04:13.581024Z","end":"2026-05-07T02:04:13.978298Z","steps":["trace[1684378475] 'range keys from in-memory index tree' (duration: 397.024078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:13.978346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:13.581003Z","time spent":"397.335734ms","remote":"127.0.0.1:54302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-07T02:04:13.978465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.932935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-07T02:04:13.978453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.401269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v56b4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-07T02:04:13.978517Z","caller":"traceutil/trace.go:171","msg":"trace[650221051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"152.019428ms","start":"2026-05-07T02:04:13.826483Z","end":"2026-05-07T02:04:13.978503Z","steps":["trace[650221051] 'range keys from in-memory index tree' (duration: 151.794072ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:13.978528Z","caller":"traceutil/trace.go:171","msg":"trace[191147986] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v56b4; range_end:; response_count:1; response_revision:649; }","duration":"347.490691ms","start":"2026-05-07T02:04:13.63102Z","end":"2026-05-07T02:04:13.97851Z","steps":["trace[191147986] 'range keys from in-memory index tree' (duration: 347.260125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:13.978564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:13.630939Z","time spent":"347.616295ms","remote":"127.0.0.1:54340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-v56b4\" "} {"level":"info","ts":"2026-05-07T02:04:14.281239Z","caller":"traceutil/trace.go:171","msg":"trace[2079031195] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"300.303875ms","start":"2026-05-07T02:04:13.980906Z","end":"2026-05-07T02:04:14.28121Z","steps":["trace[2079031195] 'process raft request' (duration: 299.650848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:14.281391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:13.980886Z","time spent":"300.431589ms","remote":"127.0.0.1:54340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T02:04:14.393896Z","caller":"traceutil/trace.go:171","msg":"trace[1871539500] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:674; }","duration":"127.939897ms","start":"2026-05-07T02:04:14.265882Z","end":"2026-05-07T02:04:14.393822Z","steps":["trace[1871539500] 'read index received' (duration: 14.869738ms)","trace[1871539500] 'applied index is now lower than readState.Index' (duration: 113.069269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:04:14.394067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.123172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T02:04:14.394109Z","caller":"traceutil/trace.go:171","msg":"trace[1560201139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"128.267075ms","start":"2026-05-07T02:04:14.265829Z","end":"2026-05-07T02:04:14.394096Z","steps":["trace[1560201139] 'agreement among raft nodes before linearized reading' (duration: 128.130431ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:14.394205Z","caller":"traceutil/trace.go:171","msg":"trace[1116996031] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"401.833143ms","start":"2026-05-07T02:04:13.992223Z","end":"2026-05-07T02:04:14.394056Z","steps":["trace[1116996031] 'process raft request' (duration: 401.538136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:14.394753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:13.992202Z","time spent":"402.392767ms","remote":"127.0.0.1:54340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:14.394146Z","caller":"traceutil/trace.go:171","msg":"trace[67340248] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"411.939437ms","start":"2026-05-07T02:04:13.981914Z","end":"2026-05-07T02:04:14.393853Z","steps":["trace[67340248] 'process raft request' (duration: 411.621379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:14.39519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:13.981897Z","time spent":"413.230001ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:14.403982Z","caller":"traceutil/trace.go:171","msg":"trace[766708574] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"112.725128ms","start":"2026-05-07T02:04:14.291239Z","end":"2026-05-07T02:04:14.403964Z","steps":["trace[766708574] 'process raft request' (duration: 112.691557ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:14.404037Z","caller":"traceutil/trace.go:171","msg":"trace[1887879465] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"115.120122ms","start":"2026-05-07T02:04:14.288893Z","end":"2026-05-07T02:04:14.404013Z","steps":["trace[1887879465] 'process raft request' (duration: 114.891496ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:14.404094Z","caller":"traceutil/trace.go:171","msg":"trace[55210235] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"113.447327ms","start":"2026-05-07T02:04:14.290596Z","end":"2026-05-07T02:04:14.404043Z","steps":["trace[55210235] 'process raft request' (duration: 113.314794ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:14.404221Z","caller":"traceutil/trace.go:171","msg":"trace[1411170591] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"115.279057ms","start":"2026-05-07T02:04:14.288931Z","end":"2026-05-07T02:04:14.40421Z","steps":["trace[1411170591] 'process raft request' (duration: 114.944087ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:56.00885Z","caller":"traceutil/trace.go:171","msg":"trace[785621204] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"122.33651ms","start":"2026-05-07T02:04:55.886489Z","end":"2026-05-07T02:04:56.008826Z","steps":["trace[785621204] 'process raft request' (duration: 114.724157ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:56.880419Z","caller":"traceutil/trace.go:171","msg":"trace[863788479] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"125.407361ms","start":"2026-05-07T02:04:56.754987Z","end":"2026-05-07T02:04:56.880395Z","steps":["trace[863788479] 'process raft request' (duration: 101.040888ms)","trace[863788479] 'compare' (duration: 24.176859ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:03.157413Z","caller":"traceutil/trace.go:171","msg":"trace[539607497] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"228.234798ms","start":"2026-05-07T02:05:02.929155Z","end":"2026-05-07T02:05:03.15739Z","steps":["trace[539607497] 'process raft request' (duration: 223.600214ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:04.328417Z","caller":"traceutil/trace.go:171","msg":"trace[1268831245] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"469.489096ms","start":"2026-05-07T02:05:03.858905Z","end":"2026-05-07T02:05:04.328394Z","steps":["trace[1268831245] 'process raft request' (duration: 469.333851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:04.328552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:03.858859Z","time spent":"469.62044ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:04.328869Z","caller":"traceutil/trace.go:171","msg":"trace[1662702261] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"298.571814ms","start":"2026-05-07T02:05:04.030269Z","end":"2026-05-07T02:05:04.328841Z","steps":["trace[1662702261] 'process raft request' (duration: 298.406549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:25.22865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.627041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:25.228745Z","caller":"traceutil/trace.go:171","msg":"trace[1028559914] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1091; }","duration":"240.782575ms","start":"2026-05-07T02:05:24.987941Z","end":"2026-05-07T02:05:25.228724Z","steps":["trace[1028559914] 'count revisions from in-memory index tree' (duration: 240.487686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:25.228756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.494127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-07T02:05:25.228808Z","caller":"traceutil/trace.go:171","msg":"trace[873939251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"190.588719ms","start":"2026-05-07T02:05:25.038206Z","end":"2026-05-07T02:05:25.228794Z","steps":["trace[873939251] 'range keys from in-memory index tree' (duration: 190.353603ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:25.575223Z","caller":"traceutil/trace.go:171","msg":"trace[1945142056] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"492.821281ms","start":"2026-05-07T02:05:25.082365Z","end":"2026-05-07T02:05:25.575186Z","steps":["trace[1945142056] 'process raft request' (duration: 492.612026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:25.575477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:25.082346Z","time spent":"492.987035ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:25.577673Z","caller":"traceutil/trace.go:171","msg":"trace[226541137] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1132; }","duration":"299.446388ms","start":"2026-05-07T02:05:25.278202Z","end":"2026-05-07T02:05:25.577648Z","steps":["trace[226541137] 'read index received' (duration: 296.733445ms)","trace[226541137] 'applied index is now lower than readState.Index' (duration: 2.712033ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:25.577724Z","caller":"traceutil/trace.go:171","msg":"trace[543551886] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"345.908363ms","start":"2026-05-07T02:05:25.231793Z","end":"2026-05-07T02:05:25.577702Z","steps":["trace[543551886] 'process raft request' (duration: 345.696768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:25.577858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.767079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:05:25.577865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:25.231778Z","time spent":"345.993556ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:25.577881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.679504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T02:05:25.57792Z","caller":"traceutil/trace.go:171","msg":"trace[1019634411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"154.863812ms","start":"2026-05-07T02:05:25.423042Z","end":"2026-05-07T02:05:25.577906Z","steps":["trace[1019634411] 'agreement among raft nodes before linearized reading' (duration: 154.755499ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:25.577925Z","caller":"traceutil/trace.go:171","msg":"trace[927877453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"299.764595ms","start":"2026-05-07T02:05:25.278148Z","end":"2026-05-07T02:05:25.577913Z","steps":["trace[927877453] 'agreement among raft nodes before linearized reading' (duration: 299.624072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:26.775667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.600933ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T02:05:26.775803Z","caller":"traceutil/trace.go:171","msg":"trace[137942750] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"343.794076ms","start":"2026-05-07T02:05:26.431988Z","end":"2026-05-07T02:05:26.775782Z","steps":["trace[137942750] 'read index received' (duration: 57.05698ms)","trace[137942750] 'applied index is now lower than readState.Index' (duration: 286.735516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:26.77596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.955571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:26.776002Z","caller":"traceutil/trace.go:171","msg":"trace[922524104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"344.032653ms","start":"2026-05-07T02:05:26.431956Z","end":"2026-05-07T02:05:26.775989Z","steps":["trace[922524104] 'agreement among raft nodes before linearized reading' (duration: 343.94386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:26.776069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:26.43194Z","time spent":"344.117596ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:05:26.776057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.476539ms","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-05-07T02:05:26.776468Z","caller":"traceutil/trace.go:171","msg":"trace[1125976101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"308.939411ms","start":"2026-05-07T02:05:26.467515Z","end":"2026-05-07T02:05:26.776454Z","steps":["trace[1125976101] 'agreement among raft nodes before linearized reading' (duration: 308.409437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:26.77651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:26.467492Z","time spent":"309.006675ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T02:05:26.930893Z","caller":"traceutil/trace.go:171","msg":"trace[94791452] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"150.802992ms","start":"2026-05-07T02:05:26.780063Z","end":"2026-05-07T02:05:26.930866Z","steps":["trace[94791452] 'process raft request' (duration: 150.647928ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:27.042844Z","caller":"traceutil/trace.go:171","msg":"trace[1230185003] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"102.925679ms","start":"2026-05-07T02:05:26.939889Z","end":"2026-05-07T02:05:27.042814Z","steps":["trace[1230185003] 'process raft request' (duration: 102.668542ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:27.786389Z","caller":"traceutil/trace.go:171","msg":"trace[32567555] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"201.410339ms","start":"2026-05-07T02:05:27.584959Z","end":"2026-05-07T02:05:27.78637Z","steps":["trace[32567555] 'process raft request' (duration: 201.302346ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:27.786812Z","caller":"traceutil/trace.go:171","msg":"trace[623932280] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"191.964356ms","start":"2026-05-07T02:05:27.594819Z","end":"2026-05-07T02:05:27.786784Z","steps":["trace[623932280] 'read index received' (duration: 191.37613ms)","trace[623932280] 'applied index is now lower than readState.Index' (duration: 586.056µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:27.787222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.327956ms","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-05-07T02:05:27.787268Z","caller":"traceutil/trace.go:171","msg":"trace[136120907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"192.440539ms","start":"2026-05-07T02:05:27.594815Z","end":"2026-05-07T02:05:27.787256Z","steps":["trace[136120907] 'agreement among raft nodes before linearized reading' (duration: 192.06231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:28.354742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.913939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:28.354858Z","caller":"traceutil/trace.go:171","msg":"trace[1572869338] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1101; }","duration":"200.085834ms","start":"2026-05-07T02:05:28.154741Z","end":"2026-05-07T02:05:28.354827Z","steps":["trace[1572869338] 'count revisions from in-memory index tree' (duration: 199.805826ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:28.576803Z","caller":"traceutil/trace.go:171","msg":"trace[1705449778] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"141.0378ms","start":"2026-05-07T02:05:28.435741Z","end":"2026-05-07T02:05:28.576779Z","steps":["trace[1705449778] 'process raft request' (duration: 140.916457ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:40.298506Z","caller":"traceutil/trace.go:171","msg":"trace[657065386] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"101.76888ms","start":"2026-05-07T02:05:40.196717Z","end":"2026-05-07T02:05:40.298486Z","steps":["trace[657065386] 'process raft request' (duration: 101.647787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.402886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811754135991096,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T02:05:42.406158Z","caller":"traceutil/trace.go:171","msg":"trace[321583954] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"503.357335ms","start":"2026-05-07T02:05:41.90278Z","end":"2026-05-07T02:05:42.406138Z","steps":["trace[321583954] 'read index received' (duration: 503.210201ms)","trace[321583954] 'applied index is now lower than readState.Index' (duration: 146.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:42.406197Z","caller":"traceutil/trace.go:171","msg":"trace[155093407] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"557.457082ms","start":"2026-05-07T02:05:41.848714Z","end":"2026-05-07T02:05:42.406171Z","steps":["trace[155093407] 'process raft request' (duration: 557.300338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.406353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.529359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:05:42.40635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.848696Z","time spent":"557.556344ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:42.406393Z","caller":"traceutil/trace.go:171","msg":"trace[1053461150] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1197; }","duration":"477.617482ms","start":"2026-05-07T02:05:41.928765Z","end":"2026-05-07T02:05:42.406382Z","steps":["trace[1053461150] 'agreement among raft nodes before linearized reading' (duration: 477.501068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.40639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.605101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T02:05:42.406421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.928751Z","time spent":"477.663583ms","remote":"127.0.0.1:46904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-07T02:05:42.406461Z","caller":"traceutil/trace.go:171","msg":"trace[1333516868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"503.728296ms","start":"2026-05-07T02:05:41.902716Z","end":"2026-05-07T02:05:42.406444Z","steps":["trace[1333516868] 'agreement among raft nodes before linearized reading' (duration: 503.50435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.406477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.933279ms","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-05-07T02:05:42.406557Z","caller":"traceutil/trace.go:171","msg":"trace[1414700795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1197; }","duration":"104.056242ms","start":"2026-05-07T02:05:42.302483Z","end":"2026-05-07T02:05:42.406539Z","steps":["trace[1414700795] 'agreement among raft nodes before linearized reading' (duration: 103.836326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.406499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.902692Z","time spent":"503.800856ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T02:05:42.654513Z","caller":"traceutil/trace.go:171","msg":"trace[2025899868] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"244.274318ms","start":"2026-05-07T02:05:42.410213Z","end":"2026-05-07T02:05:42.654488Z","steps":["trace[2025899868] 'process raft request' (duration: 244.047802ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.655129Z","caller":"traceutil/trace.go:171","msg":"trace[1721999795] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"244.889125ms","start":"2026-05-07T02:05:42.410224Z","end":"2026-05-07T02:05:42.655113Z","steps":["trace[1721999795] 'process raft request' (duration: 244.792862ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:54.826618Z","caller":"traceutil/trace.go:171","msg":"trace[752705230] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"122.521769ms","start":"2026-05-07T02:05:54.704075Z","end":"2026-05-07T02:05:54.826596Z","steps":["trace[752705230] 'process raft request' (duration: 122.374726ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:54.835959Z","caller":"traceutil/trace.go:171","msg":"trace[1252199841] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"103.786235ms","start":"2026-05-07T02:05:54.732154Z","end":"2026-05-07T02:05:54.83594Z","steps":["trace[1252199841] 'process raft request' (duration: 103.728933ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:54.836299Z","caller":"traceutil/trace.go:171","msg":"trace[800236297] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"131.370427ms","start":"2026-05-07T02:05:54.70492Z","end":"2026-05-07T02:05:54.83629Z","steps":["trace[800236297] 'process raft request' (duration: 130.873294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:57.558202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.095229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:57.558279Z","caller":"traceutil/trace.go:171","msg":"trace[36245320] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"364.822115ms","start":"2026-05-07T02:05:57.193444Z","end":"2026-05-07T02:05:57.558266Z","steps":["trace[36245320] 'read index received' (duration: 50.635054ms)","trace[36245320] 'applied index is now lower than readState.Index' (duration: 314.185981ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:57.558311Z","caller":"traceutil/trace.go:171","msg":"trace[1584381653] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"412.371815ms","start":"2026-05-07T02:05:57.145916Z","end":"2026-05-07T02:05:57.558287Z","steps":["trace[1584381653] 'process raft request' (duration: 98.123742ms)","trace[1584381653] 'compare' (duration: 313.76662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:57.558437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:57.145894Z","time spent":"412.480428ms","remote":"127.0.0.1:54636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:57.558452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.02713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-07T02:05:57.558456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.398024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:05:57.558474Z","caller":"traceutil/trace.go:171","msg":"trace[1692209466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"365.096012ms","start":"2026-05-07T02:05:57.193372Z","end":"2026-05-07T02:05:57.558468Z","steps":["trace[1692209466] 'agreement among raft nodes before linearized reading' (duration: 364.986459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:57.558503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:57.193346Z","time spent":"365.152233ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T02:05:57.558503Z","caller":"traceutil/trace.go:171","msg":"trace[674789696] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1267; }","duration":"142.481247ms","start":"2026-05-07T02:05:57.41601Z","end":"2026-05-07T02:05:57.558491Z","steps":["trace[674789696] 'agreement among raft nodes before linearized reading' (duration: 142.404595ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:58.591879Z","caller":"traceutil/trace.go:171","msg":"trace[1603316389] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"104.817572ms","start":"2026-05-07T02:05:58.487032Z","end":"2026-05-07T02:05:58.59185Z","steps":["trace[1603316389] 'process raft request' (duration: 104.676678ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:59.01427Z","caller":"traceutil/trace.go:171","msg":"trace[1611033151] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"139.577729ms","start":"2026-05-07T02:05:58.87467Z","end":"2026-05-07T02:05:59.014248Z","steps":["trace[1611033151] 'process raft request' (duration: 69.645186ms)","trace[1611033151] 'compare' (duration: 69.792579ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:59.278723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.055886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:59.27885Z","caller":"traceutil/trace.go:171","msg":"trace[855058057] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"180.434958ms","start":"2026-05-07T02:05:59.09838Z","end":"2026-05-07T02:05:59.278815Z","steps":["trace[855058057] 'read index received' (duration: 45.232898ms)","trace[855058057] 'applied index is now lower than readState.Index' (duration: 135.20036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:59.27905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.660874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T02:05:59.279098Z","caller":"traceutil/trace.go:171","msg":"trace[1960902526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"180.793239ms","start":"2026-05-07T02:05:59.098291Z","end":"2026-05-07T02:05:59.279084Z","steps":["trace[1960902526] 'agreement among raft nodes before linearized reading' (duration: 180.596183ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:59.279127Z","caller":"traceutil/trace.go:171","msg":"trace[215664679] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"204.58815ms","start":"2026-05-07T02:05:59.074496Z","end":"2026-05-07T02:05:59.279085Z","steps":["trace[215664679] 'process raft request' (duration: 69.103791ms)","trace[215664679] 'compare' (duration: 134.839662ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:59.456284Z","caller":"traceutil/trace.go:171","msg":"trace[572571665] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"340.847399ms","start":"2026-05-07T02:05:59.115409Z","end":"2026-05-07T02:05:59.456256Z","steps":["trace[572571665] 'process raft request' (duration: 324.791576ms)","trace[572571665] 'compare' (duration: 15.929099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:59.456442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:59.11537Z","time spent":"341.004963ms","remote":"127.0.0.1:54236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T02:05:59.462676Z","caller":"traceutil/trace.go:171","msg":"trace[1076518881] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1325; }","duration":"180.173392ms","start":"2026-05-07T02:05:59.282487Z","end":"2026-05-07T02:05:59.46266Z","steps":["trace[1076518881] 'read index received' (duration: 157.728948ms)","trace[1076518881] 'applied index is now lower than readState.Index' (duration: 22.443524ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:59.462712Z","caller":"traceutil/trace.go:171","msg":"trace[1121542544] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"180.477649ms","start":"2026-05-07T02:05:59.282207Z","end":"2026-05-07T02:05:59.462684Z","steps":["trace[1121542544] 'process raft request' (duration: 180.325135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:59.462853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.353657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-07T02:05:59.462891Z","caller":"traceutil/trace.go:171","msg":"trace[871684297] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1278; }","duration":"180.421958ms","start":"2026-05-07T02:05:59.28246Z","end":"2026-05-07T02:05:59.462882Z","steps":["trace[871684297] 'agreement among raft nodes before linearized reading' (duration: 180.282714ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:59.462825Z","caller":"traceutil/trace.go:171","msg":"trace[1930814959] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"178.784054ms","start":"2026-05-07T02:05:59.284004Z","end":"2026-05-07T02:05:59.462788Z","steps":["trace[1930814959] 'process raft request' (duration: 178.62142ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:59.679921Z","caller":"traceutil/trace.go:171","msg":"trace[242599756] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"217.158478ms","start":"2026-05-07T02:05:59.462723Z","end":"2026-05-07T02:05:59.679881Z","steps":["trace[242599756] 'read index received' (duration: 217.057325ms)","trace[242599756] 'applied index is now lower than readState.Index' (duration: 100.083µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:59.680099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.924315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:59.680151Z","caller":"traceutil/trace.go:171","msg":"trace[1156101303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"255.025739ms","start":"2026-05-07T02:05:59.425115Z","end":"2026-05-07T02:05:59.680141Z","steps":["trace[1156101303] 'agreement among raft nodes before linearized reading' (duration: 254.885495ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:59.679941Z","caller":"traceutil/trace.go:171","msg":"trace[725147352] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"219.83256ms","start":"2026-05-07T02:05:59.46008Z","end":"2026-05-07T02:05:59.679912Z","steps":["trace[725147352] 'process raft request' (duration: 219.683326ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:59.768416Z","caller":"traceutil/trace.go:171","msg":"trace[71215753] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"300.921514ms","start":"2026-05-07T02:05:59.467459Z","end":"2026-05-07T02:05:59.768381Z","steps":["trace[71215753] 'process raft request' (duration: 300.768669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:59.76849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.815965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:59.768549Z","caller":"traceutil/trace.go:171","msg":"trace[1511917185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1280; }","duration":"224.900547ms","start":"2026-05-07T02:05:59.543633Z","end":"2026-05-07T02:05:59.768534Z","steps":["trace[1511917185] 'agreement among raft nodes before linearized reading' (duration: 224.751703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:59.768574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:59.467405Z","time spent":"301.090689ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:59.768633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.92068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T02:05:59.768725Z","caller":"traceutil/trace.go:171","msg":"trace[66404506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"169.056303ms","start":"2026-05-07T02:05:59.599647Z","end":"2026-05-07T02:05:59.768703Z","steps":["trace[66404506] 'agreement among raft nodes before linearized reading' (duration: 168.752724ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:11.825222Z","caller":"traceutil/trace.go:171","msg":"trace[816641023] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"292.354142ms","start":"2026-05-07T02:06:11.532839Z","end":"2026-05-07T02:06:11.825193Z","steps":["trace[816641023] 'process raft request' (duration: 292.171058ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:12.347863Z","caller":"traceutil/trace.go:171","msg":"trace[1409807123] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"287.967654ms","start":"2026-05-07T02:06:12.059865Z","end":"2026-05-07T02:06:12.347833Z","steps":["trace[1409807123] 'read index received' (duration: 194.652682ms)","trace[1409807123] 'applied index is now lower than readState.Index' (duration: 93.313642ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:06:12.348202Z","caller":"traceutil/trace.go:171","msg":"trace[1548917834] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"446.830362ms","start":"2026-05-07T02:06:11.901334Z","end":"2026-05-07T02:06:12.348164Z","steps":["trace[1548917834] 'process raft request' (duration: 353.228712ms)","trace[1548917834] 'compare' (duration: 92.610694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:12.34845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.573882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-07T02:06:12.348562Z","caller":"traceutil/trace.go:171","msg":"trace[1792814841] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1364; }","duration":"288.734975ms","start":"2026-05-07T02:06:12.059806Z","end":"2026-05-07T02:06:12.348541Z","steps":["trace[1792814841] 'agreement among raft nodes before linearized reading' (duration: 288.369075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:12.348466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:11.901319Z","time spent":"446.998098ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:06:12.348956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.468975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:06:12.349013Z","caller":"traceutil/trace.go:171","msg":"trace[1358838076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1364; }","duration":"211.736372ms","start":"2026-05-07T02:06:12.137262Z","end":"2026-05-07T02:06:12.348998Z","steps":["trace[1358838076] 'agreement among raft nodes before linearized reading' (duration: 211.31373ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:51.470489Z","caller":"traceutil/trace.go:171","msg":"trace[1020165377] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"382.606541ms","start":"2026-05-07T02:06:51.087858Z","end":"2026-05-07T02:06:51.470465Z","steps":["trace[1020165377] 'process raft request' (duration: 382.433536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:51.470642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.087831Z","time spent":"382.738134ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:51.637445Z","caller":"traceutil/trace.go:171","msg":"trace[2030892576] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1604; }","duration":"485.032561ms","start":"2026-05-07T02:06:51.152384Z","end":"2026-05-07T02:06:51.637416Z","steps":["trace[2030892576] 'read index received' (duration: 318.110002ms)","trace[2030892576] 'applied index is now lower than readState.Index' (duration: 166.921339ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:06:51.63748Z","caller":"traceutil/trace.go:171","msg":"trace[500671598] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"497.453485ms","start":"2026-05-07T02:06:51.139988Z","end":"2026-05-07T02:06:51.637441Z","steps":["trace[500671598] 'process raft request' (duration: 409.242988ms)","trace[500671598] 'compare' (duration: 88.015222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:51.637663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.139923Z","time spent":"497.657861ms","remote":"127.0.0.1:54190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:06:51.637693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.339638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T02:06:51.637764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.051607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T02:06:51.63778Z","caller":"traceutil/trace.go:171","msg":"trace[1951164285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1544; }","duration":"485.469443ms","start":"2026-05-07T02:06:51.152294Z","end":"2026-05-07T02:06:51.637763Z","steps":["trace[1951164285] 'agreement among raft nodes before linearized reading' (duration: 485.231037ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:51.637821Z","caller":"traceutil/trace.go:171","msg":"trace[909636406] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1544; }","duration":"470.12614ms","start":"2026-05-07T02:06:51.167681Z","end":"2026-05-07T02:06:51.637807Z","steps":["trace[909636406] 'agreement among raft nodes before linearized reading' (duration: 469.943984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:51.637826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.152272Z","time spent":"485.546055ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T02:06:51.637866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.167669Z","time spent":"470.18763ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T02:06:51.638007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.135424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:06:51.638061Z","caller":"traceutil/trace.go:171","msg":"trace[907647898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1544; }","duration":"213.217426ms","start":"2026-05-07T02:06:51.424828Z","end":"2026-05-07T02:06:51.638046Z","steps":["trace[907647898] 'agreement among raft nodes before linearized reading' (duration: 213.158314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:51.638214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.684194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T02:06:51.638267Z","caller":"traceutil/trace.go:171","msg":"trace[1171027545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1544; }","duration":"290.773515ms","start":"2026-05-07T02:06:51.347478Z","end":"2026-05-07T02:06:51.638252Z","steps":["trace[1171027545] 'agreement among raft nodes before linearized reading' (duration: 290.59493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:52.007866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.308215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:06:52.008037Z","caller":"traceutil/trace.go:171","msg":"trace[1352690052] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"365.467228ms","start":"2026-05-07T02:06:51.642545Z","end":"2026-05-07T02:06:52.008012Z","steps":["trace[1352690052] 'process raft request' (duration: 225.918867ms)","trace[1352690052] 'compare' (duration: 138.977336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:52.008112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.642527Z","time spent":"365.553041ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:52.008243Z","caller":"traceutil/trace.go:171","msg":"trace[397215241] linearizableReadLoop","detail":"{readStateIndex:1608; appliedIndex:1605; }","duration":"365.504569ms","start":"2026-05-07T02:06:51.642696Z","end":"2026-05-07T02:06:52.0082Z","steps":["trace[397215241] 'read index received' (duration: 53.584683ms)","trace[397215241] 'applied index is now lower than readState.Index' (duration: 311.918206ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:06:52.008306Z","caller":"traceutil/trace.go:171","msg":"trace[1924393269] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"365.710735ms","start":"2026-05-07T02:06:51.642569Z","end":"2026-05-07T02:06:52.00828Z","steps":["trace[1924393269] 'process raft request' (duration: 365.385896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:52.008482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.097648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T02:06:52.008511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.807418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T02:06:52.008525Z","caller":"traceutil/trace.go:171","msg":"trace[939388358] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1547; }","duration":"162.190371ms","start":"2026-05-07T02:06:51.846323Z","end":"2026-05-07T02:06:52.008514Z","steps":["trace[939388358] 'agreement among raft nodes before linearized reading' (duration: 162.094868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:52.008539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.642547Z","time spent":"365.90925ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:52.008552Z","caller":"traceutil/trace.go:171","msg":"trace[1596941361] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1547; }","duration":"365.849608ms","start":"2026-05-07T02:06:51.64269Z","end":"2026-05-07T02:06:52.008539Z","steps":["trace[1596941361] 'agreement among raft nodes before linearized reading' (duration: 365.637673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:52.008588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:51.642649Z","time spent":"365.931371ms","remote":"127.0.0.1:54190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-05-07T02:06:52.008566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.713477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:06:52.008636Z","caller":"traceutil/trace.go:171","msg":"trace[574251426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1547; }","duration":"158.84361ms","start":"2026-05-07T02:06:51.849773Z","end":"2026-05-07T02:06:52.008616Z","steps":["trace[574251426] 'agreement among raft nodes before linearized reading' (duration: 158.738767ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:07:01.199196Z","caller":"traceutil/trace.go:171","msg":"trace[1089498946] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"132.503392ms","start":"2026-05-07T02:07:01.066675Z","end":"2026-05-07T02:07:01.199179Z","steps":["trace[1089498946] 'process raft request' (duration: 132.394399ms)"],"step_count":1}