{"level":"warn","ts":"2026-09-09T00:59:29.547828Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T00:59:29.547938Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.56: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://162.253.55.56:2380","--initial-cluster=instance=https://162.253.55.56:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.56:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.56: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-09-09T00:59:29.548022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T00:59:29.54804Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.56:2380"]} {"level":"info","ts":"2026-09-09T00:59:29.54806Z","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-09-09T00:59:29.548895Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.56:2379"]} {"level":"info","ts":"2026-09-09T00:59:29.548996Z","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://162.253.55.56:2380"],"listen-peer-urls":["https://162.253.55.56:2380"],"advertise-client-urls":["https://162.253.55.56:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.56:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.56: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-09-09T00:59:29.553635Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.310218ms"} {"level":"info","ts":"2026-09-09T00:59:29.565381Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c9fb5245756f3898","cluster-id":"2527a2eb37d698df"} {"level":"info","ts":"2026-09-09T00:59:29.56548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T00:59:29.565506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became follower at term 0"} {"level":"info","ts":"2026-09-09T00:59:29.565517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c9fb5245756f3898 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T00:59:29.565527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became follower at term 1"} {"level":"info","ts":"2026-09-09T00:59:29.565574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 switched to configuration voters=(14554317079054334104)"} {"level":"warn","ts":"2026-09-09T00:59:29.57539Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T00:59:29.576925Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T00:59:29.578211Z","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-09-09T00:59:29.580546Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c9fb5245756f3898","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T00:59:29.580811Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c9fb5245756f3898","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T00:59:29.580953Z","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-09-09T00:59:29.581003Z","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-09-09T00:59:29.581016Z","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-09-09T00:59:29.582859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 switched to configuration voters=(14554317079054334104)"} {"level":"info","ts":"2026-09-09T00:59:29.583236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2527a2eb37d698df","local-member-id":"c9fb5245756f3898","added-peer-id":"c9fb5245756f3898","added-peer-peer-urls":["https://162.253.55.56:2380"]} {"level":"info","ts":"2026-09-09T00:59:29.584578Z","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-09-09T00:59:29.584679Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.56:2380"} {"level":"info","ts":"2026-09-09T00:59:29.58492Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c9fb5245756f3898","initial-advertise-peer-urls":["https://162.253.55.56:2380"],"listen-peer-urls":["https://162.253.55.56:2380"],"advertise-client-urls":["https://162.253.55.56:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.56:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T00:59:29.584952Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T00:59:29.584897Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.56:2380"} {"level":"info","ts":"2026-09-09T00:59:30.166204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T00:59:30.166293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T00:59:30.16631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 received MsgPreVoteResp from c9fb5245756f3898 at term 1"} {"level":"info","ts":"2026-09-09T00:59:30.166321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became candidate at term 2"} {"level":"info","ts":"2026-09-09T00:59:30.166327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 received MsgVoteResp from c9fb5245756f3898 at term 2"} {"level":"info","ts":"2026-09-09T00:59:30.166334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became leader at term 2"} {"level":"info","ts":"2026-09-09T00:59:30.166341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c9fb5245756f3898 elected leader c9fb5245756f3898 at term 2"} {"level":"info","ts":"2026-09-09T00:59:30.168305Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:30.168917Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c9fb5245756f3898","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.56:2379]}","request-path":"/0/members/c9fb5245756f3898/attributes","cluster-id":"2527a2eb37d698df","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T00:59:30.169244Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T00:59:30.169276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T00:59:30.169564Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2527a2eb37d698df","local-member-id":"c9fb5245756f3898","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:30.170282Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T00:59:30.170468Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T00:59:30.173259Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T00:59:30.18217Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:30.18233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T00:59:30.193333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.56:2379"} {"level":"info","ts":"2026-09-09T00:59:33.013488Z","caller":"traceutil/trace.go:171","msg":"trace[665776447] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"199.011415ms","start":"2026-09-09T00:59:32.814443Z","end":"2026-09-09T00:59:33.013454Z","steps":["trace[665776447] 'read index received' (duration: 198.772624ms)","trace[665776447] 'applied index is now lower than readState.Index' (duration: 237.351µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T00:59:33.013736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.239816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T00:59:33.013853Z","caller":"traceutil/trace.go:171","msg":"trace[1550229459] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"199.447887ms","start":"2026-09-09T00:59:32.814387Z","end":"2026-09-09T00:59:33.013835Z","steps":["trace[1550229459] 'agreement among raft nodes before linearized reading' (duration: 199.186576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T00:59:33.013888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.447797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T00:59:33.013949Z","caller":"traceutil/trace.go:171","msg":"trace[308068200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"191.576778ms","start":"2026-09-09T00:59:32.822347Z","end":"2026-09-09T00:59:33.013924Z","steps":["trace[308068200] 'agreement among raft nodes before linearized reading' (duration: 191.422857ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:33.014144Z","caller":"traceutil/trace.go:171","msg":"trace[1346548559] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"199.859491ms","start":"2026-09-09T00:59:32.813657Z","end":"2026-09-09T00:59:33.013517Z","steps":["trace[1346548559] 'process raft request' (duration: 199.598419ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:35.901098Z","caller":"traceutil/trace.go:171","msg":"trace[788168801] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"106.359563ms","start":"2026-09-09T00:59:35.794712Z","end":"2026-09-09T00:59:35.901072Z","steps":["trace[788168801] 'process raft request' (duration: 80.265631ms)","trace[788168801] 'compare' (duration: 25.87102ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:35.90123Z","caller":"traceutil/trace.go:171","msg":"trace[1131876628] transaction","detail":"{read_only:false; number_of_response:0; response_revision:251; }","duration":"106.277552ms","start":"2026-09-09T00:59:35.794892Z","end":"2026-09-09T00:59:35.90117Z","steps":["trace[1131876628] 'process raft request' (duration: 106.073851ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:36.130973Z","caller":"traceutil/trace.go:171","msg":"trace[2112419227] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"141.511788ms","start":"2026-09-09T00:59:35.989439Z","end":"2026-09-09T00:59:36.13095Z","steps":["trace[2112419227] 'process raft request' (duration: 141.456778ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:36.131132Z","caller":"traceutil/trace.go:171","msg":"trace[1498025072] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"204.964163ms","start":"2026-09-09T00:59:35.926129Z","end":"2026-09-09T00:59:36.131093Z","steps":["trace[1498025072] 'process raft request' (duration: 145.027011ms)","trace[1498025072] 'compare' (duration: 59.427499ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:36.13146Z","caller":"traceutil/trace.go:171","msg":"trace[327430958] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"158.774826ms","start":"2026-09-09T00:59:35.972524Z","end":"2026-09-09T00:59:36.131299Z","steps":["trace[327430958] 'read index received' (duration: 98.643233ms)","trace[327430958] 'applied index is now lower than readState.Index' (duration: 60.130803ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T00:59:36.131979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.43068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-09T00:59:36.132045Z","caller":"traceutil/trace.go:171","msg":"trace[1219361973] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:260; }","duration":"159.53174ms","start":"2026-09-09T00:59:35.972493Z","end":"2026-09-09T00:59:36.132024Z","steps":["trace[1219361973] 'agreement among raft nodes before linearized reading' (duration: 159.013567ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:45.850473Z","caller":"traceutil/trace.go:171","msg":"trace[152922910] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"132.825789ms","start":"2026-09-09T00:59:45.717608Z","end":"2026-09-09T00:59:45.850434Z","steps":["trace[152922910] 'process raft request' (duration: 132.548426ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:46.02783Z","caller":"traceutil/trace.go:171","msg":"trace[1639365096] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"140.299422ms","start":"2026-09-09T00:59:45.88749Z","end":"2026-09-09T00:59:46.02779Z","steps":["trace[1639365096] 'process raft request' (duration: 90.240572ms)","trace[1639365096] 'compare' (duration: 49.830179ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:46.712172Z","caller":"traceutil/trace.go:171","msg":"trace[1553940336] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"185.618031ms","start":"2026-09-09T00:59:46.526528Z","end":"2026-09-09T00:59:46.712147Z","steps":["trace[1553940336] 'process raft request' (duration: 185.43457ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T00:59:47.141274Z","caller":"traceutil/trace.go:171","msg":"trace[824597330] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"179.376162ms","start":"2026-09-09T00:59:46.961873Z","end":"2026-09-09T00:59:47.141249Z","steps":["trace[824597330] 'process raft request' (duration: 160.989093ms)","trace[824597330] 'compare' (duration: 18.120918ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:49.070761Z","caller":"traceutil/trace.go:171","msg":"trace[1463469043] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"106.03407ms","start":"2026-09-09T00:59:48.964705Z","end":"2026-09-09T00:59:49.070739Z","steps":["trace[1463469043] 'process raft request' (duration: 63.100966ms)","trace[1463469043] 'compare' (duration: 42.811464ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T00:59:49.091189Z","caller":"traceutil/trace.go:171","msg":"trace[1296090309] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"117.017184ms","start":"2026-09-09T00:59:48.974147Z","end":"2026-09-09T00:59:49.091165Z","steps":["trace[1296090309] 'process raft request' (duration: 116.891604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T00:59:49.271186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.721243ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-09-09T01:00:14.434687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:14.077513Z","time spent":"357.147007ms","remote":"127.0.0.1:44142","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-09T01:00:14.435628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.72314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:14.435691Z","caller":"traceutil/trace.go:171","msg":"trace[1234395858] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"437.021269ms","start":"2026-09-09T01:00:13.998656Z","end":"2026-09-09T01:00:14.435677Z","steps":["trace[1234395858] 'read index received' (duration: 197.653186ms)","trace[1234395858] 'applied index is now lower than readState.Index' (duration: 239.366893ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:14.435704Z","caller":"traceutil/trace.go:171","msg":"trace[1270498855] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"463.099897ms","start":"2026-09-09T01:00:13.972585Z","end":"2026-09-09T01:00:14.435685Z","steps":["trace[1270498855] 'process raft request' (duration: 223.811594ms)","trace[1270498855] 'compare' (duration: 238.584698ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:14.435812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:13.972552Z","time spent":"463.192087ms","remote":"127.0.0.1:59166","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-09-09T01:00:14.435856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.209379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:14.435884Z","caller":"traceutil/trace.go:171","msg":"trace[940125391] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:500; }","duration":"437.24146ms","start":"2026-09-09T01:00:13.998634Z","end":"2026-09-09T01:00:14.435875Z","steps":["trace[940125391] 'agreement among raft nodes before linearized reading' (duration: 437.112369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:14.435911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:13.99855Z","time spent":"437.353881ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-09-09T01:00:14.43626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.743106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:00:14.43638Z","caller":"traceutil/trace.go:171","msg":"trace[1578496577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"181.925527ms","start":"2026-09-09T01:00:14.254431Z","end":"2026-09-09T01:00:14.436357Z","steps":["trace[1578496577] 'agreement among raft nodes before linearized reading' (duration: 181.555025ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:14.848126Z","caller":"traceutil/trace.go:171","msg":"trace[577011991] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"406.258485ms","start":"2026-09-09T01:00:14.441838Z","end":"2026-09-09T01:00:14.848096Z","steps":["trace[577011991] 'read index received' (duration: 406.084204ms)","trace[577011991] 'applied index is now lower than readState.Index' (duration: 173.291µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:14.848244Z","caller":"traceutil/trace.go:171","msg":"trace[188426072] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"407.38043ms","start":"2026-09-09T01:00:14.440854Z","end":"2026-09-09T01:00:14.848234Z","steps":["trace[188426072] 'process raft request' (duration: 407.094139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:14.848369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:14.440835Z","time spent":"407.43403ms","remote":"127.0.0.1:59392","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-09-09T01:00:14.848467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.748795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T01:00:14.848551Z","caller":"traceutil/trace.go:171","msg":"trace[536276370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"133.867986ms","start":"2026-09-09T01:00:14.714663Z","end":"2026-09-09T01:00:14.848531Z","steps":["trace[536276370] 'agreement among raft nodes before linearized reading' (duration: 133.662905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:14.848573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.721287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T01:00:14.848624Z","caller":"traceutil/trace.go:171","msg":"trace[413401986] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:501; }","duration":"406.797037ms","start":"2026-09-09T01:00:14.441811Z","end":"2026-09-09T01:00:14.848608Z","steps":["trace[413401986] 'agreement among raft nodes before linearized reading' (duration: 406.686347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:14.848627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.210033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:00:14.848661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:14.4418Z","time spent":"406.854037ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-09-09T01:00:14.848685Z","caller":"traceutil/trace.go:171","msg":"trace[2101276247] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:501; }","duration":"119.352174ms","start":"2026-09-09T01:00:14.729315Z","end":"2026-09-09T01:00:14.848668Z","steps":["trace[2101276247] 'agreement among raft nodes before linearized reading' (duration: 119.203953ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:15.691502Z","caller":"traceutil/trace.go:171","msg":"trace[1045394480] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"150.362219ms","start":"2026-09-09T01:00:15.541066Z","end":"2026-09-09T01:00:15.691428Z","steps":["trace[1045394480] 'process raft request' (duration: 85.990346ms)","trace[1045394480] 'compare' (duration: 64.077782ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:21.602231Z","caller":"traceutil/trace.go:171","msg":"trace[476250467] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"266.629853ms","start":"2026-09-09T01:00:21.335572Z","end":"2026-09-09T01:00:21.602202Z","steps":["trace[476250467] 'process raft request' (duration: 266.490823ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:21.60271Z","caller":"traceutil/trace.go:171","msg":"trace[424028929] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:633; }","duration":"225.650884ms","start":"2026-09-09T01:00:21.377039Z","end":"2026-09-09T01:00:21.60269Z","steps":["trace[424028929] 'read index received' (duration: 225.642834ms)","trace[424028929] 'applied index is now lower than readState.Index' (duration: 6.6µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:21.60283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.748734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:21.602881Z","caller":"traceutil/trace.go:171","msg":"trace[1969141770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"225.868155ms","start":"2026-09-09T01:00:21.376997Z","end":"2026-09-09T01:00:21.602865Z","steps":["trace[1969141770] 'agreement among raft nodes before linearized reading' (duration: 225.761684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:22.104782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4078185949993083427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T01:00:22.605918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4078185949993083427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T01:00:23.106105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4078185949993083427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T01:00:23.16534Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.560806384s","expected-duration":"1s"} {"level":"info","ts":"2026-09-09T01:00:23.167075Z","caller":"traceutil/trace.go:171","msg":"trace[459451967] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.784319747s","start":"2026-09-09T01:00:21.382714Z","end":"2026-09-09T01:00:23.167034Z","steps":["trace[459451967] 'process raft request' (duration: 1.783534302s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.167332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:21.382682Z","time spent":"1.784499427s","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:23.604987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000695263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-09T01:00:23.605099Z","caller":"traceutil/trace.go:171","msg":"trace[639382469] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000860324s","start":"2026-09-09T01:00:21.604215Z","end":"2026-09-09T01:00:23.605075Z","steps":["trace[639382469] 'agreement among raft nodes before linearized reading' (duration: 2.000713683s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.60521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:21.604197Z","time spent":"2.001002415s","remote":"127.0.0.1:59140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/09 01:00:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-09T01:00:23.814882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.510932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:23.815196Z","caller":"traceutil/trace.go:171","msg":"trace[921535930] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"205.457038ms","start":"2026-09-09T01:00:23.609719Z","end":"2026-09-09T01:00:23.815176Z","steps":["trace[921535930] 'process raft request' (duration: 205.399258ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:23.815459Z","caller":"traceutil/trace.go:171","msg":"trace[2083082071] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.249047654s","start":"2026-09-09T01:00:22.566371Z","end":"2026-09-09T01:00:23.815419Z","steps":["trace[2083082071] 'process raft request' (duration: 1.079866249s)","trace[2083082071] 'compare' (duration: 167.943689ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:23.815449Z","caller":"traceutil/trace.go:171","msg":"trace[814531634] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"557.908548ms","start":"2026-09-09T01:00:23.257504Z","end":"2026-09-09T01:00:23.815413Z","steps":["trace[814531634] 'process raft request' (duration: 557.517756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.81567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.257481Z","time spent":"558.098169ms","remote":"127.0.0.1:59506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T01:00:23.815705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.566345Z","time spent":"1.249222865s","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:23.815179Z","caller":"traceutil/trace.go:171","msg":"trace[988453431] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"2.210881928s","start":"2026-09-09T01:00:21.604259Z","end":"2026-09-09T01:00:23.81514Z","steps":["trace[988453431] 'read index received' (duration: 1.561533518s)","trace[988453431] 'applied index is now lower than readState.Index' (duration: 649.34564ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:23.816232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951444325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l2t92\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-09T01:00:23.81627Z","caller":"traceutil/trace.go:171","msg":"trace[1476041987] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l2t92; range_end:; response_count:1; response_revision:611; }","duration":"1.951535385s","start":"2026-09-09T01:00:21.864722Z","end":"2026-09-09T01:00:23.816257Z","steps":["trace[1476041987] 'agreement among raft nodes before linearized reading' (duration: 1.951453204s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.8163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:21.864693Z","time spent":"1.951599495s","remote":"127.0.0.1:59300","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-l2t92\" "} {"level":"warn","ts":"2026-09-09T01:00:23.816498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.18053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:23.816534Z","caller":"traceutil/trace.go:171","msg":"trace[1898853860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"204.21912ms","start":"2026-09-09T01:00:23.612306Z","end":"2026-09-09T01:00:23.816525Z","steps":["trace[1898853860] 'agreement among raft nodes before linearized reading' (duration: 204.16064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.816791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.26873ms","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-09-09T01:00:23.816825Z","caller":"traceutil/trace.go:171","msg":"trace[1248302930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"209.30322ms","start":"2026-09-09T01:00:23.607513Z","end":"2026-09-09T01:00:23.816817Z","steps":["trace[1248302930] 'agreement among raft nodes before linearized reading' (duration: 209.181418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.817111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.943896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-09T01:00:23.817827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.108716ms","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-09-09T01:00:23.817886Z","caller":"traceutil/trace.go:171","msg":"trace[1845739857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"908.241287ms","start":"2026-09-09T01:00:22.90963Z","end":"2026-09-09T01:00:23.817872Z","steps":["trace[1845739857] 'agreement among raft nodes before linearized reading' (duration: 908.103047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.817919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.909551Z","time spent":"908.361798ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:00:23.81794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.544552ms","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-09-09T01:00:23.818008Z","caller":"traceutil/trace.go:171","msg":"trace[1894571102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"819.637102ms","start":"2026-09-09T01:00:22.998355Z","end":"2026-09-09T01:00:23.817992Z","steps":["trace[1894571102] 'agreement among raft nodes before linearized reading' (duration: 818.943978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.81807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.998342Z","time spent":"819.698552ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T01:00:23.818074Z","caller":"traceutil/trace.go:171","msg":"trace[1716603799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"908.314279ms","start":"2026-09-09T01:00:22.909039Z","end":"2026-09-09T01:00:23.817353Z","steps":["trace[1716603799] 'agreement among raft nodes before linearized reading' (duration: 907.737606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:23.818241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:22.909001Z","time spent":"909.198063ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:00:24.331813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4078185949993083433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T01:00:24.832647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4078185949993083433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T01:00:25.003142Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.179701349s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-09T01:00:26.012232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008769508s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:26.012668Z","caller":"traceutil/trace.go:171","msg":"trace[1428388136] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:637; }","duration":"2.181467136s","start":"2026-09-09T01:00:23.831178Z","end":"2026-09-09T01:00:26.012645Z","steps":["trace[1428388136] 'read index received' (duration: 1.172201216s)","trace[1428388136] 'applied index is now lower than readState.Index' (duration: 1.00926462s)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:26.013026Z","caller":"traceutil/trace.go:171","msg":"trace[751570056] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"2.189655633s","start":"2026-09-09T01:00:23.823346Z","end":"2026-09-09T01:00:26.013002Z","steps":["trace[751570056] 'process raft request' (duration: 1.18002145s)","trace[751570056] 'compare' (duration: 1.008612787s)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:26.013173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.823326Z","time spent":"2.189756064s","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:26.013216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18193939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:26.013182Z","caller":"traceutil/trace.go:171","msg":"trace[584223774] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.17656532s","start":"2026-09-09T01:00:23.836533Z","end":"2026-09-09T01:00:26.013099Z","steps":["trace[584223774] 'process raft request' (duration: 2.176051886s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.013276Z","caller":"traceutil/trace.go:171","msg":"trace[111601629] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.189249141s","start":"2026-09-09T01:00:23.824008Z","end":"2026-09-09T01:00:26.013257Z","steps":["trace[111601629] 'process raft request' (duration: 2.188512056s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.013292Z","caller":"traceutil/trace.go:171","msg":"trace[1101950282] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:616; }","duration":"2.182135191s","start":"2026-09-09T01:00:23.831134Z","end":"2026-09-09T01:00:26.013269Z","steps":["trace[1101950282] 'agreement among raft nodes before linearized reading' (duration: 2.18191255s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.013291Z","caller":"traceutil/trace.go:171","msg":"trace[942746828] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.189778534s","start":"2026-09-09T01:00:23.823484Z","end":"2026-09-09T01:00:26.013262Z","steps":["trace[942746828] 'process raft request' (duration: 2.188956898s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.01331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.98655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:00:26.013335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.831118Z","time spent":"2.182205871s","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-09-09T01:00:26.01336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.823998Z","time spent":"2.189317402s","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:26.013371Z","caller":"traceutil/trace.go:171","msg":"trace[2019379890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"638.07089ms","start":"2026-09-09T01:00:25.375282Z","end":"2026-09-09T01:00:26.013353Z","steps":["trace[2019379890] 'agreement among raft nodes before linearized reading' (duration: 637.98019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.013406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.836502Z","time spent":"2.176791141s","remote":"127.0.0.1:59300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:00:26.013421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:25.375267Z","time spent":"638.1427ms","remote":"127.0.0.1:59136","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-09-09T01:00:26.013209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.085676072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.56\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-09T01:00:26.01343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.82346Z","time spent":"2.189890764s","remote":"127.0.0.1:59392","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-09-09T01:00:26.013477Z","caller":"traceutil/trace.go:171","msg":"trace[1181541623] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"2.190094456s","start":"2026-09-09T01:00:23.823365Z","end":"2026-09-09T01:00:26.01346Z","steps":["trace[1181541623] 'process raft request' (duration: 2.188982159s)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.013532Z","caller":"traceutil/trace.go:171","msg":"trace[1583498858] range","detail":"{range_begin:/registry/masterleases/162.253.55.56; range_end:; response_count:1; response_revision:616; }","duration":"2.086047254s","start":"2026-09-09T01:00:23.927437Z","end":"2026-09-09T01:00:26.013484Z","steps":["trace[1583498858] 'agreement among raft nodes before linearized reading' (duration: 2.08547347s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:26.013594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.927409Z","time spent":"2.086165275s","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.56\" "} {"level":"warn","ts":"2026-09-09T01:00:26.013613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:23.823332Z","time spent":"2.190226696s","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:00:26.302126Z","caller":"traceutil/trace.go:171","msg":"trace[1859068948] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"256.49822ms","start":"2026-09-09T01:00:26.045595Z","end":"2026-09-09T01:00:26.302094Z","steps":["trace[1859068948] 'process raft request' (duration: 177.158857ms)","trace[1859068948] 'compare' (duration: 79.187212ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:26.560659Z","caller":"traceutil/trace.go:171","msg":"trace[2117822345] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"146.282485ms","start":"2026-09-09T01:00:26.414346Z","end":"2026-09-09T01:00:26.560628Z","steps":["trace[2117822345] 'process raft request' (duration: 145.579751ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:26.737923Z","caller":"traceutil/trace.go:171","msg":"trace[2001215377] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"102.823472ms","start":"2026-09-09T01:00:26.635048Z","end":"2026-09-09T01:00:26.737871Z","steps":["trace[2001215377] 'process raft request' (duration: 102.060418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:27.012108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.780918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l2t92\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-09-09T01:00:27.012191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.549251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:27.012212Z","caller":"traceutil/trace.go:171","msg":"trace[849978110] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l2t92; range_end:; response_count:1; response_revision:619; }","duration":"128.916409ms","start":"2026-09-09T01:00:26.883258Z","end":"2026-09-09T01:00:27.012175Z","steps":["trace[849978110] 'range keys from in-memory index tree' (duration: 128.605787ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:27.012334Z","caller":"traceutil/trace.go:171","msg":"trace[243931159] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"126.269334ms","start":"2026-09-09T01:00:26.886049Z","end":"2026-09-09T01:00:27.012319Z","steps":["trace[243931159] 'process raft request' (duration: 23.528822ms)","trace[243931159] 'compare' (duration: 102.411511ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:27.190483Z","caller":"traceutil/trace.go:171","msg":"trace[453759806] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"157.145215ms","start":"2026-09-09T01:00:27.033298Z","end":"2026-09-09T01:00:27.190444Z","steps":["trace[453759806] 'process raft request' (duration: 109.802842ms)","trace[453759806] 'compare' (duration: 47.249393ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:27.438523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.841922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:27.43866Z","caller":"traceutil/trace.go:171","msg":"trace[1574420525] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"258.408859ms","start":"2026-09-09T01:00:27.180219Z","end":"2026-09-09T01:00:27.438628Z","steps":["trace[1574420525] 'process raft request' (duration: 157.360906ms)","trace[1574420525] 'compare' (duration: 100.44746ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:28.111575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.043759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:00:28.111777Z","caller":"traceutil/trace.go:171","msg":"trace[1556148596] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"592.894151ms","start":"2026-09-09T01:00:27.518824Z","end":"2026-09-09T01:00:28.111718Z","steps":["trace[1556148596] 'process raft request' (duration: 231.101587ms)","trace[1556148596] 'compare' (duration: 360.783248ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:28.111884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:27.518801Z","time spent":"593.038952ms","remote":"127.0.0.1:59366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T01:00:28.314031Z","caller":"traceutil/trace.go:171","msg":"trace[1760630450] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"195.358697ms","start":"2026-09-09T01:00:28.118642Z","end":"2026-09-09T01:00:28.314001Z","steps":["trace[1760630450] 'process raft request' (duration: 176.749263ms)","trace[1760630450] 'compare' (duration: 18.449054ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:28.314416Z","caller":"traceutil/trace.go:171","msg":"trace[56781457] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"195.422797ms","start":"2026-09-09T01:00:28.118945Z","end":"2026-09-09T01:00:28.314368Z","steps":["trace[56781457] 'process raft request' (duration: 195.016565ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:28.315077Z","caller":"traceutil/trace.go:171","msg":"trace[1961562004] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:651; }","duration":"192.518321ms","start":"2026-09-09T01:00:28.12254Z","end":"2026-09-09T01:00:28.315058Z","steps":["trace[1961562004] 'read index received' (duration: 47.314623ms)","trace[1961562004] 'applied index is now lower than readState.Index' (duration: 145.201748ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:28.315191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.633271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-09T01:00:28.315237Z","caller":"traceutil/trace.go:171","msg":"trace[1536717146] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"196.153512ms","start":"2026-09-09T01:00:28.119058Z","end":"2026-09-09T01:00:28.315212Z","steps":["trace[1536717146] 'process raft request' (duration: 195.188607ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:28.315259Z","caller":"traceutil/trace.go:171","msg":"trace[1779157617] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:628; }","duration":"192.742722ms","start":"2026-09-09T01:00:28.122485Z","end":"2026-09-09T01:00:28.315227Z","steps":["trace[1779157617] 'agreement among raft nodes before linearized reading' (duration: 192.639421ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:31.283322Z","caller":"traceutil/trace.go:171","msg":"trace[415112781] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"159.186013ms","start":"2026-09-09T01:00:31.124103Z","end":"2026-09-09T01:00:31.283289Z","steps":["trace[415112781] 'process raft request' (duration: 131.802991ms)","trace[415112781] 'compare' (duration: 27.222842ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:31.283443Z","caller":"traceutil/trace.go:171","msg":"trace[499671227] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"156.899981ms","start":"2026-09-09T01:00:31.126522Z","end":"2026-09-09T01:00:31.283422Z","steps":["trace[499671227] 'process raft request' (duration: 156.71997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:46.026899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.577877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:46.027009Z","caller":"traceutil/trace.go:171","msg":"trace[1403941195] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:789; }","duration":"508.754679ms","start":"2026-09-09T01:00:45.518233Z","end":"2026-09-09T01:00:46.026988Z","steps":["trace[1403941195] 'count revisions from in-memory index tree' (duration: 508.473087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:46.027088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:45.518208Z","time spent":"508.867789ms","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:01:05.920644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.353342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:05.920751Z","caller":"traceutil/trace.go:171","msg":"trace[2145908147] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"242.537284ms","start":"2026-09-09T01:01:05.678196Z","end":"2026-09-09T01:01:05.920733Z","steps":["trace[2145908147] 'process raft request' (duration: 45.998221ms)","trace[2145908147] 'compare' (duration: 196.191081ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:05.946693Z","caller":"traceutil/trace.go:171","msg":"trace[1602909911] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"268.330985ms","start":"2026-09-09T01:01:05.67833Z","end":"2026-09-09T01:01:05.946661Z","steps":["trace[1602909911] 'process raft request' (duration: 268.172384ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:06.000655Z","caller":"traceutil/trace.go:171","msg":"trace[1078557329] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"218.437372ms","start":"2026-09-09T01:01:05.782193Z","end":"2026-09-09T01:01:06.00063Z","steps":["trace[1078557329] 'process raft request' (duration: 218.252471ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.054214Z","caller":"traceutil/trace.go:171","msg":"trace[305888157] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"187.066581ms","start":"2026-09-09T01:01:06.867118Z","end":"2026-09-09T01:01:07.054184Z","steps":["trace[305888157] 'read index received' (duration: 10.414227ms)","trace[305888157] 'applied index is now lower than readState.Index' (duration: 176.651134ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.054345Z","caller":"traceutil/trace.go:171","msg":"trace[809716715] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"180.939387ms","start":"2026-09-09T01:01:06.873394Z","end":"2026-09-09T01:01:07.054334Z","steps":["trace[809716715] 'process raft request' (duration: 180.698625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.054617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.466003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4685"} {"level":"info","ts":"2026-09-09T01:01:07.054647Z","caller":"traceutil/trace.go:171","msg":"trace[1929477265] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"187.430763ms","start":"2026-09-09T01:01:06.867046Z","end":"2026-09-09T01:01:07.054477Z","steps":["trace[1929477265] 'process raft request' (duration: 157.31101ms)","trace[1929477265] 'compare' (duration: 29.52656ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.054705Z","caller":"traceutil/trace.go:171","msg":"trace[748981087] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1085; }","duration":"187.593344ms","start":"2026-09-09T01:01:06.867092Z","end":"2026-09-09T01:01:07.054685Z","steps":["trace[748981087] 'agreement among raft nodes before linearized reading' (duration: 187.353322ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.085215Z","caller":"traceutil/trace.go:171","msg":"trace[1281030837] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"207.579543ms","start":"2026-09-09T01:01:06.877587Z","end":"2026-09-09T01:01:07.085167Z","steps":["trace[1281030837] 'process raft request' (duration: 207.262011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.085506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.785015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-75c4c745bc\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-09-09T01:01:07.085587Z","caller":"traceutil/trace.go:171","msg":"trace[1672711990] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-75c4c745bc; range_end:; response_count:1; response_revision:1087; }","duration":"211.868046ms","start":"2026-09-09T01:01:06.873684Z","end":"2026-09-09T01:01:07.085552Z","steps":["trace[1672711990] 'agreement among raft nodes before linearized reading' (duration: 211.712725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.085589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.938706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4950"} {"level":"info","ts":"2026-09-09T01:01:07.085506Z","caller":"traceutil/trace.go:171","msg":"trace[1915918410] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"200.137341ms","start":"2026-09-09T01:01:06.885339Z","end":"2026-09-09T01:01:07.085476Z","steps":["trace[1915918410] 'process raft request' (duration: 199.757559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.085722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.090287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64\" ","response":"range_response_count:1 size:4690"} {"level":"warn","ts":"2026-09-09T01:01:07.085713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.398621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:968"} {"level":"info","ts":"2026-09-09T01:01:07.085755Z","caller":"traceutil/trace.go:171","msg":"trace[1727322663] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64; range_end:; response_count:1; response_revision:1087; }","duration":"212.154547ms","start":"2026-09-09T01:01:06.873591Z","end":"2026-09-09T01:01:07.085746Z","steps":["trace[1727322663] 'agreement among raft nodes before linearized reading' (duration: 212.076996ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.085773Z","caller":"traceutil/trace.go:171","msg":"trace[84987836] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1087; }","duration":"152.495512ms","start":"2026-09-09T01:01:06.933262Z","end":"2026-09-09T01:01:07.085758Z","steps":["trace[84987836] 'agreement among raft nodes before linearized reading' (duration: 152.278641ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.085644Z","caller":"traceutil/trace.go:171","msg":"trace[1576838237] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1087; }","duration":"212.009316ms","start":"2026-09-09T01:01:06.87362Z","end":"2026-09-09T01:01:07.08563Z","steps":["trace[1576838237] 'agreement among raft nodes before linearized reading' (duration: 211.878885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.08606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.379859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-cainjector-64b59ddb75\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-09-09T01:01:07.086098Z","caller":"traceutil/trace.go:171","msg":"trace[568619054] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-cainjector-64b59ddb75; range_end:; response_count:1; response_revision:1087; }","duration":"212.43391ms","start":"2026-09-09T01:01:06.873654Z","end":"2026-09-09T01:01:07.086088Z","steps":["trace[568619054] 'agreement among raft nodes before linearized reading' (duration: 211.757145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.320308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.31322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:07.320417Z","caller":"traceutil/trace.go:171","msg":"trace[1361987772] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"212.304758ms","start":"2026-09-09T01:01:07.108092Z","end":"2026-09-09T01:01:07.320397Z","steps":["trace[1361987772] 'read index received' (duration: 105.825077ms)","trace[1361987772] 'applied index is now lower than readState.Index' (duration: 106.478241ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.320428Z","caller":"traceutil/trace.go:171","msg":"trace[1131173307] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"218.718813ms","start":"2026-09-09T01:01:07.101683Z","end":"2026-09-09T01:01:07.320402Z","steps":["trace[1131173307] 'process raft request' (duration: 112.208362ms)","trace[1131173307] 'compare' (duration: 106.16788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:07.320677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.708177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-75c4c745bc\" ","response":"range_response_count:1 size:4425"} {"level":"warn","ts":"2026-09-09T01:01:07.320684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.410338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-75c4c745bc-vvchv\" ","response":"range_response_count:1 size:4508"} {"level":"warn","ts":"2026-09-09T01:01:07.320681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.916288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-09-09T01:01:07.320724Z","caller":"traceutil/trace.go:171","msg":"trace[511145934] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-75c4c745bc; range_end:; response_count:1; response_revision:1089; }","duration":"228.803668ms","start":"2026-09-09T01:01:07.091907Z","end":"2026-09-09T01:01:07.32071Z","steps":["trace[511145934] 'agreement among raft nodes before linearized reading' (duration: 228.603917ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.320732Z","caller":"traceutil/trace.go:171","msg":"trace[1349262617] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-75c4c745bc-vvchv; range_end:; response_count:1; response_revision:1089; }","duration":"232.487859ms","start":"2026-09-09T01:01:07.088231Z","end":"2026-09-09T01:01:07.320718Z","steps":["trace[1349262617] 'agreement among raft nodes before linearized reading' (duration: 232.363798ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.320757Z","caller":"traceutil/trace.go:171","msg":"trace[67550732] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64; range_end:; response_count:1; response_revision:1089; }","duration":"227.022358ms","start":"2026-09-09T01:01:07.093713Z","end":"2026-09-09T01:01:07.320735Z","steps":["trace[67550732] 'agreement among raft nodes before linearized reading' (duration: 226.752967ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.326526Z","caller":"traceutil/trace.go:171","msg":"trace[1700014254] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"209.865575ms","start":"2026-09-09T01:01:07.116644Z","end":"2026-09-09T01:01:07.326509Z","steps":["trace[1700014254] 'process raft request' (duration: 209.812185ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.326847Z","caller":"traceutil/trace.go:171","msg":"trace[1647856202] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"215.758537ms","start":"2026-09-09T01:01:07.111071Z","end":"2026-09-09T01:01:07.326829Z","steps":["trace[1647856202] 'process raft request' (duration: 215.294364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.327539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.442085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:986"} {"level":"info","ts":"2026-09-09T01:01:07.327628Z","caller":"traceutil/trace.go:171","msg":"trace[1067511423] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1091; }","duration":"191.562656ms","start":"2026-09-09T01:01:07.136052Z","end":"2026-09-09T01:01:07.327614Z","steps":["trace[1067511423] 'agreement among raft nodes before linearized reading' (duration: 191.384675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:07.327816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.727477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-09-09T01:01:07.327848Z","caller":"traceutil/trace.go:171","msg":"trace[724245079] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1091; }","duration":"191.788207ms","start":"2026-09-09T01:01:07.136052Z","end":"2026-09-09T01:01:07.32784Z","steps":["trace[724245079] 'agreement among raft nodes before linearized reading' (duration: 191.696127ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.515568Z","caller":"traceutil/trace.go:171","msg":"trace[1408463074] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"109.929229ms","start":"2026-09-09T01:01:07.405603Z","end":"2026-09-09T01:01:07.515533Z","steps":["trace[1408463074] 'process raft request' (duration: 44.744404ms)","trace[1408463074] 'compare' (duration: 65.098625ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:07.516723Z","caller":"traceutil/trace.go:171","msg":"trace[379715667] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"109.582008ms","start":"2026-09-09T01:01:07.407111Z","end":"2026-09-09T01:01:07.516693Z","steps":["trace[379715667] 'process raft request' (duration: 109.235497ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.517636Z","caller":"traceutil/trace.go:171","msg":"trace[528970003] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"110.473863ms","start":"2026-09-09T01:01:07.40714Z","end":"2026-09-09T01:01:07.517614Z","steps":["trace[528970003] 'process raft request' (duration: 109.371196ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:07.518622Z","caller":"traceutil/trace.go:171","msg":"trace[851662530] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"100.907411ms","start":"2026-09-09T01:01:07.417685Z","end":"2026-09-09T01:01:07.518593Z","steps":["trace[851662530] 'process raft request' (duration: 99.540343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:08.020822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.440578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:08.021049Z","caller":"traceutil/trace.go:171","msg":"trace[1935382262] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"279.038362ms","start":"2026-09-09T01:01:07.74199Z","end":"2026-09-09T01:01:08.021028Z","steps":["trace[1935382262] 'process raft request' (duration: 278.942361ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.021095Z","caller":"traceutil/trace.go:171","msg":"trace[314130668] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"321.284612ms","start":"2026-09-09T01:01:07.699779Z","end":"2026-09-09T01:01:08.021064Z","steps":["trace[314130668] 'process raft request' (duration: 185.516052ms)","trace[314130668] 'compare' (duration: 135.229467ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:08.02131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:07.699758Z","time spent":"321.446124ms","remote":"127.0.0.1:59200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211310,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:01:08.036249Z","caller":"traceutil/trace.go:171","msg":"trace[963319645] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1141; }","duration":"225.155939ms","start":"2026-09-09T01:01:07.811069Z","end":"2026-09-09T01:01:08.036225Z","steps":["trace[963319645] 'read index received' (duration: 74.284306ms)","trace[963319645] 'applied index is now lower than readState.Index' (duration: 150.870843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:08.036392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.30307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:01:08.036422Z","caller":"traceutil/trace.go:171","msg":"trace[217692934] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1107; }","duration":"225.39742ms","start":"2026-09-09T01:01:07.811015Z","end":"2026-09-09T01:01:08.036412Z","steps":["trace[217692934] 'agreement among raft nodes before linearized reading' (duration: 225.2944ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.036518Z","caller":"traceutil/trace.go:171","msg":"trace[1345582683] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"284.319532ms","start":"2026-09-09T01:01:07.752175Z","end":"2026-09-09T01:01:08.036495Z","steps":["trace[1345582683] 'process raft request' (duration: 283.908319ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.036657Z","caller":"traceutil/trace.go:171","msg":"trace[1763137116] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"198.634843ms","start":"2026-09-09T01:01:07.838009Z","end":"2026-09-09T01:01:08.036644Z","steps":["trace[1763137116] 'process raft request' (duration: 198.179611ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.16029Z","caller":"traceutil/trace.go:171","msg":"trace[1570434827] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"110.376712ms","start":"2026-09-09T01:01:08.049889Z","end":"2026-09-09T01:01:08.160265Z","steps":["trace[1570434827] 'process raft request' (duration: 110.315661ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.160307Z","caller":"traceutil/trace.go:171","msg":"trace[1236070118] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"112.426633ms","start":"2026-09-09T01:01:08.047839Z","end":"2026-09-09T01:01:08.160266Z","steps":["trace[1236070118] 'process raft request' (duration: 111.619159ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.246183Z","caller":"traceutil/trace.go:171","msg":"trace[1948667960] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"138.015053ms","start":"2026-09-09T01:01:08.108125Z","end":"2026-09-09T01:01:08.24614Z","steps":["trace[1948667960] 'process raft request' (duration: 137.701542ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.694488Z","caller":"traceutil/trace.go:171","msg":"trace[1031879327] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"178.234683ms","start":"2026-09-09T01:01:08.516228Z","end":"2026-09-09T01:01:08.694462Z","steps":["trace[1031879327] 'read index received' (duration: 84.822043ms)","trace[1031879327] 'applied index is now lower than readState.Index' (duration: 93.41167ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:08.694632Z","caller":"traceutil/trace.go:171","msg":"trace[1392952706] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"204.652096ms","start":"2026-09-09T01:01:08.489939Z","end":"2026-09-09T01:01:08.694591Z","steps":["trace[1392952706] 'process raft request' (duration: 111.283137ms)","trace[1392952706] 'compare' (duration: 93.103948ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:08.694791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.547844ms","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-09-09T01:01:08.694833Z","caller":"traceutil/trace.go:171","msg":"trace[463733084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"178.652665ms","start":"2026-09-09T01:01:08.516169Z","end":"2026-09-09T01:01:08.694822Z","steps":["trace[463733084] 'agreement among raft nodes before linearized reading' (duration: 178.426333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:08.951828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.546377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:01:08.952104Z","caller":"traceutil/trace.go:171","msg":"trace[1368474643] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"167.325893ms","start":"2026-09-09T01:01:08.784736Z","end":"2026-09-09T01:01:08.952062Z","steps":["trace[1368474643] 'process raft request' (duration: 48.456204ms)","trace[1368474643] 'compare' (duration: 118.414006ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:08.952231Z","caller":"traceutil/trace.go:171","msg":"trace[2140881671] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"165.608484ms","start":"2026-09-09T01:01:08.786567Z","end":"2026-09-09T01:01:08.952175Z","steps":["trace[2140881671] 'process raft request' (duration: 165.511813ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.952359Z","caller":"traceutil/trace.go:171","msg":"trace[998014884] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"165.967606ms","start":"2026-09-09T01:01:08.786376Z","end":"2026-09-09T01:01:08.952344Z","steps":["trace[998014884] 'process raft request' (duration: 165.637985ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:08.952569Z","caller":"traceutil/trace.go:171","msg":"trace[1153236436] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"166.614579ms","start":"2026-09-09T01:01:08.785933Z","end":"2026-09-09T01:01:08.952547Z","steps":["trace[1153236436] 'read index received' (duration: 47.237478ms)","trace[1153236436] 'applied index is now lower than readState.Index' (duration: 119.374721ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:08.952894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.937251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-cc2gl\" ","response":"range_response_count:1 size:2916"} {"level":"info","ts":"2026-09-09T01:01:08.952979Z","caller":"traceutil/trace.go:171","msg":"trace[44621896] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-cc2gl; range_end:; response_count:1; response_revision:1122; }","duration":"167.056522ms","start":"2026-09-09T01:01:08.785899Z","end":"2026-09-09T01:01:08.952955Z","steps":["trace[44621896] 'agreement among raft nodes before linearized reading' (duration: 166.70183ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:09.530729Z","caller":"traceutil/trace.go:171","msg":"trace[420315512] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"153.922749ms","start":"2026-09-09T01:01:09.376776Z","end":"2026-09-09T01:01:09.530699Z","steps":["trace[420315512] 'process raft request' (duration: 153.825408ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:09.530802Z","caller":"traceutil/trace.go:171","msg":"trace[727700622] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"168.084566ms","start":"2026-09-09T01:01:09.362692Z","end":"2026-09-09T01:01:09.530776Z","steps":["trace[727700622] 'process raft request' (duration: 83.572946ms)","trace[727700622] 'compare' (duration: 84.115038ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:09.53075Z","caller":"traceutil/trace.go:171","msg":"trace[1192177380] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"154.793394ms","start":"2026-09-09T01:01:09.375935Z","end":"2026-09-09T01:01:09.530729Z","steps":["trace[1192177380] 'read index received' (duration: 70.325894ms)","trace[1192177380] 'applied index is now lower than readState.Index' (duration: 84.46482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:09.530909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.950575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:09.530966Z","caller":"traceutil/trace.go:171","msg":"trace[251770668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"155.056525ms","start":"2026-09-09T01:01:09.375896Z","end":"2026-09-09T01:01:09.530952Z","steps":["trace[251770668] 'agreement among raft nodes before linearized reading' (duration: 154.891894ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:15.737666Z","caller":"traceutil/trace.go:171","msg":"trace[1920361135] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"137.331128ms","start":"2026-09-09T01:01:15.600309Z","end":"2026-09-09T01:01:15.73764Z","steps":["trace[1920361135] 'process raft request' (duration: 102.7198ms)","trace[1920361135] 'compare' (duration: 34.423187ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:15.737938Z","caller":"traceutil/trace.go:171","msg":"trace[1693307182] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"102.214876ms","start":"2026-09-09T01:01:15.635637Z","end":"2026-09-09T01:01:15.737852Z","steps":["trace[1693307182] 'process raft request' (duration: 101.954135ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:17.115612Z","caller":"traceutil/trace.go:171","msg":"trace[380978884] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"111.461178ms","start":"2026-09-09T01:01:17.004114Z","end":"2026-09-09T01:01:17.115575Z","steps":["trace[380978884] 'process raft request' (duration: 111.203956ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:20.997714Z","caller":"traceutil/trace.go:171","msg":"trace[2136119652] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"111.313036ms","start":"2026-09-09T01:01:20.88637Z","end":"2026-09-09T01:01:20.997683Z","steps":["trace[2136119652] 'process raft request' (duration: 109.219164ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:27.318423Z","caller":"traceutil/trace.go:171","msg":"trace[1075553794] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"147.904724ms","start":"2026-09-09T01:01:27.170486Z","end":"2026-09-09T01:01:27.31839Z","steps":["trace[1075553794] 'read index received' (duration: 121.783582ms)","trace[1075553794] 'applied index is now lower than readState.Index' (duration: 26.119362ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:27.31843Z","caller":"traceutil/trace.go:171","msg":"trace[1086976149] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"188.798676ms","start":"2026-09-09T01:01:27.129609Z","end":"2026-09-09T01:01:27.318408Z","steps":["trace[1086976149] 'process raft request' (duration: 162.738305ms)","trace[1086976149] 'compare' (duration: 25.83677ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:27.318652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.152266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-09T01:01:27.31877Z","caller":"traceutil/trace.go:171","msg":"trace[1617910583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"148.312936ms","start":"2026-09-09T01:01:27.17044Z","end":"2026-09-09T01:01:27.318753Z","steps":["trace[1617910583] 'agreement among raft nodes before linearized reading' (duration: 148.060655ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:27.752229Z","caller":"traceutil/trace.go:171","msg":"trace[127475662] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"147.520432ms","start":"2026-09-09T01:01:27.60469Z","end":"2026-09-09T01:01:27.75221Z","steps":["trace[127475662] 'process raft request' (duration: 147.19626ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:27.752231Z","caller":"traceutil/trace.go:171","msg":"trace[90033168] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"145.539721ms","start":"2026-09-09T01:01:27.606654Z","end":"2026-09-09T01:01:27.752194Z","steps":["trace[90033168] 'process raft request' (duration: 145.4208ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:27.88822Z","caller":"traceutil/trace.go:171","msg":"trace[1053838492] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"129.683384ms","start":"2026-09-09T01:01:27.758513Z","end":"2026-09-09T01:01:27.888197Z","steps":["trace[1053838492] 'process raft request' (duration: 129.523064ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:29.940846Z","caller":"traceutil/trace.go:171","msg":"trace[1403639629] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"112.473261ms","start":"2026-09-09T01:01:29.828347Z","end":"2026-09-09T01:01:29.94082Z","steps":["trace[1403639629] 'read index received' (duration: 112.30518ms)","trace[1403639629] 'applied index is now lower than readState.Index' (duration: 166.731µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:29.940899Z","caller":"traceutil/trace.go:171","msg":"trace[2002057783] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"156.613981ms","start":"2026-09-09T01:01:29.784242Z","end":"2026-09-09T01:01:29.940856Z","steps":["trace[2002057783] 'process raft request' (duration: 156.4295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:29.941078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.673772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:29.941258Z","caller":"traceutil/trace.go:171","msg":"trace[2053372579] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1240; }","duration":"112.953453ms","start":"2026-09-09T01:01:29.828285Z","end":"2026-09-09T01:01:29.941239Z","steps":["trace[2053372579] 'agreement among raft nodes before linearized reading' (duration: 112.670992ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:30.060897Z","caller":"traceutil/trace.go:171","msg":"trace[1172882294] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"114.06302ms","start":"2026-09-09T01:01:29.946792Z","end":"2026-09-09T01:01:30.060855Z","steps":["trace[1172882294] 'process raft request' (duration: 48.621575ms)","trace[1172882294] 'compare' (duration: 65.299624ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:01:30.863095Z","caller":"traceutil/trace.go:171","msg":"trace[1350991082] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"115.164376ms","start":"2026-09-09T01:01:30.747904Z","end":"2026-09-09T01:01:30.863068Z","steps":["trace[1350991082] 'process raft request' (duration: 114.980415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.018859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.221512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T01:01:32.018943Z","caller":"traceutil/trace.go:171","msg":"trace[1858694657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"867.345672ms","start":"2026-09-09T01:01:31.151578Z","end":"2026-09-09T01:01:32.018923Z","steps":["trace[1858694657] 'range keys from in-memory index tree' (duration: 866.994681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.018982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.151562Z","time spent":"867.410982ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:01:32.019051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.771527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/cmapichecker-nm22g\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:32.019123Z","caller":"traceutil/trace.go:171","msg":"trace[1412711105] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/cmapichecker-nm22g; range_end:; response_count:0; response_revision:1242; }","duration":"260.904948ms","start":"2026-09-09T01:01:31.758203Z","end":"2026-09-09T01:01:32.019108Z","steps":["trace[1412711105] 'range keys from in-memory index tree' (duration: 260.687466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.018877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.724706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:32.019243Z","caller":"traceutil/trace.go:171","msg":"trace[2035889518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"940.175588ms","start":"2026-09-09T01:01:31.07905Z","end":"2026-09-09T01:01:32.019226Z","steps":["trace[2035889518] 'range keys from in-memory index tree' (duration: 939.573955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.019278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.079029Z","time spent":"940.240329ms","remote":"127.0.0.1:59136","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-09-09T01:01:32.019207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.016768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:01:32.019414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.850555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T01:01:32.019388Z","caller":"traceutil/trace.go:171","msg":"trace[512349173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"644.218709ms","start":"2026-09-09T01:01:31.375145Z","end":"2026-09-09T01:01:32.019364Z","steps":["trace[512349173] 'range keys from in-memory index tree' (duration: 643.933788ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:32.019516Z","caller":"traceutil/trace.go:171","msg":"trace[2092537864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"567.966696ms","start":"2026-09-09T01:01:31.451494Z","end":"2026-09-09T01:01:32.01946Z","steps":["trace[2092537864] 'range keys from in-memory index tree' (duration: 567.654305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.019534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.375123Z","time spent":"644.394631ms","remote":"127.0.0.1:59140","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-09-09T01:01:32.019675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.841918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-09T01:01:32.019609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.451477Z","time spent":"568.116966ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T01:01:32.019668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.836128ms","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-09-09T01:01:32.01981Z","caller":"traceutil/trace.go:171","msg":"trace[757182671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"933.023478ms","start":"2026-09-09T01:01:31.086758Z","end":"2026-09-09T01:01:32.019782Z","steps":["trace[757182671] 'range keys from in-memory index tree' (duration: 932.028414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.019929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.086738Z","time spent":"933.17572ms","remote":"127.0.0.1:59392","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":"info","ts":"2026-09-09T01:01:32.019723Z","caller":"traceutil/trace.go:171","msg":"trace[2144498442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1242; }","duration":"584.913688ms","start":"2026-09-09T01:01:31.434797Z","end":"2026-09-09T01:01:32.019711Z","steps":["trace[2144498442] 'range keys from in-memory index tree' (duration: 583.943013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.020117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:31.434778Z","time spent":"585.31722ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T01:01:32.71169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.202818ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:32.711827Z","caller":"traceutil/trace.go:171","msg":"trace[855219435] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1248; }","duration":"154.363058ms","start":"2026-09-09T01:01:32.557445Z","end":"2026-09-09T01:01:32.711808Z","steps":["trace[855219435] 'range keys from in-memory index tree' (duration: 154.178527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.711832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.336912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:32.711892Z","caller":"traceutil/trace.go:171","msg":"trace[502896767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"337.438373ms","start":"2026-09-09T01:01:32.374438Z","end":"2026-09-09T01:01:32.711876Z","steps":["trace[502896767] 'range keys from in-memory index tree' (duration: 337.202271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:32.71192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:32.374418Z","time spent":"337.495913ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T01:02:10.376926Z","caller":"traceutil/trace.go:171","msg":"trace[464308345] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"113.413925ms","start":"2026-09-09T01:02:10.263486Z","end":"2026-09-09T01:02:10.3769Z","steps":["trace[464308345] 'process raft request' (duration: 113.257244ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:10.377024Z","caller":"traceutil/trace.go:171","msg":"trace[2143568005] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"115.143013ms","start":"2026-09-09T01:02:10.261848Z","end":"2026-09-09T01:02:10.376991Z","steps":["trace[2143568005] 'process raft request' (duration: 74.479483ms)","trace[2143568005] 'compare' (duration: 40.237258ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:21.841796Z","caller":"traceutil/trace.go:171","msg":"trace[1610095622] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1993; }","duration":"259.836276ms","start":"2026-09-09T01:02:21.581933Z","end":"2026-09-09T01:02:21.841769Z","steps":["trace[1610095622] 'read index received' (duration: 259.507215ms)","trace[1610095622] 'applied index is now lower than readState.Index' (duration: 327.551µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:21.84189Z","caller":"traceutil/trace.go:171","msg":"trace[1985786682] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"356.209319ms","start":"2026-09-09T01:02:21.485627Z","end":"2026-09-09T01:02:21.841836Z","steps":["trace[1985786682] 'process raft request' (duration: 355.953377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:21.842086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.115628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-09T01:02:21.842093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:21.485604Z","time spent":"356.376959ms","remote":"127.0.0.1:59300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:02:21.842133Z","caller":"traceutil/trace.go:171","msg":"trace[2117171907] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1914; }","duration":"260.293069ms","start":"2026-09-09T01:02:21.581825Z","end":"2026-09-09T01:02:21.842118Z","steps":["trace[2117171907] 'agreement among raft nodes before linearized reading' (duration: 260.090928ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:22.429544Z","caller":"traceutil/trace.go:171","msg":"trace[28431393] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"123.188487ms","start":"2026-09-09T01:02:22.306327Z","end":"2026-09-09T01:02:22.429516Z","steps":["trace[28431393] 'read index received' (duration: 122.617044ms)","trace[28431393] 'applied index is now lower than readState.Index' (duration: 569.453µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:22.429767Z","caller":"traceutil/trace.go:171","msg":"trace[1176224422] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"154.223465ms","start":"2026-09-09T01:02:22.275459Z","end":"2026-09-09T01:02:22.429682Z","steps":["trace[1176224422] 'process raft request' (duration: 153.493791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:22.430026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.691079ms","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-09-09T01:02:22.430077Z","caller":"traceutil/trace.go:171","msg":"trace[754934283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1917; }","duration":"123.807621ms","start":"2026-09-09T01:02:22.306257Z","end":"2026-09-09T01:02:22.430065Z","steps":["trace[754934283] 'agreement among raft nodes before linearized reading' (duration: 123.404149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:22.43049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.225627ms","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-09-09T01:02:22.430718Z","caller":"traceutil/trace.go:171","msg":"trace[1616143149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1917; }","duration":"119.492388ms","start":"2026-09-09T01:02:22.311207Z","end":"2026-09-09T01:02:22.430699Z","steps":["trace[1616143149] 'agreement among raft nodes before linearized reading' (duration: 118.727353ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:50.728757Z","caller":"traceutil/trace.go:171","msg":"trace[158274076] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"154.662957ms","start":"2026-09-09T01:02:50.574064Z","end":"2026-09-09T01:02:50.728726Z","steps":["trace[158274076] 'process raft request' (duration: 153.660882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:57.011317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.030979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:02:57.011545Z","caller":"traceutil/trace.go:171","msg":"trace[986333991] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"202.731017ms","start":"2026-09-09T01:02:56.808795Z","end":"2026-09-09T01:02:57.011527Z","steps":["trace[986333991] 'process raft request' (duration: 202.645396ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.011883Z","caller":"traceutil/trace.go:171","msg":"trace[700891547] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"236.183008ms","start":"2026-09-09T01:02:56.775684Z","end":"2026-09-09T01:02:57.011867Z","steps":["trace[700891547] 'process raft request' (duration: 82.506536ms)","trace[700891547] 'compare' (duration: 152.607047ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:57.012014Z","caller":"traceutil/trace.go:171","msg":"trace[340965108] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"227.588212ms","start":"2026-09-09T01:02:56.784397Z","end":"2026-09-09T01:02:57.011986Z","steps":["trace[340965108] 'read index received' (duration: 73.807999ms)","trace[340965108] 'applied index is now lower than readState.Index' (duration: 153.777633ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:02:57.012325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.589059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-09T01:02:57.012328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.897906ms","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-09-09T01:02:57.012382Z","caller":"traceutil/trace.go:171","msg":"trace[130621005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2161; }","duration":"208.6983ms","start":"2026-09-09T01:02:56.803665Z","end":"2026-09-09T01:02:57.012364Z","steps":["trace[130621005] 'agreement among raft nodes before linearized reading' (duration: 208.528789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:57.012366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.224087ms","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-09-09T01:02:57.012404Z","caller":"traceutil/trace.go:171","msg":"trace[174795245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2161; }","duration":"207.980936ms","start":"2026-09-09T01:02:56.804392Z","end":"2026-09-09T01:02:57.012373Z","steps":["trace[174795245] 'agreement among raft nodes before linearized reading' (duration: 207.842575ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.012441Z","caller":"traceutil/trace.go:171","msg":"trace[1374528067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2161; }","duration":"208.311947ms","start":"2026-09-09T01:02:56.804108Z","end":"2026-09-09T01:02:57.01242Z","steps":["trace[1374528067] 'agreement among raft nodes before linearized reading' (duration: 208.083956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:57.012418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.281094ms","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-09-09T01:02:57.012534Z","caller":"traceutil/trace.go:171","msg":"trace[802457898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2161; }","duration":"226.487266ms","start":"2026-09-09T01:02:56.786023Z","end":"2026-09-09T01:02:57.01251Z","steps":["trace[802457898] 'agreement among raft nodes before linearized reading' (duration: 226.154294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:57.01254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.152445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-09T01:02:57.012356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.67876ms","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-09-09T01:02:57.01262Z","caller":"traceutil/trace.go:171","msg":"trace[397819838] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"228.209595ms","start":"2026-09-09T01:02:56.784379Z","end":"2026-09-09T01:02:57.012589Z","steps":["trace[397819838] 'agreement among raft nodes before linearized reading' (duration: 227.760083ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.012715Z","caller":"traceutil/trace.go:171","msg":"trace[1562002082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2161; }","duration":"209.032961ms","start":"2026-09-09T01:02:56.803637Z","end":"2026-09-09T01:02:57.01267Z","steps":["trace[1562002082] 'agreement among raft nodes before linearized reading' (duration: 208.580829ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.288024Z","caller":"traceutil/trace.go:171","msg":"trace[116979845] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"270.343324ms","start":"2026-09-09T01:02:57.017656Z","end":"2026-09-09T01:02:57.288Z","steps":["trace[116979845] 'process raft request' (duration: 270.065102ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.288351Z","caller":"traceutil/trace.go:171","msg":"trace[247129081] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"270.190733ms","start":"2026-09-09T01:02:57.018128Z","end":"2026-09-09T01:02:57.288319Z","steps":["trace[247129081] 'process raft request' (duration: 269.824432ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.288384Z","caller":"traceutil/trace.go:171","msg":"trace[847300839] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"269.62573ms","start":"2026-09-09T01:02:57.018737Z","end":"2026-09-09T01:02:57.288363Z","steps":["trace[847300839] 'process raft request' (duration: 269.576869ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:57.288567Z","caller":"traceutil/trace.go:171","msg":"trace[1483274876] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"269.941511ms","start":"2026-09-09T01:02:57.018609Z","end":"2026-09-09T01:02:57.288551Z","steps":["trace[1483274876] 'process raft request' (duration: 269.609089ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:01.162675Z","caller":"traceutil/trace.go:171","msg":"trace[1633716879] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"137.805127ms","start":"2026-09-09T01:03:01.024776Z","end":"2026-09-09T01:03:01.162582Z","steps":["trace[1633716879] 'process raft request' (duration: 42.490171ms)","trace[1633716879] 'compare' (duration: 94.464931ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:03:01.167003Z","caller":"traceutil/trace.go:171","msg":"trace[1579428081] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"141.481205ms","start":"2026-09-09T01:03:01.025465Z","end":"2026-09-09T01:03:01.166946Z","steps":["trace[1579428081] 'process raft request' (duration: 141.249284ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:01.167415Z","caller":"traceutil/trace.go:171","msg":"trace[1699267299] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2272; }","duration":"127.274279ms","start":"2026-09-09T01:03:01.039582Z","end":"2026-09-09T01:03:01.166856Z","steps":["trace[1699267299] 'read index received' (duration: 27.699101ms)","trace[1699267299] 'applied index is now lower than readState.Index' (duration: 99.573918ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:03:01.167632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.007844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:03:01.167722Z","caller":"traceutil/trace.go:171","msg":"trace[17279241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2185; }","duration":"128.155824ms","start":"2026-09-09T01:03:01.03953Z","end":"2026-09-09T01:03:01.167685Z","steps":["trace[17279241] 'agreement among raft nodes before linearized reading' (duration: 127.888223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:03:01.419774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.811701ms","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-09-09T01:03:01.419866Z","caller":"traceutil/trace.go:171","msg":"trace[1989103984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2189; }","duration":"110.972841ms","start":"2026-09-09T01:03:01.308871Z","end":"2026-09-09T01:03:01.419844Z","steps":["trace[1989103984] 'agreement among raft nodes before linearized reading' (duration: 110.71695ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:01.420066Z","caller":"traceutil/trace.go:171","msg":"trace[1335631781] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"111.286592ms","start":"2026-09-09T01:03:01.308753Z","end":"2026-09-09T01:03:01.42004Z","steps":["trace[1335631781] 'process raft request' (duration: 83.358931ms)","trace[1335631781] 'compare' (duration: 27.244748ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:03:19.703136Z","caller":"traceutil/trace.go:171","msg":"trace[1722381709] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"116.061358ms","start":"2026-09-09T01:03:19.587038Z","end":"2026-09-09T01:03:19.703099Z","steps":["trace[1722381709] 'process raft request' (duration: 79.739201ms)","trace[1722381709] 'compare' (duration: 36.175966ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:04:39.577357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.212004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:04:39.577467Z","caller":"traceutil/trace.go:171","msg":"trace[1248295632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2814; }","duration":"202.346715ms","start":"2026-09-09T01:04:39.375076Z","end":"2026-09-09T01:04:39.577423Z","steps":["trace[1248295632] 'range keys from in-memory index tree' (duration: 202.125114ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:39.848579Z","caller":"traceutil/trace.go:171","msg":"trace[1592753018] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"254.676949ms","start":"2026-09-09T01:04:39.593879Z","end":"2026-09-09T01:04:39.848556Z","steps":["trace[1592753018] 'process raft request' (duration: 254.544148ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:39.914703Z","caller":"traceutil/trace.go:171","msg":"trace[1266379784] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"308.51122ms","start":"2026-09-09T01:04:39.606171Z","end":"2026-09-09T01:04:39.914683Z","steps":["trace[1266379784] 'process raft request' (duration: 306.268087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:39.914869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:04:39.606144Z","time spent":"308.64671ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:04:39.915128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.459017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-09-09T01:04:39.915255Z","caller":"traceutil/trace.go:171","msg":"trace[149492264] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2816; }","duration":"228.639308ms","start":"2026-09-09T01:04:39.686596Z","end":"2026-09-09T01:04:39.915235Z","steps":["trace[149492264] 'agreement among raft nodes before linearized reading' (duration: 228.278146ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:39.915343Z","caller":"traceutil/trace.go:171","msg":"trace[187388567] linearizableReadLoop","detail":"{readStateIndex:2927; appliedIndex:2926; }","duration":"227.980185ms","start":"2026-09-09T01:04:39.686657Z","end":"2026-09-09T01:04:39.914637Z","steps":["trace[187388567] 'read index received' (duration: 161.990227ms)","trace[187388567] 'applied index is now lower than readState.Index' (duration: 65.988778ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:04:39.915643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.707586ms","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-09-09T01:04:39.915695Z","caller":"traceutil/trace.go:171","msg":"trace[1820179254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2816; }","duration":"100.796686ms","start":"2026-09-09T01:04:39.814883Z","end":"2026-09-09T01:04:39.91568Z","steps":["trace[1820179254] 'agreement among raft nodes before linearized reading' (duration: 100.659265ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.292113Z","caller":"traceutil/trace.go:171","msg":"trace[1172750720] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"121.958939ms","start":"2026-09-09T01:09:12.170116Z","end":"2026-09-09T01:09:12.292074Z","steps":["trace[1172750720] 'process raft request' (duration: 120.199831ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.303405Z","caller":"traceutil/trace.go:171","msg":"trace[66340692] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"111.792226ms","start":"2026-09-09T01:09:12.191593Z","end":"2026-09-09T01:09:12.303386Z","steps":["trace[66340692] 'process raft request' (duration: 111.677524ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.303454Z","caller":"traceutil/trace.go:171","msg":"trace[1255976729] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"116.550142ms","start":"2026-09-09T01:09:12.186875Z","end":"2026-09-09T01:09:12.303425Z","steps":["trace[1255976729] 'process raft request' (duration: 116.31252ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.303451Z","caller":"traceutil/trace.go:171","msg":"trace[1843039256] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4871; }","duration":"112.226819ms","start":"2026-09-09T01:09:12.191203Z","end":"2026-09-09T01:09:12.30343Z","steps":["trace[1843039256] 'read index received' (duration: 99.189528ms)","trace[1843039256] 'applied index is now lower than readState.Index' (duration: 13.036271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:12.303645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.42203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:3186"} {"level":"info","ts":"2026-09-09T01:09:12.303695Z","caller":"traceutil/trace.go:171","msg":"trace[585952133] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4687; }","duration":"112.51921ms","start":"2026-09-09T01:09:12.191162Z","end":"2026-09-09T01:09:12.303682Z","steps":["trace[585952133] 'agreement among raft nodes before linearized reading' (duration: 112.337089ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.303752Z","caller":"traceutil/trace.go:171","msg":"trace[720801849] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"103.345339ms","start":"2026-09-09T01:09:12.200383Z","end":"2026-09-09T01:09:12.303728Z","steps":["trace[720801849] 'process raft request' (duration: 103.011678ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.303745Z","caller":"traceutil/trace.go:171","msg":"trace[492506036] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"103.887492ms","start":"2026-09-09T01:09:12.199812Z","end":"2026-09-09T01:09:12.3037Z","steps":["trace[492506036] 'process raft request' (duration: 103.551019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:12.303812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.180311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:09:12.303993Z","caller":"traceutil/trace.go:171","msg":"trace[1119845015] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-admission; range_end:; response_count:0; response_revision:4687; }","duration":"111.408023ms","start":"2026-09-09T01:09:12.192573Z","end":"2026-09-09T01:09:12.303981Z","steps":["trace[1119845015] 'agreement among raft nodes before linearized reading' (duration: 111.171462ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:12.303826Z","caller":"traceutil/trace.go:171","msg":"trace[809030884] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"111.090972ms","start":"2026-09-09T01:09:12.192725Z","end":"2026-09-09T01:09:12.303816Z","steps":["trace[809030884] 'process raft request' (duration: 110.581818ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:20.244867Z","caller":"traceutil/trace.go:171","msg":"trace[1897690139] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"147.701768ms","start":"2026-09-09T01:09:20.097145Z","end":"2026-09-09T01:09:20.244847Z","steps":["trace[1897690139] 'read index received' (duration: 98.944345ms)","trace[1897690139] 'applied index is now lower than readState.Index' (duration: 48.756503ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:20.24497Z","caller":"traceutil/trace.go:171","msg":"trace[525949749] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"152.546146ms","start":"2026-09-09T01:09:20.0924Z","end":"2026-09-09T01:09:20.244946Z","steps":["trace[525949749] 'process raft request' (duration: 103.735092ms)","trace[525949749] 'compare' (duration: 48.531712ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:20.245062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.925569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b5fe9d7-cd5e-4a5d-b521-7170721b5b8b\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-09-09T01:09:20.245088Z","caller":"traceutil/trace.go:171","msg":"trace[1228268098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b5fe9d7-cd5e-4a5d-b521-7170721b5b8b; range_end:; response_count:1; response_revision:4903; }","duration":"147.9837ms","start":"2026-09-09T01:09:20.097095Z","end":"2026-09-09T01:09:20.245079Z","steps":["trace[1228268098] 'agreement among raft nodes before linearized reading' (duration: 147.86059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:20.245405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.613686ms","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":"info","ts":"2026-09-09T01:09:20.245473Z","caller":"traceutil/trace.go:171","msg":"trace[826862646] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4903; }","duration":"139.726676ms","start":"2026-09-09T01:09:20.10574Z","end":"2026-09-09T01:09:20.245467Z","steps":["trace[826862646] 'agreement among raft nodes before linearized reading' (duration: 139.536875ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:28.634093Z","caller":"traceutil/trace.go:171","msg":"trace[230765978] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"211.967077ms","start":"2026-09-09T01:09:28.4221Z","end":"2026-09-09T01:09:28.634067Z","steps":["trace[230765978] 'process raft request' (duration: 211.813527ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:28.640099Z","caller":"traceutil/trace.go:171","msg":"trace[1505575666] linearizableReadLoop","detail":"{readStateIndex:5287; appliedIndex:5286; }","duration":"197.405038ms","start":"2026-09-09T01:09:28.442672Z","end":"2026-09-09T01:09:28.640077Z","steps":["trace[1505575666] 'read index received' (duration: 191.816018ms)","trace[1505575666] 'applied index is now lower than readState.Index' (duration: 5.58818ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:28.640269Z","caller":"traceutil/trace.go:171","msg":"trace[1997692461] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"204.115204ms","start":"2026-09-09T01:09:28.43614Z","end":"2026-09-09T01:09:28.640256Z","steps":["trace[1997692461] 'process raft request' (duration: 203.734783ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:28.640606Z","caller":"traceutil/trace.go:171","msg":"trace[1536903175] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"114.060536ms","start":"2026-09-09T01:09:28.526535Z","end":"2026-09-09T01:09:28.640596Z","steps":["trace[1536903175] 'process raft request' (duration: 113.469253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:28.640879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.180002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0360d0e2-bfdc-432b-bc91-ddaa9c1b0fda\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-09T01:09:28.640937Z","caller":"traceutil/trace.go:171","msg":"trace[151275539] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0360d0e2-bfdc-432b-bc91-ddaa9c1b0fda; range_end:; response_count:1; response_revision:5089; }","duration":"198.280273ms","start":"2026-09-09T01:09:28.442639Z","end":"2026-09-09T01:09:28.640919Z","steps":["trace[151275539] 'agreement among raft nodes before linearized reading' (duration: 198.086722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:28.640971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.161328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-09T01:09:28.641007Z","caller":"traceutil/trace.go:171","msg":"trace[64480325] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5089; }","duration":"160.223078ms","start":"2026-09-09T01:09:28.480772Z","end":"2026-09-09T01:09:28.640995Z","steps":["trace[64480325] 'agreement among raft nodes before linearized reading' (duration: 160.122318ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:28.96976Z","caller":"traceutil/trace.go:171","msg":"trace[1128002108] linearizableReadLoop","detail":"{readStateIndex:5296; appliedIndex:5295; }","duration":"168.902534ms","start":"2026-09-09T01:09:28.800831Z","end":"2026-09-09T01:09:28.969733Z","steps":["trace[1128002108] 'read index received' (duration: 132.402497ms)","trace[1128002108] 'applied index is now lower than readState.Index' (duration: 36.498757ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:28.970101Z","caller":"traceutil/trace.go:171","msg":"trace[1783212146] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"238.135189ms","start":"2026-09-09T01:09:28.731939Z","end":"2026-09-09T01:09:28.970074Z","steps":["trace[1783212146] 'process raft request' (duration: 201.39534ms)","trace[1783212146] 'compare' (duration: 36.281597ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:28.970226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.293706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-09T01:09:28.970314Z","caller":"traceutil/trace.go:171","msg":"trace[992982682] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5097; }","duration":"169.506957ms","start":"2026-09-09T01:09:28.800789Z","end":"2026-09-09T01:09:28.970296Z","steps":["trace[992982682] 'agreement among raft nodes before linearized reading' (duration: 169.178015ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:29.220134Z","caller":"traceutil/trace.go:171","msg":"trace[377121637] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"138.59868ms","start":"2026-09-09T01:09:29.081509Z","end":"2026-09-09T01:09:29.220108Z","steps":["trace[377121637] 'process raft request' (duration: 132.621628ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:30.201158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2770} {"level":"info","ts":"2026-09-09T01:09:30.280208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2770,"took":"77.61214ms","hash":3761197778,"current-db-size-bytes":51609600,"current-db-size":"52 MB","current-db-size-in-use-bytes":27459584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:09:30.280256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3761197778,"revision":2770,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:09:40.945511Z","caller":"traceutil/trace.go:171","msg":"trace[183237143] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5448; }","duration":"257.887006ms","start":"2026-09-09T01:09:40.687596Z","end":"2026-09-09T01:09:40.945483Z","steps":["trace[183237143] 'read index received' (duration: 257.643385ms)","trace[183237143] 'applied index is now lower than readState.Index' (duration: 242.541µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:40.94567Z","caller":"traceutil/trace.go:171","msg":"trace[947353099] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"259.996427ms","start":"2026-09-09T01:09:40.685641Z","end":"2026-09-09T01:09:40.945637Z","steps":["trace[947353099] 'process raft request' (duration: 259.686395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:40.945819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.195157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-09T01:09:40.945882Z","caller":"traceutil/trace.go:171","msg":"trace[85433894] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5242; }","duration":"258.288358ms","start":"2026-09-09T01:09:40.687575Z","end":"2026-09-09T01:09:40.945863Z","steps":["trace[85433894] 'agreement among raft nodes before linearized reading' (duration: 258.066747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:40.94636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.831694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-09T01:09:40.946471Z","caller":"traceutil/trace.go:171","msg":"trace[1588569521] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5242; }","duration":"184.017105ms","start":"2026-09-09T01:09:40.762432Z","end":"2026-09-09T01:09:40.946449Z","steps":["trace[1588569521] 'agreement among raft nodes before linearized reading' (duration: 183.708783ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:41.088858Z","caller":"traceutil/trace.go:171","msg":"trace[1679637999] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"135.126261ms","start":"2026-09-09T01:09:40.953693Z","end":"2026-09-09T01:09:41.088819Z","steps":["trace[1679637999] 'process raft request' (duration: 134.799199ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:41.089572Z","caller":"traceutil/trace.go:171","msg":"trace[502281217] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"134.94196ms","start":"2026-09-09T01:09:40.954597Z","end":"2026-09-09T01:09:41.089539Z","steps":["trace[502281217] 'process raft request' (duration: 134.724139ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:41.089738Z","caller":"traceutil/trace.go:171","msg":"trace[108696024] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"135.953565ms","start":"2026-09-09T01:09:40.953771Z","end":"2026-09-09T01:09:41.089724Z","steps":["trace[108696024] 'process raft request' (duration: 134.858ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:41.089773Z","caller":"traceutil/trace.go:171","msg":"trace[403799860] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"135.146571ms","start":"2026-09-09T01:09:40.954616Z","end":"2026-09-09T01:09:41.089762Z","steps":["trace[403799860] 'process raft request' (duration: 134.740679ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:41.29949Z","caller":"traceutil/trace.go:171","msg":"trace[2041735691] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"205.29213ms","start":"2026-09-09T01:09:41.094171Z","end":"2026-09-09T01:09:41.299463Z","steps":["trace[2041735691] 'process raft request' (duration: 205.056039ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:41.299856Z","caller":"traceutil/trace.go:171","msg":"trace[51162221] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"205.433671ms","start":"2026-09-09T01:09:41.094375Z","end":"2026-09-09T01:09:41.299808Z","steps":["trace[51162221] 'process raft request' (duration: 205.038379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:17.487313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.156496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:17.487407Z","caller":"traceutil/trace.go:171","msg":"trace[2092208819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5595; }","duration":"114.300888ms","start":"2026-09-09T01:10:17.373087Z","end":"2026-09-09T01:10:17.487388Z","steps":["trace[2092208819] 'range keys from in-memory index tree' (duration: 114.027066ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:17.786594Z","caller":"traceutil/trace.go:171","msg":"trace[1198471766] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"119.99303ms","start":"2026-09-09T01:10:17.666579Z","end":"2026-09-09T01:10:17.786572Z","steps":["trace[1198471766] 'process raft request' (duration: 119.823579ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:34.224814Z","caller":"traceutil/trace.go:171","msg":"trace[1058115567] linearizableReadLoop","detail":"{readStateIndex:5937; appliedIndex:5935; }","duration":"103.589781ms","start":"2026-09-09T01:10:34.1212Z","end":"2026-09-09T01:10:34.22479Z","steps":["trace[1058115567] 'read index received' (duration: 43.366815ms)","trace[1058115567] 'applied index is now lower than readState.Index' (duration: 60.222056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:10:34.225047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.869412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:10:34.224896Z","caller":"traceutil/trace.go:171","msg":"trace[982857685] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"103.911432ms","start":"2026-09-09T01:10:34.12095Z","end":"2026-09-09T01:10:34.224862Z","steps":["trace[982857685] 'process raft request' (duration: 103.638641ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:34.225155Z","caller":"traceutil/trace.go:171","msg":"trace[1289134122] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5717; }","duration":"104.074812ms","start":"2026-09-09T01:10:34.121037Z","end":"2026-09-09T01:10:34.225112Z","steps":["trace[1289134122] 'agreement among raft nodes before linearized reading' (duration: 103.850811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:34.24779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.853602ms","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-09-09T01:10:34.247836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.74864ms","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-09-09T01:10:34.247864Z","caller":"traceutil/trace.go:171","msg":"trace[187323456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5718; }","duration":"112.968473ms","start":"2026-09-09T01:10:34.134879Z","end":"2026-09-09T01:10:34.247847Z","steps":["trace[187323456] 'agreement among raft nodes before linearized reading' (duration: 112.75056ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:34.247902Z","caller":"traceutil/trace.go:171","msg":"trace[1051482852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5718; }","duration":"110.848711ms","start":"2026-09-09T01:10:34.137038Z","end":"2026-09-09T01:10:34.247887Z","steps":["trace[1051482852] 'agreement among raft nodes before linearized reading' (duration: 110.620389ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:00.722356Z","caller":"traceutil/trace.go:171","msg":"trace[1517871810] transaction","detail":"{read_only:false; response_revision:5916; number_of_response:1; }","duration":"105.123998ms","start":"2026-09-09T01:11:00.617195Z","end":"2026-09-09T01:11:00.722319Z","steps":["trace[1517871810] 'process raft request' (duration: 84.721648ms)","trace[1517871810] 'compare' (duration: 20.009808ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:00.72246Z","caller":"traceutil/trace.go:171","msg":"trace[1656741224] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"102.194033ms","start":"2026-09-09T01:11:00.620238Z","end":"2026-09-09T01:11:00.722432Z","steps":["trace[1656741224] 'process raft request' (duration: 102.128813ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:16.186304Z","caller":"traceutil/trace.go:171","msg":"trace[1603152477] transaction","detail":"{read_only:false; response_revision:6032; number_of_response:1; }","duration":"121.265666ms","start":"2026-09-09T01:11:16.065009Z","end":"2026-09-09T01:11:16.186275Z","steps":["trace[1603152477] 'process raft request' (duration: 121.108825ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:07.596174Z","caller":"traceutil/trace.go:171","msg":"trace[1775345317] linearizableReadLoop","detail":"{readStateIndex:7050; appliedIndex:7049; }","duration":"194.02459ms","start":"2026-09-09T01:13:07.402119Z","end":"2026-09-09T01:13:07.596144Z","steps":["trace[1775345317] 'read index received' (duration: 193.751948ms)","trace[1775345317] 'applied index is now lower than readState.Index' (duration: 271.212µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:13:07.596381Z","caller":"traceutil/trace.go:171","msg":"trace[1303801395] transaction","detail":"{read_only:false; response_revision:6795; number_of_response:1; }","duration":"198.053252ms","start":"2026-09-09T01:13:07.398311Z","end":"2026-09-09T01:13:07.596365Z","steps":["trace[1303801395] 'process raft request' (duration: 197.66693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:07.596435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.77827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:13:07.596499Z","caller":"traceutil/trace.go:171","msg":"trace[91966572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6795; }","duration":"184.867321ms","start":"2026-09-09T01:13:07.411617Z","end":"2026-09-09T01:13:07.596484Z","steps":["trace[91966572] 'agreement among raft nodes before linearized reading' (duration: 184.76889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:07.596675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.540182ms","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-09-09T01:13:07.596757Z","caller":"traceutil/trace.go:171","msg":"trace[483268784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6795; }","duration":"194.668613ms","start":"2026-09-09T01:13:07.402075Z","end":"2026-09-09T01:13:07.596744Z","steps":["trace[483268784] 'agreement among raft nodes before linearized reading' (duration: 194.368541ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:07.722671Z","caller":"traceutil/trace.go:171","msg":"trace[1342717603] transaction","detail":"{read_only:false; response_revision:6796; number_of_response:1; }","duration":"121.815289ms","start":"2026-09-09T01:13:07.600829Z","end":"2026-09-09T01:13:07.722644Z","steps":["trace[1342717603] 'process raft request' (duration: 107.258901ms)","trace[1342717603] 'compare' (duration: 14.338567ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:14:30.206659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5120} {"level":"info","ts":"2026-09-09T01:14:30.277049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5120,"took":"69.151375ms","hash":3029693765,"current-db-size-bytes":51609600,"current-db-size":"52 MB","current-db-size-in-use-bytes":19333120,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-09T01:14:30.277242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3029693765,"revision":5120,"compact-revision":2770} {"level":"info","ts":"2026-09-09T01:19:30.21183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7300} {"level":"info","ts":"2026-09-09T01:19:30.270405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7300,"took":"57.439691ms","hash":2018978376,"current-db-size-bytes":51609600,"current-db-size":"52 MB","current-db-size-in-use-bytes":15659008,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-09-09T01:19:30.270457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2018978376,"revision":7300,"compact-revision":5120}