{"level":"warn","ts":"2026-08-20T01:13:19.147969Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:13:19.148064Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-20T01:13:19.148147Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:13:19.148166Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-08-20T01:13:19.148191Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-20T01:13:19.148968Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-08-20T01:13:19.149064Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-20T01:13:19.158906Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.590218ms"} {"level":"info","ts":"2026-08-20T01:13:19.166035Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-08-20T01:13:19.166102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:13:19.166126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-08-20T01:13:19.16614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:13:19.166148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-08-20T01:13:19.166186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-08-20T01:13:19.17356Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:13:19.176693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:13:19.179108Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-20T01:13:19.181244Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:13:19.181441Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:13:19.18246Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-20T01:13:19.182947Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-20T01:13:19.182961Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-20T01:13:19.183442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-08-20T01:13:19.183779Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-08-20T01:13:19.186094Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-20T01:13:19.18627Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-08-20T01:13:19.186287Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-08-20T01:13:19.186936Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:13:19.186961Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:13:19.866713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:13:19.86678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:13:19.866819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-08-20T01:13:19.866845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:13:19.866861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-08-20T01:13:19.866877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-08-20T01:13:19.866895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-08-20T01:13:19.868099Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:13:19.868145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:13:19.868146Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:19.868729Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:13:19.869463Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:19.869578Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:19.869616Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:19.869837Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:13:19.869859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:13:19.872626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"info","ts":"2026-08-20T01:13:19.872905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:13:43.055255Z","caller":"traceutil/trace.go:171","msg":"trace[213136873] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"306.542681ms","start":"2026-08-20T01:13:42.74869Z","end":"2026-08-20T01:13:43.055233Z","steps":["trace[213136873] 'read index received' (duration: 306.336579ms)","trace[213136873] 'applied index is now lower than readState.Index' (duration: 205.322µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:13:43.055333Z","caller":"traceutil/trace.go:171","msg":"trace[1995324014] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"511.493666ms","start":"2026-08-20T01:13:42.543807Z","end":"2026-08-20T01:13:43.055301Z","steps":["trace[1995324014] 'process raft request' (duration: 511.299125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.05542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.342835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-08-20T01:13:43.055477Z","caller":"traceutil/trace.go:171","msg":"trace[302626241] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:396; }","duration":"241.457546ms","start":"2026-08-20T01:13:42.814012Z","end":"2026-08-20T01:13:43.055469Z","steps":["trace[302626241] 'agreement among raft nodes before linearized reading' (duration: 241.351055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.055481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.769772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T01:13:43.05563Z","caller":"traceutil/trace.go:171","msg":"trace[307198309] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:396; }","duration":"306.886993ms","start":"2026-08-20T01:13:42.748657Z","end":"2026-08-20T01:13:43.055544Z","steps":["trace[307198309] 'agreement among raft nodes before linearized reading' (duration: 306.701791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.05563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.146488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:13:43.055703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:42.543778Z","time spent":"511.612507ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:13:43.055709Z","caller":"traceutil/trace.go:171","msg":"trace[376452596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"214.248579ms","start":"2026-08-20T01:13:42.841439Z","end":"2026-08-20T01:13:43.055688Z","steps":["trace[376452596] 'agreement among raft nodes before linearized reading' (duration: 214.133238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.055679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:42.748642Z","time spent":"307.025844ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"info","ts":"2026-08-20T01:13:43.253244Z","caller":"traceutil/trace.go:171","msg":"trace[1357561290] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"113.573749ms","start":"2026-08-20T01:13:43.13964Z","end":"2026-08-20T01:13:43.253214Z","steps":["trace[1357561290] 'process raft request' (duration: 69.206102ms)","trace[1357561290] 'compare' (duration: 44.225806ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:13:43.259477Z","caller":"traceutil/trace.go:171","msg":"trace[1086537605] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"117.533268ms","start":"2026-08-20T01:13:43.141923Z","end":"2026-08-20T01:13:43.259456Z","steps":["trace[1086537605] 'process raft request' (duration: 117.470358ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:43.259541Z","caller":"traceutil/trace.go:171","msg":"trace[719307370] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"118.130613ms","start":"2026-08-20T01:13:43.14139Z","end":"2026-08-20T01:13:43.259521Z","steps":["trace[719307370] 'process raft request' (duration: 117.896801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.482611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.048424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:13:43.48285Z","caller":"traceutil/trace.go:171","msg":"trace[1046703693] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"146.999458ms","start":"2026-08-20T01:13:43.335819Z","end":"2026-08-20T01:13:43.482818Z","steps":["trace[1046703693] 'process raft request' (duration: 36.530091ms)","trace[1046703693] 'compare' (duration: 109.915623ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:13:43.482978Z","caller":"traceutil/trace.go:171","msg":"trace[707968558] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"144.394519ms","start":"2026-08-20T01:13:43.338565Z","end":"2026-08-20T01:13:43.48296Z","steps":["trace[707968558] 'process raft request' (duration: 144.154787ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:43.483352Z","caller":"traceutil/trace.go:171","msg":"trace[1814789391] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"146.987027ms","start":"2026-08-20T01:13:43.336346Z","end":"2026-08-20T01:13:43.483333Z","steps":["trace[1814789391] 'read index received' (duration: 36.010037ms)","trace[1814789391] 'applied index is now lower than readState.Index' (duration: 110.9752ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:43.483752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.382401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-v24n4\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-08-20T01:13:43.483818Z","caller":"traceutil/trace.go:171","msg":"trace[852524311] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-v24n4; range_end:; response_count:1; response_revision:409; }","duration":"147.500302ms","start":"2026-08-20T01:13:43.336303Z","end":"2026-08-20T01:13:43.483803Z","steps":["trace[852524311] 'agreement among raft nodes before linearized reading' (duration: 147.348721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.483807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.074717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-08-20T01:13:43.483921Z","caller":"traceutil/trace.go:171","msg":"trace[816847955] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:409; }","duration":"144.205978ms","start":"2026-08-20T01:13:43.339695Z","end":"2026-08-20T01:13:43.483901Z","steps":["trace[816847955] 'agreement among raft nodes before linearized reading' (duration: 144.049287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.484344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.824776ms","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-08-20T01:13:43.484523Z","caller":"traceutil/trace.go:171","msg":"trace[2075803002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"116.023647ms","start":"2026-08-20T01:13:43.368481Z","end":"2026-08-20T01:13:43.484505Z","steps":["trace[2075803002] 'agreement among raft nodes before linearized reading' (duration: 115.805526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.846942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.056123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:13:43.84711Z","caller":"traceutil/trace.go:171","msg":"trace[1665362905] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"302.155134ms","start":"2026-08-20T01:13:43.544941Z","end":"2026-08-20T01:13:43.847096Z","steps":["trace[1665362905] 'process raft request' (duration: 302.114664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.847183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.544937Z","time spent":"302.218525ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:13:43.84726Z","caller":"traceutil/trace.go:171","msg":"trace[441533620] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"304.327629ms","start":"2026-08-20T01:13:43.542853Z","end":"2026-08-20T01:13:43.847181Z","steps":["trace[441533620] 'process raft request' (duration: 304.166658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:43.84746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.542846Z","time spent":"304.54465ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:13:43.847697Z","caller":"traceutil/trace.go:171","msg":"trace[1629967283] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"305.249576ms","start":"2026-08-20T01:13:43.541835Z","end":"2026-08-20T01:13:43.847085Z","steps":["trace[1629967283] 'process raft request' (duration: 110.995832ms)","trace[1629967283] 'compare' (duration: 193.929322ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:43.847867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.541811Z","time spent":"305.991902ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:13:44.352197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.585883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:13:44.352394Z","caller":"traceutil/trace.go:171","msg":"trace[1677067906] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"786.813881ms","start":"2026-08-20T01:13:43.565567Z","end":"2026-08-20T01:13:44.352381Z","steps":["trace[1677067906] 'process raft request' (duration: 786.72942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:44.352455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.565559Z","time spent":"786.868341ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:13:44.352466Z","caller":"traceutil/trace.go:171","msg":"trace[1723913649] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"790.319306ms","start":"2026-08-20T01:13:43.5621Z","end":"2026-08-20T01:13:44.352419Z","steps":["trace[1723913649] 'process raft request' (duration: 572.447001ms)","trace[1723913649] 'compare' (duration: 217.458982ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:44.352629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.562084Z","time spent":"790.481257ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:13:44.387645Z","caller":"traceutil/trace.go:171","msg":"trace[496099341] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"535.979144ms","start":"2026-08-20T01:13:43.851647Z","end":"2026-08-20T01:13:44.387627Z","steps":["trace[496099341] 'process raft request' (duration: 535.792752ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:44.387709Z","caller":"traceutil/trace.go:171","msg":"trace[1817659547] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"534.449462ms","start":"2026-08-20T01:13:43.853224Z","end":"2026-08-20T01:13:44.387674Z","steps":["trace[1817659547] 'process raft request' (duration: 534.325431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:44.387741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.851626Z","time spent":"536.067224ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:13:44.387833Z","caller":"traceutil/trace.go:171","msg":"trace[1544662611] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:433; }","duration":"532.885321ms","start":"2026-08-20T01:13:43.854931Z","end":"2026-08-20T01:13:44.387816Z","steps":["trace[1544662611] 'read index received' (duration: 279.628573ms)","trace[1544662611] 'applied index is now lower than readState.Index' (duration: 253.255738ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:44.387834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.853215Z","time spent":"534.547853ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:13:44.387862Z","caller":"traceutil/trace.go:171","msg":"trace[1530771315] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"533.446425ms","start":"2026-08-20T01:13:43.854409Z","end":"2026-08-20T01:13:44.387855Z","steps":["trace[1530771315] 'process raft request' (duration: 533.194944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:44.387897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.854398Z","time spent":"533.475556ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:13:44.387917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.988323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-08-20T01:13:44.387935Z","caller":"traceutil/trace.go:171","msg":"trace[2102185445] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:424; }","duration":"533.040173ms","start":"2026-08-20T01:13:43.854889Z","end":"2026-08-20T01:13:44.387929Z","steps":["trace[2102185445] 'agreement among raft nodes before linearized reading' (duration: 532.987133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:44.387952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:43.854874Z","time spent":"533.074533ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-08-20T01:13:44.429218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.083461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:13:44.429298Z","caller":"traceutil/trace.go:171","msg":"trace[566381782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"379.212302ms","start":"2026-08-20T01:13:44.050067Z","end":"2026-08-20T01:13:44.429279Z","steps":["trace[566381782] 'agreement among raft nodes before linearized reading' (duration: 379.085761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:44.429336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:44.050044Z","time spent":"379.284583ms","remote":"127.0.0.1:58804","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-08-20T01:13:47.76768Z","caller":"traceutil/trace.go:171","msg":"trace[1347753141] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"196.165341ms","start":"2026-08-20T01:13:47.571489Z","end":"2026-08-20T01:13:47.767654Z","steps":["trace[1347753141] 'read index received' (duration: 195.98144ms)","trace[1347753141] 'applied index is now lower than readState.Index' (duration: 182.411µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:13:47.767747Z","caller":"traceutil/trace.go:171","msg":"trace[513848913] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"207.665731ms","start":"2026-08-20T01:13:47.560036Z","end":"2026-08-20T01:13:47.767701Z","steps":["trace[513848913] 'process raft request' (duration: 207.456999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:47.76785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.344333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-20T01:13:47.767851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.328483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T01:13:47.767905Z","caller":"traceutil/trace.go:171","msg":"trace[316006894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"196.441973ms","start":"2026-08-20T01:13:47.57145Z","end":"2026-08-20T01:13:47.767892Z","steps":["trace[316006894] 'agreement among raft nodes before linearized reading' (duration: 196.286682ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:47.7679Z","caller":"traceutil/trace.go:171","msg":"trace[29167896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"196.421733ms","start":"2026-08-20T01:13:47.571462Z","end":"2026-08-20T01:13:47.767884Z","steps":["trace[29167896] 'agreement among raft nodes before linearized reading' (duration: 196.326902ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:47.979444Z","caller":"traceutil/trace.go:171","msg":"trace[631843239] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"207.951843ms","start":"2026-08-20T01:13:47.771464Z","end":"2026-08-20T01:13:47.979416Z","steps":["trace[631843239] 'process raft request' (duration: 207.416258ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:47.979618Z","caller":"traceutil/trace.go:171","msg":"trace[1247382787] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:450; }","duration":"139.009999ms","start":"2026-08-20T01:13:47.840598Z","end":"2026-08-20T01:13:47.979608Z","steps":["trace[1247382787] 'read index received' (duration: 138.296422ms)","trace[1247382787] 'applied index is now lower than readState.Index' (duration: 713.037µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:47.979691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.08282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:13:47.979721Z","caller":"traceutil/trace.go:171","msg":"trace[914418052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"139.14807ms","start":"2026-08-20T01:13:47.84056Z","end":"2026-08-20T01:13:47.979709Z","steps":["trace[914418052] 'agreement among raft nodes before linearized reading' (duration: 139.08538ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:47.979807Z","caller":"traceutil/trace.go:171","msg":"trace[2113047771] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"207.791732ms","start":"2026-08-20T01:13:47.771988Z","end":"2026-08-20T01:13:47.97978Z","steps":["trace[2113047771] 'process raft request' (duration: 207.580989ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:50.115001Z","caller":"traceutil/trace.go:171","msg":"trace[418955529] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"276.813858ms","start":"2026-08-20T01:13:49.838151Z","end":"2026-08-20T01:13:50.114965Z","steps":["trace[418955529] 'read index received' (duration: 276.577186ms)","trace[418955529] 'applied index is now lower than readState.Index' (duration: 232.902µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:13:50.115157Z","caller":"traceutil/trace.go:171","msg":"trace[1730756203] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"341.744047ms","start":"2026-08-20T01:13:49.773373Z","end":"2026-08-20T01:13:50.115117Z","steps":["trace[1730756203] 'process raft request' (duration: 341.409584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:50.115277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:49.773357Z","time spent":"341.829808ms","remote":"127.0.0.1:59028","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-08-20T01:13:50.115357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.201663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:13:50.115401Z","caller":"traceutil/trace.go:171","msg":"trace[1547956981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"277.258713ms","start":"2026-08-20T01:13:49.838127Z","end":"2026-08-20T01:13:50.115386Z","steps":["trace[1547956981] 'agreement among raft nodes before linearized reading' (duration: 277.207622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:50.115299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.651658ms","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-08-20T01:13:50.115539Z","caller":"traceutil/trace.go:171","msg":"trace[897545465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:440; }","duration":"130.895901ms","start":"2026-08-20T01:13:49.98463Z","end":"2026-08-20T01:13:50.115526Z","steps":["trace[897545465] 'agreement among raft nodes before linearized reading' (duration: 130.613247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:50.115652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.973121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T01:13:50.115708Z","caller":"traceutil/trace.go:171","msg":"trace[1922457754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:440; }","duration":"131.045271ms","start":"2026-08-20T01:13:49.984651Z","end":"2026-08-20T01:13:50.115696Z","steps":["trace[1922457754] 'agreement among raft nodes before linearized reading' (duration: 130.96085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:57.840774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.878793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:13:57.840882Z","caller":"traceutil/trace.go:171","msg":"trace[286983990] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"609.398279ms","start":"2026-08-20T01:13:57.231466Z","end":"2026-08-20T01:13:57.840864Z","steps":["trace[286983990] 'process raft request' (duration: 283.338944ms)","trace[286983990] 'compare' (duration: 325.724541ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:57.840941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:57.231456Z","time spent":"609.45614ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:13:57.842317Z","caller":"traceutil/trace.go:171","msg":"trace[738235476] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"601.185557ms","start":"2026-08-20T01:13:57.241113Z","end":"2026-08-20T01:13:57.842299Z","steps":["trace[738235476] 'process raft request' (duration: 601.079326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:57.842455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:57.241089Z","time spent":"601.289908ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:13:57.842563Z","caller":"traceutil/trace.go:171","msg":"trace[446055169] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"521.004024ms","start":"2026-08-20T01:13:57.321545Z","end":"2026-08-20T01:13:57.842549Z","steps":["trace[446055169] 'read index received' (duration: 193.267364ms)","trace[446055169] 'applied index is now lower than readState.Index' (duration: 327.73585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:13:57.842812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.255497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15679"} {"level":"info","ts":"2026-08-20T01:13:57.842856Z","caller":"traceutil/trace.go:171","msg":"trace[635338468] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:593; }","duration":"521.333029ms","start":"2026-08-20T01:13:57.321511Z","end":"2026-08-20T01:13:57.842844Z","steps":["trace[635338468] 'agreement among raft nodes before linearized reading' (duration: 521.180737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:57.842887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:57.321496Z","time spent":"521.38395ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15702,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-08-20T01:13:57.842637Z","caller":"traceutil/trace.go:171","msg":"trace[1245196891] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"470.824613ms","start":"2026-08-20T01:13:57.371804Z","end":"2026-08-20T01:13:57.842629Z","steps":["trace[1245196891] 'process raft request' (duration: 470.468238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:57.843274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:13:57.37178Z","time spent":"471.464133ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:13:57.847206Z","caller":"traceutil/trace.go:171","msg":"trace[1534918145] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"275.722152ms","start":"2026-08-20T01:13:57.571464Z","end":"2026-08-20T01:13:57.847187Z","steps":["trace[1534918145] 'process raft request' (duration: 275.62035ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:00.319062Z","caller":"traceutil/trace.go:171","msg":"trace[1838434301] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"131.151973ms","start":"2026-08-20T01:14:00.187881Z","end":"2026-08-20T01:14:00.319033Z","steps":["trace[1838434301] 'process raft request' (duration: 129.276414ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:00.319709Z","caller":"traceutil/trace.go:171","msg":"trace[1644141522] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"120.715486ms","start":"2026-08-20T01:14:00.198979Z","end":"2026-08-20T01:14:00.319694Z","steps":["trace[1644141522] 'read index received' (duration: 118.125894ms)","trace[1644141522] 'applied index is now lower than readState.Index' (duration: 2.588722ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:00.319817Z","caller":"traceutil/trace.go:171","msg":"trace[1420162475] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"131.294446ms","start":"2026-08-20T01:14:00.188489Z","end":"2026-08-20T01:14:00.319784Z","steps":["trace[1420162475] 'process raft request' (duration: 130.500413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:00.32003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.878115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:00.320094Z","caller":"traceutil/trace.go:171","msg":"trace[1523401254] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:618; }","duration":"105.952766ms","start":"2026-08-20T01:14:00.214124Z","end":"2026-08-20T01:14:00.320077Z","steps":["trace[1523401254] 'agreement among raft nodes before linearized reading' (duration: 105.858764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:00.320131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.705026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:00.320193Z","caller":"traceutil/trace.go:171","msg":"trace[159548157] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:618; }","duration":"112.810897ms","start":"2026-08-20T01:14:00.207362Z","end":"2026-08-20T01:14:00.320173Z","steps":["trace[159548157] 'agreement among raft nodes before linearized reading' (duration: 112.696356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:00.320196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.880771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:00.320298Z","caller":"traceutil/trace.go:171","msg":"trace[433770283] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:618; }","duration":"107.011683ms","start":"2026-08-20T01:14:00.213259Z","end":"2026-08-20T01:14:00.320271Z","steps":["trace[433770283] 'agreement among raft nodes before linearized reading' (duration: 106.791069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:00.320558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.148345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:14:00.32058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.299326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:00.320604Z","caller":"traceutil/trace.go:171","msg":"trace[1273374481] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:618; }","duration":"113.340756ms","start":"2026-08-20T01:14:00.207258Z","end":"2026-08-20T01:14:00.320599Z","steps":["trace[1273374481] 'agreement among raft nodes before linearized reading' (duration: 113.305246ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:00.320607Z","caller":"traceutil/trace.go:171","msg":"trace[2076952950] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:618; }","duration":"107.210956ms","start":"2026-08-20T01:14:00.213385Z","end":"2026-08-20T01:14:00.320596Z","steps":["trace[2076952950] 'agreement among raft nodes before linearized reading' (duration: 107.133435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:00.320826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.97786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-20T01:14:00.320866Z","caller":"traceutil/trace.go:171","msg":"trace[256144747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:618; }","duration":"121.879364ms","start":"2026-08-20T01:14:00.198967Z","end":"2026-08-20T01:14:00.320846Z","steps":["trace[256144747] 'agreement among raft nodes before linearized reading' (duration: 120.868748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:00.320992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.679702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:00.321048Z","caller":"traceutil/trace.go:171","msg":"trace[1882719107] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:618; }","duration":"113.792693ms","start":"2026-08-20T01:14:00.207242Z","end":"2026-08-20T01:14:00.321035Z","steps":["trace[1882719107] 'agreement among raft nodes before linearized reading' (duration: 113.5968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.6735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.197551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:04.67357Z","caller":"traceutil/trace.go:171","msg":"trace[1988517920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"834.300434ms","start":"2026-08-20T01:14:03.839252Z","end":"2026-08-20T01:14:04.673553Z","steps":["trace[1988517920] 'range keys from in-memory index tree' (duration: 834.13289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.673596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.839236Z","time spent":"834.353574ms","remote":"127.0.0.1:58804","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-08-20T01:14:04.701655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335253572141999,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T01:14:04.739817Z","caller":"traceutil/trace.go:171","msg":"trace[868492939] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"538.265207ms","start":"2026-08-20T01:14:04.201529Z","end":"2026-08-20T01:14:04.739795Z","steps":["trace[868492939] 'read index received' (duration: 537.245019ms)","trace[868492939] 'applied index is now lower than readState.Index' (duration: 1.019278ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:04.740631Z","caller":"traceutil/trace.go:171","msg":"trace[1555514499] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"820.115024ms","start":"2026-08-20T01:14:03.9205Z","end":"2026-08-20T01:14:04.740615Z","steps":["trace[1555514499] 'process raft request' (duration: 818.713308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.740775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:03.92048Z","time spent":"820.189175ms","remote":"127.0.0.1:58874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:04.741078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.305913ms","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-08-20T01:14:04.741135Z","caller":"traceutil/trace.go:171","msg":"trace[281430612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"411.385465ms","start":"2026-08-20T01:14:04.329735Z","end":"2026-08-20T01:14:04.741121Z","steps":["trace[281430612] 'agreement among raft nodes before linearized reading' (duration: 411.233922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.741167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:04.329718Z","time spent":"411.442686ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:04.741563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.014979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-20T01:14:04.741633Z","caller":"traceutil/trace.go:171","msg":"trace[921856575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"540.121722ms","start":"2026-08-20T01:14:04.201494Z","end":"2026-08-20T01:14:04.741616Z","steps":["trace[921856575] 'agreement among raft nodes before linearized reading' (duration: 539.882647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.741673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:04.201481Z","time spent":"540.183632ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:04.741681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.533489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:14:04.741725Z","caller":"traceutil/trace.go:171","msg":"trace[303872276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:644; }","duration":"400.6023ms","start":"2026-08-20T01:14:04.34111Z","end":"2026-08-20T01:14:04.741712Z","steps":["trace[303872276] 'agreement among raft nodes before linearized reading' (duration: 400.478538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.741759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:04.341097Z","time spent":"400.653671ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T01:14:04.741835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.347069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T01:14:04.741865Z","caller":"traceutil/trace.go:171","msg":"trace[1539050650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"403.416781ms","start":"2026-08-20T01:14:04.338439Z","end":"2026-08-20T01:14:04.741856Z","steps":["trace[1539050650] 'agreement among raft nodes before linearized reading' (duration: 403.333189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.741897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:04.338418Z","time spent":"403.473281ms","remote":"127.0.0.1:59028","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-08-20T01:14:04.742023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.791615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mgb27\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-20T01:14:04.742045Z","caller":"traceutil/trace.go:171","msg":"trace[335193198] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mgb27; range_end:; response_count:1; response_revision:644; }","duration":"284.832866ms","start":"2026-08-20T01:14:04.457206Z","end":"2026-08-20T01:14:04.742038Z","steps":["trace[335193198] 'agreement among raft nodes before linearized reading' (duration: 284.759915ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:04.962661Z","caller":"traceutil/trace.go:171","msg":"trace[974757749] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"210.393757ms","start":"2026-08-20T01:14:04.752246Z","end":"2026-08-20T01:14:04.962639Z","steps":["trace[974757749] 'process raft request' (duration: 210.169553ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:04.962895Z","caller":"traceutil/trace.go:171","msg":"trace[880122124] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"209.255847ms","start":"2026-08-20T01:14:04.753623Z","end":"2026-08-20T01:14:04.962879Z","steps":["trace[880122124] 'process raft request' (duration: 208.977692ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:04.963114Z","caller":"traceutil/trace.go:171","msg":"trace[210823088] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"207.662878ms","start":"2026-08-20T01:14:04.75544Z","end":"2026-08-20T01:14:04.963103Z","steps":["trace[210823088] 'process raft request' (duration: 207.376333ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:04.963157Z","caller":"traceutil/trace.go:171","msg":"trace[1500486184] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"204.222436ms","start":"2026-08-20T01:14:04.758889Z","end":"2026-08-20T01:14:04.963111Z","steps":["trace[1500486184] 'process raft request' (duration: 204.144404ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:04.990672Z","caller":"traceutil/trace.go:171","msg":"trace[913954557] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"149.819607ms","start":"2026-08-20T01:14:04.840829Z","end":"2026-08-20T01:14:04.990649Z","steps":["trace[913954557] 'read index received' (duration: 122.267321ms)","trace[913954557] 'applied index is now lower than readState.Index' (duration: 27.551426ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:04.990807Z","caller":"traceutil/trace.go:171","msg":"trace[1485492609] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"195.283515ms","start":"2026-08-20T01:14:04.795495Z","end":"2026-08-20T01:14:04.990778Z","steps":["trace[1485492609] 'process raft request' (duration: 194.98961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:04.991073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.237294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:04.991148Z","caller":"traceutil/trace.go:171","msg":"trace[115274872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"150.334086ms","start":"2026-08-20T01:14:04.840776Z","end":"2026-08-20T01:14:04.99111Z","steps":["trace[115274872] 'agreement among raft nodes before linearized reading' (duration: 149.961349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:05.425605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.608451ms","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-08-20T01:14:05.425702Z","caller":"traceutil/trace.go:171","msg":"trace[1316521179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"223.745334ms","start":"2026-08-20T01:14:05.201938Z","end":"2026-08-20T01:14:05.425683Z","steps":["trace[1316521179] 'range keys from in-memory index tree' (duration: 223.465399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:06.56912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.324006ms","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-08-20T01:14:06.569272Z","caller":"traceutil/trace.go:171","msg":"trace[2141908306] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:653; }","duration":"499.487549ms","start":"2026-08-20T01:14:06.069759Z","end":"2026-08-20T01:14:06.569246Z","steps":["trace[2141908306] 'range keys from in-memory index tree' (duration: 499.301476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:06.569645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.172167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:06.569752Z","caller":"traceutil/trace.go:171","msg":"trace[1362316355] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"730.348043ms","start":"2026-08-20T01:14:05.839385Z","end":"2026-08-20T01:14:06.569733Z","steps":["trace[1362316355] 'read index received' (duration: 321.918491ms)","trace[1362316355] 'applied index is now lower than readState.Index' (duration: 408.428142ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:06.569794Z","caller":"traceutil/trace.go:171","msg":"trace[233379920] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.06384621s","start":"2026-08-20T01:14:05.50592Z","end":"2026-08-20T01:14:06.569766Z","steps":["trace[233379920] 'process raft request' (duration: 655.476829ms)","trace[233379920] 'compare' (duration: 407.772119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:06.569852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.461075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:06.569889Z","caller":"traceutil/trace.go:171","msg":"trace[40487723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"730.525357ms","start":"2026-08-20T01:14:05.839353Z","end":"2026-08-20T01:14:06.569879Z","steps":["trace[40487723] 'agreement among raft nodes before linearized reading' (duration: 730.436125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:06.569926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:05.839337Z","time spent":"730.582288ms","remote":"127.0.0.1:58806","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-08-20T01:14:06.569924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:05.505893Z","time spent":"1.063971533s","remote":"127.0.0.1:59140","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-08-20T01:14:22.101451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335253572142239,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:14:22.558862Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004145255s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T01:14:22.559248Z","caller":"traceutil/trace.go:171","msg":"trace[1718415468] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"958.546978ms","start":"2026-08-20T01:14:21.600681Z","end":"2026-08-20T01:14:22.559228Z","steps":["trace[1718415468] 'read index received' (duration: 958.343153ms)","trace[1718415468] 'applied index is now lower than readState.Index' (duration: 202.915µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:22.559329Z","caller":"traceutil/trace.go:171","msg":"trace[1578846774] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.004658038s","start":"2026-08-20T01:14:21.554636Z","end":"2026-08-20T01:14:22.559294Z","steps":["trace[1578846774] 'process raft request' (duration: 1.004439403s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:22.559435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.741803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T01:14:22.559469Z","caller":"traceutil/trace.go:171","msg":"trace[883212592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"958.819775ms","start":"2026-08-20T01:14:21.600638Z","end":"2026-08-20T01:14:22.559457Z","steps":["trace[883212592] 'agreement among raft nodes before linearized reading' (duration: 958.679342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:22.55951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:21.600626Z","time spent":"958.875676ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T01:14:22.559499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:21.55462Z","time spent":"1.004796471s","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:22.559499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.605996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:22.559624Z","caller":"traceutil/trace.go:171","msg":"trace[1468562269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"718.761382ms","start":"2026-08-20T01:14:21.84084Z","end":"2026-08-20T01:14:22.559601Z","steps":["trace[1468562269] 'agreement among raft nodes before linearized reading' (duration: 718.532365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:22.55968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:21.840825Z","time spent":"718.845883ms","remote":"127.0.0.1:58804","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-08-20T01:14:22.674011Z","caller":"traceutil/trace.go:171","msg":"trace[1574236828] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"109.92866ms","start":"2026-08-20T01:14:22.564059Z","end":"2026-08-20T01:14:22.673987Z","steps":["trace[1574236828] 'process raft request' (duration: 109.791517ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:36.722898Z","caller":"traceutil/trace.go:171","msg":"trace[1278497136] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"100.802851ms","start":"2026-08-20T01:14:36.622077Z","end":"2026-08-20T01:14:36.72288Z","steps":["trace[1278497136] 'process raft request' (duration: 100.696428ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:37.104001Z","caller":"traceutil/trace.go:171","msg":"trace[1375016331] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"118.8718ms","start":"2026-08-20T01:14:36.985108Z","end":"2026-08-20T01:14:37.10398Z","steps":["trace[1375016331] 'process raft request' (duration: 118.762437ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:37.42757Z","caller":"traceutil/trace.go:171","msg":"trace[82119825] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"137.359315ms","start":"2026-08-20T01:14:37.290181Z","end":"2026-08-20T01:14:37.427541Z","steps":["trace[82119825] 'process raft request' (duration: 137.292923ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:37.427845Z","caller":"traceutil/trace.go:171","msg":"trace[1313947246] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"137.736527ms","start":"2026-08-20T01:14:37.290092Z","end":"2026-08-20T01:14:37.427828Z","steps":["trace[1313947246] 'process raft request' (duration: 137.283173ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:44.867196Z","caller":"traceutil/trace.go:171","msg":"trace[426642621] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"108.788898ms","start":"2026-08-20T01:14:44.758325Z","end":"2026-08-20T01:14:44.867114Z","steps":["trace[426642621] 'process raft request' (duration: 108.57772ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:44.867246Z","caller":"traceutil/trace.go:171","msg":"trace[957533550] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"109.544943ms","start":"2026-08-20T01:14:44.75766Z","end":"2026-08-20T01:14:44.867205Z","steps":["trace[957533550] 'process raft request' (duration: 108.758388ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:44.867703Z","caller":"traceutil/trace.go:171","msg":"trace[906436903] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"108.407526ms","start":"2026-08-20T01:14:44.75928Z","end":"2026-08-20T01:14:44.867687Z","steps":["trace[906436903] 'process raft request' (duration: 107.727475ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:45.847771Z","caller":"traceutil/trace.go:171","msg":"trace[1221045845] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"128.57778ms","start":"2026-08-20T01:14:45.719169Z","end":"2026-08-20T01:14:45.847747Z","steps":["trace[1221045845] 'process raft request' (duration: 39.76087ms)","trace[1221045845] 'compare' (duration: 88.49367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:53.513961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.948636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T01:14:53.514096Z","caller":"traceutil/trace.go:171","msg":"trace[450376325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1179; }","duration":"216.136462ms","start":"2026-08-20T01:14:53.297939Z","end":"2026-08-20T01:14:53.514076Z","steps":["trace[450376325] 'range keys from in-memory index tree' (duration: 215.639556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:53.51416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.063613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:14:53.514235Z","caller":"traceutil/trace.go:171","msg":"trace[1665109846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1179; }","duration":"121.208398ms","start":"2026-08-20T01:14:53.393003Z","end":"2026-08-20T01:14:53.514211Z","steps":["trace[1665109846] 'range keys from in-memory index tree' (duration: 120.885898ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:53.808439Z","caller":"traceutil/trace.go:171","msg":"trace[774826394] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"289.783526ms","start":"2026-08-20T01:14:53.518627Z","end":"2026-08-20T01:14:53.808411Z","steps":["trace[774826394] 'process raft request' (duration: 289.585399ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:53.82317Z","caller":"traceutil/trace.go:171","msg":"trace[290736672] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"304.426889ms","start":"2026-08-20T01:14:53.518722Z","end":"2026-08-20T01:14:53.823149Z","steps":["trace[290736672] 'process raft request' (duration: 304.170999ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:53.823255Z","caller":"traceutil/trace.go:171","msg":"trace[2118893007] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1213; }","duration":"288.461783ms","start":"2026-08-20T01:14:53.534776Z","end":"2026-08-20T01:14:53.823238Z","steps":["trace[2118893007] 'read index received' (duration: 273.435707ms)","trace[2118893007] 'applied index is now lower than readState.Index' (duration: 15.025166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:53.823309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:53.518704Z","time spent":"304.529262ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:53.823351Z","caller":"traceutil/trace.go:171","msg":"trace[397302418] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"176.490298ms","start":"2026-08-20T01:14:53.646852Z","end":"2026-08-20T01:14:53.823343Z","steps":["trace[397302418] 'process raft request' (duration: 176.2334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:53.823553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.549383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:14:53.823624Z","caller":"traceutil/trace.go:171","msg":"trace[1535322680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"287.684027ms","start":"2026-08-20T01:14:53.535924Z","end":"2026-08-20T01:14:53.823608Z","steps":["trace[1535322680] 'agreement among raft nodes before linearized reading' (duration: 287.451559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:53.823619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.826044ms","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-08-20T01:14:53.824044Z","caller":"traceutil/trace.go:171","msg":"trace[998940031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"289.293709ms","start":"2026-08-20T01:14:53.534729Z","end":"2026-08-20T01:14:53.824022Z","steps":["trace[998940031] 'agreement among raft nodes before linearized reading' (duration: 288.684199ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:10.128406Z","caller":"traceutil/trace.go:171","msg":"trace[741121947] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"213.66813ms","start":"2026-08-20T01:15:09.914711Z","end":"2026-08-20T01:15:10.12838Z","steps":["trace[741121947] 'process raft request' (duration: 213.384699ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:10.129037Z","caller":"traceutil/trace.go:171","msg":"trace[1385782565] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"212.365004ms","start":"2026-08-20T01:15:09.916653Z","end":"2026-08-20T01:15:10.129018Z","steps":["trace[1385782565] 'process raft request' (duration: 212.23879ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:16.023766Z","caller":"traceutil/trace.go:171","msg":"trace[1536768870] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"208.240476ms","start":"2026-08-20T01:15:15.815495Z","end":"2026-08-20T01:15:16.023736Z","steps":["trace[1536768870] 'process raft request' (duration: 208.021678ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:16.024246Z","caller":"traceutil/trace.go:171","msg":"trace[1188725931] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1479; }","duration":"184.767995ms","start":"2026-08-20T01:15:15.839452Z","end":"2026-08-20T01:15:16.02422Z","steps":["trace[1188725931] 'read index received' (duration: 184.758024ms)","trace[1188725931] 'applied index is now lower than readState.Index' (duration: 7.951µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:15:16.024366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.893231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:15:16.024398Z","caller":"traceutil/trace.go:171","msg":"trace[845342403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1437; }","duration":"184.963443ms","start":"2026-08-20T01:15:15.839425Z","end":"2026-08-20T01:15:16.024388Z","steps":["trace[845342403] 'agreement among raft nodes before linearized reading' (duration: 184.89108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:16.025291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.157641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-20T01:15:16.025358Z","caller":"traceutil/trace.go:171","msg":"trace[167310330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1437; }","duration":"107.272244ms","start":"2026-08-20T01:15:15.918069Z","end":"2026-08-20T01:15:16.025341Z","steps":["trace[167310330] 'agreement among raft nodes before linearized reading' (duration: 106.918192ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:20.300207Z","caller":"traceutil/trace.go:171","msg":"trace[730535057] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"110.903092ms","start":"2026-08-20T01:15:20.189244Z","end":"2026-08-20T01:15:20.300147Z","steps":["trace[730535057] 'process raft request' (duration: 110.532668ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:20.300742Z","caller":"traceutil/trace.go:171","msg":"trace[1707241311] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"109.947785ms","start":"2026-08-20T01:15:20.190772Z","end":"2026-08-20T01:15:20.300719Z","steps":["trace[1707241311] 'process raft request' (duration: 109.861592ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:26.551345Z","caller":"traceutil/trace.go:171","msg":"trace[1115617964] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"119.267523ms","start":"2026-08-20T01:15:26.432042Z","end":"2026-08-20T01:15:26.551309Z","steps":["trace[1115617964] 'process raft request' (duration: 119.215081ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:26.551806Z","caller":"traceutil/trace.go:171","msg":"trace[152507182] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"120.835473ms","start":"2026-08-20T01:15:26.430953Z","end":"2026-08-20T01:15:26.551788Z","steps":["trace[152507182] 'process raft request' (duration: 97.794865ms)","trace[152507182] 'compare' (duration: 22.29179ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:45.138293Z","caller":"traceutil/trace.go:171","msg":"trace[1933083960] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"149.709607ms","start":"2026-08-20T01:15:44.98855Z","end":"2026-08-20T01:15:45.138259Z","steps":["trace[1933083960] 'read index received' (duration: 149.484058ms)","trace[1933083960] 'applied index is now lower than readState.Index' (duration: 223.889µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:45.138384Z","caller":"traceutil/trace.go:171","msg":"trace[1838088148] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"160.047591ms","start":"2026-08-20T01:15:44.978294Z","end":"2026-08-20T01:15:45.138341Z","steps":["trace[1838088148] 'process raft request' (duration: 159.612644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:45.138593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.011929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-20T01:15:45.138642Z","caller":"traceutil/trace.go:171","msg":"trace[1698207194] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29; range_end:; response_count:1; response_revision:1872; }","duration":"150.131694ms","start":"2026-08-20T01:15:44.988495Z","end":"2026-08-20T01:15:45.138626Z","steps":["trace[1698207194] 'agreement among raft nodes before linearized reading' (duration: 149.927976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:45.6388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381335253572144402,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:15:46.126391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.426464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:15:46.12769Z","caller":"traceutil/trace.go:171","msg":"trace[1860589382] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"1.113742649s","start":"2026-08-20T01:15:45.013887Z","end":"2026-08-20T01:15:46.12763Z","steps":["trace[1860589382] 'process raft request' (duration: 749.978762ms)","trace[1860589382] 'compare' (duration: 362.319049ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:46.128012Z","caller":"traceutil/trace.go:171","msg":"trace[202577148] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"1.110602173s","start":"2026-08-20T01:15:45.017335Z","end":"2026-08-20T01:15:46.127937Z","steps":["trace[202577148] 'process raft request' (duration: 1.109154805s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.128405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.0173Z","time spent":"1.110969079s","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:15:46.1284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.013868Z","time spent":"1.11427075s","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:15:46.476317Z","caller":"traceutil/trace.go:171","msg":"trace[1828900607] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"1.328331349s","start":"2026-08-20T01:15:45.147967Z","end":"2026-08-20T01:15:46.476298Z","steps":["trace[1828900607] 'process raft request' (duration: 1.328246815s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.476436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.147954Z","time spent":"1.328418513s","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:15:46.47668Z","caller":"traceutil/trace.go:171","msg":"trace[184208728] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"1.328679433s","start":"2026-08-20T01:15:45.147951Z","end":"2026-08-20T01:15:46.47663Z","steps":["trace[184208728] 'process raft request' (duration: 1.255086158s)","trace[184208728] 'compare' (duration: 73.045873ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:15:46.476913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.147924Z","time spent":"1.3288671s","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:15:46.476906Z","caller":"traceutil/trace.go:171","msg":"trace[150732667] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1943; }","duration":"1.338494387s","start":"2026-08-20T01:15:45.138385Z","end":"2026-08-20T01:15:46.476879Z","steps":["trace[150732667] 'read index received' (duration: 625.569317ms)","trace[150732667] 'applied index is now lower than readState.Index' (duration: 712.92369ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:46.477Z","caller":"traceutil/trace.go:171","msg":"trace[2104221118] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"1.328908542s","start":"2026-08-20T01:15:45.148075Z","end":"2026-08-20T01:15:46.476984Z","steps":["trace[2104221118] 'process raft request' (duration: 1.328535947s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.477039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.148049Z","time spent":"1.328970344s","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:15:46.477101Z","caller":"traceutil/trace.go:171","msg":"trace[1799458372] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"1.329049338s","start":"2026-08-20T01:15:45.148046Z","end":"2026-08-20T01:15:46.477096Z","steps":["trace[1799458372] 'process raft request' (duration: 1.328218034s)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:46.477114Z","caller":"traceutil/trace.go:171","msg":"trace[364614541] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"1.178543288s","start":"2026-08-20T01:15:45.298557Z","end":"2026-08-20T01:15:46.4771Z","steps":["trace[364614541] 'process raft request' (duration: 1.178280257s)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:46.477182Z","caller":"traceutil/trace.go:171","msg":"trace[551993021] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"1.234951248s","start":"2026-08-20T01:15:45.242224Z","end":"2026-08-20T01:15:46.477175Z","steps":["trace[551993021] 'process raft request' (duration: 1.234561562s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.477174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.298534Z","time spent":"1.178608679s","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:15:46.477212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.242196Z","time spent":"1.23500032s","remote":"127.0.0.1:59028","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-08-20T01:15:46.477124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.148039Z","time spent":"1.329071249s","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:15:46.477338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380527391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-20T01:15:46.477363Z","caller":"traceutil/trace.go:171","msg":"trace[1396041785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1880; }","duration":"1.380601795s","start":"2026-08-20T01:15:45.096749Z","end":"2026-08-20T01:15:46.477351Z","steps":["trace[1396041785] 'agreement among raft nodes before linearized reading' (duration: 1.380509161s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.477378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.096724Z","time spent":"1.380650487s","remote":"127.0.0.1:53752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-20T01:15:46.508725Z","caller":"traceutil/trace.go:171","msg":"trace[537260661] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"214.335027ms","start":"2026-08-20T01:15:46.294352Z","end":"2026-08-20T01:15:46.508687Z","steps":["trace[537260661] 'process raft request' (duration: 214.147959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.508946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361074414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-20T01:15:46.509028Z","caller":"traceutil/trace.go:171","msg":"trace[196673663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29; range_end:; response_count:1; response_revision:1881; }","duration":"1.361214829s","start":"2026-08-20T01:15:45.147794Z","end":"2026-08-20T01:15:46.509009Z","steps":["trace[196673663] 'agreement among raft nodes before linearized reading' (duration: 1.360888036s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.509078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.147777Z","time spent":"1.361291552s","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29\" "} {"level":"warn","ts":"2026-08-20T01:15:46.509262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.956047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-20T01:15:46.509313Z","caller":"traceutil/trace.go:171","msg":"trace[579982871] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29; range_end:; response_count:1; response_revision:1881; }","duration":"365.056281ms","start":"2026-08-20T01:15:46.144244Z","end":"2026-08-20T01:15:46.5093Z","steps":["trace[579982871] 'agreement among raft nodes before linearized reading' (duration: 364.848122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.509353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:46.144224Z","time spent":"365.119634ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49259a46-707f-4f21-bafb-82e004864e29\" "} {"level":"warn","ts":"2026-08-20T01:15:46.509398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.532514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:15:46.509436Z","caller":"traceutil/trace.go:171","msg":"trace[2012330916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"668.584326ms","start":"2026-08-20T01:15:45.840842Z","end":"2026-08-20T01:15:46.509427Z","steps":["trace[2012330916] 'agreement among raft nodes before linearized reading' (duration: 668.536984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.509469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.840832Z","time spent":"668.631658ms","remote":"127.0.0.1:58804","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-08-20T01:15:46.509567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.356582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:15:46.509621Z","caller":"traceutil/trace.go:171","msg":"trace[757895250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1881; }","duration":"231.469216ms","start":"2026-08-20T01:15:46.278142Z","end":"2026-08-20T01:15:46.509611Z","steps":["trace[757895250] 'agreement among raft nodes before linearized reading' (duration: 231.352671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.509643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.134582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:15:46.5097Z","caller":"traceutil/trace.go:171","msg":"trace[183207850] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1881; }","duration":"726.209035ms","start":"2026-08-20T01:15:45.78347Z","end":"2026-08-20T01:15:46.509679Z","steps":["trace[183207850] 'agreement among raft nodes before linearized reading' (duration: 726.126562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.50974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.783454Z","time spent":"726.276768ms","remote":"127.0.0.1:33992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":16,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:15:46.50982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.926786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-20T01:15:46.509855Z","caller":"traceutil/trace.go:171","msg":"trace[1676944039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1881; }","duration":"243.02297ms","start":"2026-08-20T01:15:46.266824Z","end":"2026-08-20T01:15:46.509846Z","steps":["trace[1676944039] 'agreement among raft nodes before linearized reading' (duration: 242.923146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.509569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174962187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T01:15:46.509976Z","caller":"traceutil/trace.go:171","msg":"trace[76299632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1881; }","duration":"1.175457007s","start":"2026-08-20T01:15:45.334509Z","end":"2026-08-20T01:15:46.509966Z","steps":["trace[76299632] 'agreement among raft nodes before linearized reading' (duration: 1.174973087s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.509969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.750387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-20T01:15:46.510014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.334476Z","time spent":"1.175529449s","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T01:15:46.509356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.821054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:15:46.510036Z","caller":"traceutil/trace.go:171","msg":"trace[2017553380] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1881; }","duration":"360.833511ms","start":"2026-08-20T01:15:46.149175Z","end":"2026-08-20T01:15:46.510008Z","steps":["trace[2017553380] 'agreement among raft nodes before linearized reading' (duration: 360.673775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.510086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:46.149154Z","time spent":"360.924684ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-20T01:15:46.510147Z","caller":"traceutil/trace.go:171","msg":"trace[2074603734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1881; }","duration":"645.573834ms","start":"2026-08-20T01:15:45.864496Z","end":"2026-08-20T01:15:46.51007Z","steps":["trace[2074603734] 'agreement among raft nodes before linearized reading' (duration: 644.823634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.510242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:45.864486Z","time spent":"645.726951ms","remote":"127.0.0.1:33792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-20T01:15:46.79311Z","caller":"traceutil/trace.go:171","msg":"trace[63952989] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"115.957368ms","start":"2026-08-20T01:15:46.677127Z","end":"2026-08-20T01:15:46.793085Z","steps":["trace[63952989] 'read index received' (duration: 115.708058ms)","trace[63952989] 'applied index is now lower than readState.Index' (duration: 248.26µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:46.79319Z","caller":"traceutil/trace.go:171","msg":"trace[1494252668] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"272.382208ms","start":"2026-08-20T01:15:46.520763Z","end":"2026-08-20T01:15:46.793145Z","steps":["trace[1494252668] 'process raft request' (duration: 272.158209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:46.793611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.464447ms","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-08-20T01:15:46.793699Z","caller":"traceutil/trace.go:171","msg":"trace[412828926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"116.608523ms","start":"2026-08-20T01:15:46.677069Z","end":"2026-08-20T01:15:46.793677Z","steps":["trace[412828926] 'agreement among raft nodes before linearized reading' (duration: 116.114124ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:47.338132Z","caller":"traceutil/trace.go:171","msg":"trace[1160233211] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"537.681932ms","start":"2026-08-20T01:15:46.800427Z","end":"2026-08-20T01:15:47.338109Z","steps":["trace[1160233211] 'process raft request' (duration: 537.419962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:47.338256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:46.800403Z","time spent":"537.773466ms","remote":"127.0.0.1:59028","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-08-20T01:15:47.338274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.024699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:15:47.338314Z","caller":"traceutil/trace.go:171","msg":"trace[721688088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"497.106912ms","start":"2026-08-20T01:15:46.841194Z","end":"2026-08-20T01:15:47.338301Z","steps":["trace[721688088] 'agreement among raft nodes before linearized reading' (duration: 497.015258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:47.338337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:46.841179Z","time spent":"497.153154ms","remote":"127.0.0.1:58804","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-08-20T01:15:47.33803Z","caller":"traceutil/trace.go:171","msg":"trace[430776327] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"496.764889ms","start":"2026-08-20T01:15:46.84124Z","end":"2026-08-20T01:15:47.338005Z","steps":["trace[430776327] 'read index received' (duration: 496.661455ms)","trace[430776327] 'applied index is now lower than readState.Index' (duration: 102.474µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:47.463026Z","caller":"traceutil/trace.go:171","msg":"trace[837042606] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"142.038737ms","start":"2026-08-20T01:15:47.320941Z","end":"2026-08-20T01:15:47.462979Z","steps":["trace[837042606] 'process raft request' (duration: 133.552256ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:02.697566Z","caller":"traceutil/trace.go:171","msg":"trace[2120496021] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2086; }","duration":"109.273064ms","start":"2026-08-20T01:16:02.588267Z","end":"2026-08-20T01:16:02.69754Z","steps":["trace[2120496021] 'read index received' (duration: 56.604174ms)","trace[2120496021] 'applied index is now lower than readState.Index' (duration: 52.66787ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:02.698015Z","caller":"traceutil/trace.go:171","msg":"trace[332847653] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"142.684547ms","start":"2026-08-20T01:16:02.555317Z","end":"2026-08-20T01:16:02.698002Z","steps":["trace[332847653] 'process raft request' (duration: 142.005609ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:02.698308Z","caller":"traceutil/trace.go:171","msg":"trace[375508866] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"137.313695ms","start":"2026-08-20T01:16:02.56098Z","end":"2026-08-20T01:16:02.698294Z","steps":["trace[375508866] 'process raft request' (duration: 136.519722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:02.698574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.278735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T01:16:02.698665Z","caller":"traceutil/trace.go:171","msg":"trace[1573774361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2014; }","duration":"110.41444ms","start":"2026-08-20T01:16:02.588232Z","end":"2026-08-20T01:16:02.698646Z","steps":["trace[1573774361] 'agreement among raft nodes before linearized reading' (duration: 110.15145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:02.69882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.504587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-20T01:16:02.698857Z","caller":"traceutil/trace.go:171","msg":"trace[423714965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2014; }","duration":"104.587221ms","start":"2026-08-20T01:16:02.594258Z","end":"2026-08-20T01:16:02.698845Z","steps":["trace[423714965] 'agreement among raft nodes before linearized reading' (duration: 104.440255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:02.992715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.844567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:16:02.992829Z","caller":"traceutil/trace.go:171","msg":"trace[583890158] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"288.613078ms","start":"2026-08-20T01:16:02.704196Z","end":"2026-08-20T01:16:02.992809Z","steps":["trace[583890158] 'process raft request' (duration: 35.577463ms)","trace[583890158] 'compare' (duration: 252.570406ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:03.163211Z","caller":"traceutil/trace.go:171","msg":"trace[1034608728] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2090; }","duration":"403.783417ms","start":"2026-08-20T01:16:02.759402Z","end":"2026-08-20T01:16:03.163185Z","steps":["trace[1034608728] 'read index received' (duration: 376.929414ms)","trace[1034608728] 'applied index is now lower than readState.Index' (duration: 26.852933ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:03.163366Z","caller":"traceutil/trace.go:171","msg":"trace[196810811] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"452.242062ms","start":"2026-08-20T01:16:02.711009Z","end":"2026-08-20T01:16:03.163251Z","steps":["trace[196810811] 'process raft request' (duration: 452.084366ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:03.163414Z","caller":"traceutil/trace.go:171","msg":"trace[1327062984] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"452.585297ms","start":"2026-08-20T01:16:02.710708Z","end":"2026-08-20T01:16:03.163293Z","steps":["trace[1327062984] 'process raft request' (duration: 425.597169ms)","trace[1327062984] 'compare' (duration: 26.616273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:16:03.163547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.173784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-20T01:16:03.163563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:02.710685Z","time spent":"452.799875ms","remote":"127.0.0.1:59028","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-08-20T01:16:03.163489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.434961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:16:03.163603Z","caller":"traceutil/trace.go:171","msg":"trace[640037322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2017; }","duration":"270.248127ms","start":"2026-08-20T01:16:02.893339Z","end":"2026-08-20T01:16:03.163587Z","steps":["trace[640037322] 'agreement among raft nodes before linearized reading' (duration: 270.0806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:03.163577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.149791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-20T01:16:03.163579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:02.710921Z","time spent":"452.534415ms","remote":"127.0.0.1:59028","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-08-20T01:16:03.163674Z","caller":"traceutil/trace.go:171","msg":"trace[325982144] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:2017; }","duration":"404.303668ms","start":"2026-08-20T01:16:02.759345Z","end":"2026-08-20T01:16:03.163649Z","steps":["trace[325982144] 'agreement among raft nodes before linearized reading' (duration: 404.008446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:03.163737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:02.759332Z","time spent":"404.394633ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"info","ts":"2026-08-20T01:16:03.163916Z","caller":"traceutil/trace.go:171","msg":"trace[2011096991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2017; }","duration":"316.903091ms","start":"2026-08-20T01:16:02.846997Z","end":"2026-08-20T01:16:03.1639Z","steps":["trace[2011096991] 'agreement among raft nodes before linearized reading' (duration: 316.389489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:03.164019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:02.846987Z","time spent":"316.975254ms","remote":"127.0.0.1:58804","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-08-20T01:16:06.912874Z","caller":"traceutil/trace.go:171","msg":"trace[439183089] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"686.916828ms","start":"2026-08-20T01:16:06.225932Z","end":"2026-08-20T01:16:06.912849Z","steps":["trace[439183089] 'process raft request' (duration: 686.761972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:06.913031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:06.225916Z","time spent":"687.033733ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:16:06.99233Z","caller":"traceutil/trace.go:171","msg":"trace[893588302] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"750.362452ms","start":"2026-08-20T01:16:06.241948Z","end":"2026-08-20T01:16:06.992311Z","steps":["trace[893588302] 'process raft request' (duration: 750.123573ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:06.992331Z","caller":"traceutil/trace.go:171","msg":"trace[838694032] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"455.07551ms","start":"2026-08-20T01:16:06.53723Z","end":"2026-08-20T01:16:06.992305Z","steps":["trace[838694032] 'process raft request' (duration: 454.994777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:06.99242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:06.241942Z","time spent":"750.429935ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:16:06.992471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:06.537205Z","time spent":"455.190295ms","remote":"127.0.0.1:59028","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-08-20T01:16:06.992585Z","caller":"traceutil/trace.go:171","msg":"trace[2016232548] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"282.467928ms","start":"2026-08-20T01:16:06.710099Z","end":"2026-08-20T01:16:06.992567Z","steps":["trace[2016232548] 'process raft request' (duration: 282.343723ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:06.992573Z","caller":"traceutil/trace.go:171","msg":"trace[109428164] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"334.675653ms","start":"2026-08-20T01:16:06.657863Z","end":"2026-08-20T01:16:06.992539Z","steps":["trace[109428164] 'process raft request' (duration: 334.402822ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:06.992667Z","caller":"traceutil/trace.go:171","msg":"trace[12233497] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2107; }","duration":"267.582968ms","start":"2026-08-20T01:16:06.725073Z","end":"2026-08-20T01:16:06.992656Z","steps":["trace[12233497] 'read index received' (duration: 187.630517ms)","trace[12233497] 'applied index is now lower than readState.Index' (duration: 79.951801ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:06.992712Z","caller":"traceutil/trace.go:171","msg":"trace[1830715412] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"280.307178ms","start":"2026-08-20T01:16:06.712384Z","end":"2026-08-20T01:16:06.992691Z","steps":["trace[1830715412] 'process raft request' (duration: 280.141301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:06.992739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:06.657848Z","time spent":"334.808869ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:16:06.992807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.107307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:16:06.992819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.748704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-20T01:16:06.992838Z","caller":"traceutil/trace.go:171","msg":"trace[956655621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2037; }","duration":"152.168189ms","start":"2026-08-20T01:16:06.840662Z","end":"2026-08-20T01:16:06.99283Z","steps":["trace[956655621] 'agreement among raft nodes before linearized reading' (duration: 152.116657ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:06.992846Z","caller":"traceutil/trace.go:171","msg":"trace[1256328631] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2037; }","duration":"267.805717ms","start":"2026-08-20T01:16:06.72503Z","end":"2026-08-20T01:16:06.992836Z","steps":["trace[1256328631] 'agreement among raft nodes before linearized reading' (duration: 267.684342ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:31.189596Z","caller":"traceutil/trace.go:171","msg":"trace[1684923710] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"108.808367ms","start":"2026-08-20T01:16:31.080764Z","end":"2026-08-20T01:16:31.189573Z","steps":["trace[1684923710] 'process raft request' (duration: 108.663911ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:31.189906Z","caller":"traceutil/trace.go:171","msg":"trace[1199767841] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"107.691279ms","start":"2026-08-20T01:16:31.0822Z","end":"2026-08-20T01:16:31.189891Z","steps":["trace[1199767841] 'process raft request' (duration: 107.334834ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:32.014459Z","caller":"traceutil/trace.go:171","msg":"trace[1180670124] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"680.01336ms","start":"2026-08-20T01:16:31.334415Z","end":"2026-08-20T01:16:32.014429Z","steps":["trace[1180670124] 'process raft request' (duration: 606.359204ms)","trace[1180670124] 'compare' (duration: 73.498199ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:16:32.015515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:31.334408Z","time spent":"681.040003ms","remote":"127.0.0.1:59028","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-08-20T01:16:32.0146Z","caller":"traceutil/trace.go:171","msg":"trace[1842460723] linearizableReadLoop","detail":"{readStateIndex:2258; appliedIndex:2256; }","duration":"436.604408ms","start":"2026-08-20T01:16:31.577939Z","end":"2026-08-20T01:16:32.014543Z","steps":["trace[1842460723] 'read index received' (duration: 362.850337ms)","trace[1842460723] 'applied index is now lower than readState.Index' (duration: 73.75306ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:16:32.014839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.888959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:16:32.015824Z","caller":"traceutil/trace.go:171","msg":"trace[1548289836] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2176; }","duration":"437.895073ms","start":"2026-08-20T01:16:31.577878Z","end":"2026-08-20T01:16:32.015773Z","steps":["trace[1548289836] 'agreement among raft nodes before linearized reading' (duration: 436.759774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:32.016004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:31.577857Z","time spent":"438.086241ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-20T01:16:32.015042Z","caller":"traceutil/trace.go:171","msg":"trace[1283521795] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"679.763898ms","start":"2026-08-20T01:16:31.335266Z","end":"2026-08-20T01:16:32.01503Z","steps":["trace[1283521795] 'process raft request' (duration: 679.122411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:32.016366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.445797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:16:32.016414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:31.335258Z","time spent":"681.077725ms","remote":"127.0.0.1:59028","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-08-20T01:16:32.016418Z","caller":"traceutil/trace.go:171","msg":"trace[1389298920] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2176; }","duration":"284.559812ms","start":"2026-08-20T01:16:31.731841Z","end":"2026-08-20T01:16:32.016401Z","steps":["trace[1389298920] 'agreement among raft nodes before linearized reading' (duration: 284.484488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:32.016301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.981032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:16:32.016527Z","caller":"traceutil/trace.go:171","msg":"trace[152899209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2176; }","duration":"433.244452ms","start":"2026-08-20T01:16:31.583266Z","end":"2026-08-20T01:16:32.01651Z","steps":["trace[152899209] 'agreement among raft nodes before linearized reading' (duration: 432.95664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:32.016566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:31.583243Z","time spent":"433.314245ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T01:16:32.016946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.267404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:16:32.017131Z","caller":"traceutil/trace.go:171","msg":"trace[254736704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"175.469313ms","start":"2026-08-20T01:16:31.841583Z","end":"2026-08-20T01:16:32.017052Z","steps":["trace[254736704] 'agreement among raft nodes before linearized reading' (duration: 174.732901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:32.491675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.801546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:16:32.491908Z","caller":"traceutil/trace.go:171","msg":"trace[1379636255] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2258; }","duration":"294.00097ms","start":"2026-08-20T01:16:32.197885Z","end":"2026-08-20T01:16:32.491886Z","steps":["trace[1379636255] 'read index received' (duration: 57.813517ms)","trace[1379636255] 'applied index is now lower than readState.Index' (duration: 236.186103ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:16:32.492084Z","caller":"traceutil/trace.go:171","msg":"trace[1511534791] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"469.577585ms","start":"2026-08-20T01:16:32.022477Z","end":"2026-08-20T01:16:32.492054Z","steps":["trace[1511534791] 'process raft request' (duration: 469.313883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:16:32.492112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.22012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-20T01:16:32.492161Z","caller":"traceutil/trace.go:171","msg":"trace[586285108] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"294.305883ms","start":"2026-08-20T01:16:32.197836Z","end":"2026-08-20T01:16:32.492142Z","steps":["trace[586285108] 'agreement among raft nodes before linearized reading' (duration: 294.112384ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:16:32.492129Z","caller":"traceutil/trace.go:171","msg":"trace[2137706317] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"470.621289ms","start":"2026-08-20T01:16:32.02146Z","end":"2026-08-20T01:16:32.492081Z","steps":["trace[2137706317] 'process raft request' (duration: 234.306471ms)","trace[2137706317] 'compare' (duration: 235.000511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:16:32.49222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:32.022433Z","time spent":"469.709841ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:16:32.492352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:32.021433Z","time spent":"470.794257ms","remote":"127.0.0.1:59028","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-08-20T01:16:33.119654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:16:32.762102Z","time spent":"357.54707ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-20T01:17:53.629075Z","caller":"traceutil/trace.go:171","msg":"trace[279825424] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"100.004024ms","start":"2026-08-20T01:17:53.529047Z","end":"2026-08-20T01:17:53.629051Z","steps":["trace[279825424] 'process raft request' (duration: 99.917613ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:53.629083Z","caller":"traceutil/trace.go:171","msg":"trace[881324330] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"105.797095ms","start":"2026-08-20T01:17:53.52325Z","end":"2026-08-20T01:17:53.629047Z","steps":["trace[881324330] 'process raft request' (duration: 85.208974ms)","trace[881324330] 'compare' (duration: 20.388807ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:17:53.827704Z","caller":"traceutil/trace.go:171","msg":"trace[412528595] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"125.30585ms","start":"2026-08-20T01:17:53.702353Z","end":"2026-08-20T01:17:53.827659Z","steps":["trace[412528595] 'process raft request' (duration: 111.124828ms)","trace[412528595] 'compare' (duration: 13.810288ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:17:53.828161Z","caller":"traceutil/trace.go:171","msg":"trace[1466160840] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"124.960798ms","start":"2026-08-20T01:17:53.703155Z","end":"2026-08-20T01:17:53.828116Z","steps":["trace[1466160840] 'process raft request' (duration: 124.38732ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:53.828197Z","caller":"traceutil/trace.go:171","msg":"trace[756826038] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"123.940156ms","start":"2026-08-20T01:17:53.704242Z","end":"2026-08-20T01:17:53.828183Z","steps":["trace[756826038] 'process raft request' (duration: 123.413038ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:54.173986Z","caller":"traceutil/trace.go:171","msg":"trace[311547623] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"133.035895ms","start":"2026-08-20T01:17:54.04091Z","end":"2026-08-20T01:17:54.173946Z","steps":["trace[311547623] 'process raft request' (duration: 132.967604ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:54.174107Z","caller":"traceutil/trace.go:171","msg":"trace[1703281703] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"133.167147ms","start":"2026-08-20T01:17:54.040887Z","end":"2026-08-20T01:17:54.174054Z","steps":["trace[1703281703] 'process raft request' (duration: 132.897753ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:54.174219Z","caller":"traceutil/trace.go:171","msg":"trace[578679812] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"157.938533ms","start":"2026-08-20T01:17:54.016227Z","end":"2026-08-20T01:17:54.174165Z","steps":["trace[578679812] 'process raft request' (duration: 69.793693ms)","trace[578679812] 'compare' (duration: 87.422421ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:17:54.667337Z","caller":"traceutil/trace.go:171","msg":"trace[1572428490] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"112.846936ms","start":"2026-08-20T01:17:54.554445Z","end":"2026-08-20T01:17:54.667292Z","steps":["trace[1572428490] 'process raft request' (duration: 40.787134ms)","trace[1572428490] 'compare' (duration: 71.603966ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:17:57.109835Z","caller":"traceutil/trace.go:171","msg":"trace[835680335] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"102.425595ms","start":"2026-08-20T01:17:57.007378Z","end":"2026-08-20T01:17:57.109804Z","steps":["trace[835680335] 'process raft request' (duration: 102.243023ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:57.890138Z","caller":"traceutil/trace.go:171","msg":"trace[536922006] linearizableReadLoop","detail":"{readStateIndex:2828; appliedIndex:2827; }","duration":"370.379761ms","start":"2026-08-20T01:17:57.519709Z","end":"2026-08-20T01:17:57.890088Z","steps":["trace[536922006] 'read index received' (duration: 370.147859ms)","trace[536922006] 'applied index is now lower than readState.Index' (duration: 230.132µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:17:57.890767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.013899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:17:57.890778Z","caller":"traceutil/trace.go:171","msg":"trace[1758755899] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"385.020823ms","start":"2026-08-20T01:17:57.50571Z","end":"2026-08-20T01:17:57.890731Z","steps":["trace[1758755899] 'process raft request' (duration: 384.142471ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:57.890832Z","caller":"traceutil/trace.go:171","msg":"trace[872849056] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2727; }","duration":"371.151682ms","start":"2026-08-20T01:17:57.519664Z","end":"2026-08-20T01:17:57.890816Z","steps":["trace[872849056] 'agreement among raft nodes before linearized reading' (duration: 370.99374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:17:57.89114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:17:57.505672Z","time spent":"385.310756ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:17:57.891176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:17:57.519648Z","time spent":"371.508646ms","remote":"127.0.0.1:53752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:17:57.900546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.711984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:17:57.900751Z","caller":"traceutil/trace.go:171","msg":"trace[1889350647] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2730; }","duration":"357.981489ms","start":"2026-08-20T01:17:57.542758Z","end":"2026-08-20T01:17:57.90074Z","steps":["trace[1889350647] 'agreement among raft nodes before linearized reading' (duration: 357.673485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:17:57.900827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:17:57.542746Z","time spent":"358.066379ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:17:57.900901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.12384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:17:57.901046Z","caller":"traceutil/trace.go:171","msg":"trace[1713778760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2730; }","duration":"118.310534ms","start":"2026-08-20T01:17:57.782719Z","end":"2026-08-20T01:17:57.90103Z","steps":["trace[1713778760] 'agreement among raft nodes before linearized reading' (duration: 117.761386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:17:57.900591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.376999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:17:57.901135Z","caller":"traceutil/trace.go:171","msg":"trace[514810137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2730; }","duration":"337.933278ms","start":"2026-08-20T01:17:57.563164Z","end":"2026-08-20T01:17:57.901097Z","steps":["trace[514810137] 'agreement among raft nodes before linearized reading' (duration: 337.36713ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:57.900645Z","caller":"traceutil/trace.go:171","msg":"trace[1241709095] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"251.915596ms","start":"2026-08-20T01:17:57.648705Z","end":"2026-08-20T01:17:57.900621Z","steps":["trace[1241709095] 'process raft request' (duration: 251.484721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:17:57.901213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:17:57.563149Z","time spent":"338.035539ms","remote":"127.0.0.1:33894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-08-20T01:17:57.90067Z","caller":"traceutil/trace.go:171","msg":"trace[1213325860] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"238.819394ms","start":"2026-08-20T01:17:57.661824Z","end":"2026-08-20T01:17:57.900643Z","steps":["trace[1213325860] 'process raft request' (duration: 238.537041ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:57.900504Z","caller":"traceutil/trace.go:171","msg":"trace[94903920] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"229.203469ms","start":"2026-08-20T01:17:57.671249Z","end":"2026-08-20T01:17:57.900453Z","steps":["trace[94903920] 'process raft request' (duration: 229.139778ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:22.198268Z","caller":"traceutil/trace.go:171","msg":"trace[339842160] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"193.704722ms","start":"2026-08-20T01:18:22.004535Z","end":"2026-08-20T01:18:22.19824Z","steps":["trace[339842160] 'process raft request' (duration: 193.511259ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:22.228233Z","caller":"traceutil/trace.go:171","msg":"trace[203769374] linearizableReadLoop","detail":"{readStateIndex:2974; appliedIndex:2970; }","duration":"203.56324ms","start":"2026-08-20T01:18:22.024633Z","end":"2026-08-20T01:18:22.228197Z","steps":["trace[203769374] 'read index received' (duration: 173.405704ms)","trace[203769374] 'applied index is now lower than readState.Index' (duration: 30.156046ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:18:22.228594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.891376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:18:22.228644Z","caller":"traceutil/trace.go:171","msg":"trace[746302301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2869; }","duration":"204.038708ms","start":"2026-08-20T01:18:22.024593Z","end":"2026-08-20T01:18:22.228632Z","steps":["trace[746302301] 'agreement among raft nodes before linearized reading' (duration: 203.759423ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:22.229032Z","caller":"traceutil/trace.go:171","msg":"trace[1153096419] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"214.568089ms","start":"2026-08-20T01:18:22.014448Z","end":"2026-08-20T01:18:22.229016Z","steps":["trace[1153096419] 'process raft request' (duration: 213.433198ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:22.229204Z","caller":"traceutil/trace.go:171","msg":"trace[981646067] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"205.299401ms","start":"2026-08-20T01:18:22.023893Z","end":"2026-08-20T01:18:22.229193Z","steps":["trace[981646067] 'process raft request' (duration: 204.149131ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:22.229368Z","caller":"traceutil/trace.go:171","msg":"trace[339242914] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"205.345682ms","start":"2026-08-20T01:18:22.024003Z","end":"2026-08-20T01:18:22.229348Z","steps":["trace[339242914] 'process raft request' (duration: 204.08782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:18:22.229406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.148795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T01:18:22.22965Z","caller":"traceutil/trace.go:171","msg":"trace[136741246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2869; }","duration":"113.557752ms","start":"2026-08-20T01:18:22.116007Z","end":"2026-08-20T01:18:22.229565Z","steps":["trace[136741246] 'agreement among raft nodes before linearized reading' (duration: 112.936501ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:22.229523Z","caller":"traceutil/trace.go:171","msg":"trace[2009373628] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"180.113616ms","start":"2026-08-20T01:18:22.049398Z","end":"2026-08-20T01:18:22.229511Z","steps":["trace[2009373628] 'process raft request' (duration: 178.742922ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:33.507983Z","caller":"traceutil/trace.go:171","msg":"trace[871291703] transaction","detail":"{read_only:false; response_revision:2929; number_of_response:1; }","duration":"182.367357ms","start":"2026-08-20T01:18:33.325589Z","end":"2026-08-20T01:18:33.507956Z","steps":["trace[871291703] 'process raft request' (duration: 182.188264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:18:34.019504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.717137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:18:34.019598Z","caller":"traceutil/trace.go:171","msg":"trace[550286670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2929; }","duration":"177.818109ms","start":"2026-08-20T01:18:33.841759Z","end":"2026-08-20T01:18:34.019577Z","steps":["trace[550286670] 'range keys from in-memory index tree' (duration: 177.635656ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.736543Z","caller":"traceutil/trace.go:171","msg":"trace[2003759298] transaction","detail":"{read_only:false; response_revision:3277; number_of_response:1; }","duration":"116.846815ms","start":"2026-08-20T01:19:40.619638Z","end":"2026-08-20T01:19:40.736484Z","steps":["trace[2003759298] 'process raft request' (duration: 30.002231ms)","trace[2003759298] 'compare' (duration: 86.672328ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:40.737113Z","caller":"traceutil/trace.go:171","msg":"trace[223068966] transaction","detail":"{read_only:false; response_revision:3278; number_of_response:1; }","duration":"114.315643ms","start":"2026-08-20T01:19:40.622762Z","end":"2026-08-20T01:19:40.737077Z","steps":["trace[223068966] 'process raft request' (duration: 113.678584ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.737196Z","caller":"traceutil/trace.go:171","msg":"trace[1352355004] transaction","detail":"{read_only:false; response_revision:3279; number_of_response:1; }","duration":"112.621893ms","start":"2026-08-20T01:19:40.624554Z","end":"2026-08-20T01:19:40.737176Z","steps":["trace[1352355004] 'process raft request' (duration: 112.314235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:40.947306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.84803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:19:40.947443Z","caller":"traceutil/trace.go:171","msg":"trace[1317759859] linearizableReadLoop","detail":"{readStateIndex:3404; appliedIndex:3403; }","duration":"105.417217ms","start":"2026-08-20T01:19:40.842004Z","end":"2026-08-20T01:19:40.947421Z","steps":["trace[1317759859] 'read index received' (duration: 44.971µs)","trace[1317759859] 'applied index is now lower than readState.Index' (duration: 105.370466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:19:40.947529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.52043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:19:40.94758Z","caller":"traceutil/trace.go:171","msg":"trace[503281681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3281; }","duration":"105.610153ms","start":"2026-08-20T01:19:40.841955Z","end":"2026-08-20T01:19:40.947565Z","steps":["trace[503281681] 'agreement among raft nodes before linearized reading' (duration: 105.5301ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.948254Z","caller":"traceutil/trace.go:171","msg":"trace[1281028968] transaction","detail":"{read_only:false; response_revision:3281; number_of_response:1; }","duration":"181.419599ms","start":"2026-08-20T01:19:40.766064Z","end":"2026-08-20T01:19:40.947483Z","steps":["trace[1281028968] 'process raft request' (duration: 75.186299ms)","trace[1281028968] 'compare' (duration: 105.549881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:20:22.901098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.2985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:20:22.901385Z","caller":"traceutil/trace.go:171","msg":"trace[173427513] transaction","detail":"{read_only:false; response_revision:3492; number_of_response:1; }","duration":"113.636605ms","start":"2026-08-20T01:20:22.787649Z","end":"2026-08-20T01:20:22.901286Z","steps":["trace[173427513] 'process raft request' (duration: 11.049646ms)","trace[173427513] 'compare' (duration: 102.116484ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:25.398962Z","caller":"traceutil/trace.go:171","msg":"trace[636835314] transaction","detail":"{read_only:false; response_revision:3919; number_of_response:1; }","duration":"110.626969ms","start":"2026-08-20T01:21:25.288303Z","end":"2026-08-20T01:21:25.39893Z","steps":["trace[636835314] 'process raft request' (duration: 110.437962ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:25.842591Z","caller":"traceutil/trace.go:171","msg":"trace[208385680] transaction","detail":"{read_only:false; response_revision:4332; number_of_response:1; }","duration":"228.035514ms","start":"2026-08-20T01:22:25.614529Z","end":"2026-08-20T01:22:25.842565Z","steps":["trace[208385680] 'process raft request' (duration: 227.872648ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:25.846704Z","caller":"traceutil/trace.go:171","msg":"trace[1623284184] transaction","detail":"{read_only:false; response_revision:4333; number_of_response:1; }","duration":"210.152797ms","start":"2026-08-20T01:22:25.636539Z","end":"2026-08-20T01:22:25.846692Z","steps":["trace[1623284184] 'process raft request' (duration: 209.97063ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:25.846691Z","caller":"traceutil/trace.go:171","msg":"trace[1628754934] linearizableReadLoop","detail":"{readStateIndex:4511; appliedIndex:4508; }","duration":"140.443548ms","start":"2026-08-20T01:22:25.706225Z","end":"2026-08-20T01:22:25.846669Z","steps":["trace[1628754934] 'read index received' (duration: 136.230178ms)","trace[1628754934] 'applied index is now lower than readState.Index' (duration: 4.21241ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:22:25.846775Z","caller":"traceutil/trace.go:171","msg":"trace[2028314099] transaction","detail":"{read_only:false; response_revision:4334; number_of_response:1; }","duration":"142.660232ms","start":"2026-08-20T01:22:25.704092Z","end":"2026-08-20T01:22:25.846752Z","steps":["trace[2028314099] 'process raft request' (duration: 142.533047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:25.846856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.608484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:22:25.846896Z","caller":"traceutil/trace.go:171","msg":"trace[1705904666] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4334; }","duration":"140.679996ms","start":"2026-08-20T01:22:25.706203Z","end":"2026-08-20T01:22:25.846883Z","steps":["trace[1705904666] 'agreement among raft nodes before linearized reading' (duration: 140.568552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:35.622542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.174593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:22:35.622793Z","caller":"traceutil/trace.go:171","msg":"trace[779695611] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"170.923336ms","start":"2026-08-20T01:22:35.451847Z","end":"2026-08-20T01:22:35.622771Z","steps":["trace[779695611] 'process raft request' (duration: 170.853274ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:35.622746Z","caller":"traceutil/trace.go:171","msg":"trace[2092159786] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"221.302527ms","start":"2026-08-20T01:22:35.401393Z","end":"2026-08-20T01:22:35.622696Z","steps":["trace[2092159786] 'process raft request' (duration: 69.844522ms)","trace[2092159786] 'compare' (duration: 150.908684ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:22:35.62281Z","caller":"traceutil/trace.go:171","msg":"trace[1317286154] linearizableReadLoop","detail":"{readStateIndex:4994; appliedIndex:4993; }","duration":"220.169195ms","start":"2026-08-20T01:22:35.402617Z","end":"2026-08-20T01:22:35.622786Z","steps":["trace[1317286154] 'read index received' (duration: 68.579955ms)","trace[1317286154] 'applied index is now lower than readState.Index' (duration: 151.58671ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:35.623569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.919193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:499110"} {"level":"info","ts":"2026-08-20T01:22:35.623672Z","caller":"traceutil/trace.go:171","msg":"trace[751468175] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4810; }","duration":"221.040688ms","start":"2026-08-20T01:22:35.402612Z","end":"2026-08-20T01:22:35.623653Z","steps":["trace[751468175] 'agreement among raft nodes before linearized reading' (duration: 220.255378ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:35.673858Z","caller":"traceutil/trace.go:171","msg":"trace[656222877] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"111.857944ms","start":"2026-08-20T01:22:35.561969Z","end":"2026-08-20T01:22:35.673827Z","steps":["trace[656222877] 'process raft request' (duration: 111.668237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:35.674051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.336028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-499685a6-b1d4-48e4-b979-7d660d94d271\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-08-20T01:22:35.674126Z","caller":"traceutil/trace.go:171","msg":"trace[79383146] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-499685a6-b1d4-48e4-b979-7d660d94d271; range_end:; response_count:1; response_revision:4811; }","duration":"248.443192ms","start":"2026-08-20T01:22:35.425662Z","end":"2026-08-20T01:22:35.674105Z","steps":["trace[79383146] 'agreement among raft nodes before linearized reading' (duration: 248.179782ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:38.607956Z","caller":"traceutil/trace.go:171","msg":"trace[1828641010] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"133.0007ms","start":"2026-08-20T01:22:38.474913Z","end":"2026-08-20T01:22:38.607914Z","steps":["trace[1828641010] 'process raft request' (duration: 132.762631ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.611737Z","caller":"traceutil/trace.go:171","msg":"trace[1245012809] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"109.35095ms","start":"2026-08-20T01:22:39.502361Z","end":"2026-08-20T01:22:39.611712Z","steps":["trace[1245012809] 'process raft request' (duration: 99.874448ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.611831Z","caller":"traceutil/trace.go:171","msg":"trace[46170421] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5073; }","duration":"109.353309ms","start":"2026-08-20T01:22:39.502429Z","end":"2026-08-20T01:22:39.611782Z","steps":["trace[46170421] 'read index received' (duration: 99.971211ms)","trace[46170421] 'applied index is now lower than readState.Index' (duration: 9.375078ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:22:39.611891Z","caller":"traceutil/trace.go:171","msg":"trace[1251267091] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"109.141192ms","start":"2026-08-20T01:22:39.502727Z","end":"2026-08-20T01:22:39.611868Z","steps":["trace[1251267091] 'process raft request' (duration: 109.002615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:39.612214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.202149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-08-20T01:22:39.612217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.736046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-20T01:22:39.612336Z","caller":"traceutil/trace.go:171","msg":"trace[1784507696] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4887; }","duration":"109.905211ms","start":"2026-08-20T01:22:39.502402Z","end":"2026-08-20T01:22:39.612307Z","steps":["trace[1784507696] 'agreement among raft nodes before linearized reading' (duration: 109.493106ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.612266Z","caller":"traceutil/trace.go:171","msg":"trace[1526147708] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4887; }","duration":"106.291773ms","start":"2026-08-20T01:22:39.505958Z","end":"2026-08-20T01:22:39.61225Z","steps":["trace[1526147708] 'agreement among raft nodes before linearized reading' (duration: 106.096906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:39.612518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.992425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-20T01:22:39.612586Z","caller":"traceutil/trace.go:171","msg":"trace[630408126] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4887; }","duration":"110.089668ms","start":"2026-08-20T01:22:39.50248Z","end":"2026-08-20T01:22:39.61257Z","steps":["trace[630408126] 'agreement among raft nodes before linearized reading' (duration: 109.544348ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.819897Z","caller":"traceutil/trace.go:171","msg":"trace[578720805] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"170.274649ms","start":"2026-08-20T01:22:39.649601Z","end":"2026-08-20T01:22:39.819875Z","steps":["trace[578720805] 'process raft request' (duration: 170.117543ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.873956Z","caller":"traceutil/trace.go:171","msg":"trace[1528302191] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"219.25186ms","start":"2026-08-20T01:22:39.654676Z","end":"2026-08-20T01:22:39.873927Z","steps":["trace[1528302191] 'process raft request' (duration: 219.028131ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.874246Z","caller":"traceutil/trace.go:171","msg":"trace[1362282740] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"187.713935ms","start":"2026-08-20T01:22:39.686504Z","end":"2026-08-20T01:22:39.874218Z","steps":["trace[1362282740] 'process raft request' (duration: 187.362591ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:39.874232Z","caller":"traceutil/trace.go:171","msg":"trace[969085187] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5077; }","duration":"165.053279ms","start":"2026-08-20T01:22:39.70912Z","end":"2026-08-20T01:22:39.874173Z","steps":["trace[969085187] 'read index received' (duration: 110.833657ms)","trace[969085187] 'applied index is now lower than readState.Index' (duration: 54.216212ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:39.874535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.391452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-20T01:22:39.874693Z","caller":"traceutil/trace.go:171","msg":"trace[352102644] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4891; }","duration":"165.573378ms","start":"2026-08-20T01:22:39.709081Z","end":"2026-08-20T01:22:39.874654Z","steps":["trace[352102644] 'agreement among raft nodes before linearized reading' (duration: 165.240336ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:40.904132Z","caller":"traceutil/trace.go:171","msg":"trace[1527718463] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5097; }","duration":"240.878213ms","start":"2026-08-20T01:22:40.663228Z","end":"2026-08-20T01:22:40.904106Z","steps":["trace[1527718463] 'read index received' (duration: 240.713796ms)","trace[1527718463] 'applied index is now lower than readState.Index' (duration: 163.417µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:22:40.904182Z","caller":"traceutil/trace.go:171","msg":"trace[722667735] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"287.131603ms","start":"2026-08-20T01:22:40.617025Z","end":"2026-08-20T01:22:40.904156Z","steps":["trace[722667735] 'process raft request' (duration: 286.938365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:40.904339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.091802ms","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-08-20T01:22:40.904403Z","caller":"traceutil/trace.go:171","msg":"trace[327446883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4910; }","duration":"241.185234ms","start":"2026-08-20T01:22:40.663204Z","end":"2026-08-20T01:22:40.904389Z","steps":["trace[327446883] 'agreement among raft nodes before linearized reading' (duration: 241.011438ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:47.106356Z","caller":"traceutil/trace.go:171","msg":"trace[13507941] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"765.79116ms","start":"2026-08-20T01:22:46.340532Z","end":"2026-08-20T01:22:47.106323Z","steps":["trace[13507941] 'process raft request' (duration: 765.641524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.106523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:46.340484Z","time spent":"765.960687ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:22:47.145273Z","caller":"traceutil/trace.go:171","msg":"trace[1292442086] linearizableReadLoop","detail":"{readStateIndex:5209; appliedIndex:5207; }","duration":"517.816441ms","start":"2026-08-20T01:22:46.627439Z","end":"2026-08-20T01:22:47.145256Z","steps":["trace[1292442086] 'read index received' (duration: 478.73454ms)","trace[1292442086] 'applied index is now lower than readState.Index' (duration: 39.080761ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:47.145412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.968967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:22:47.145457Z","caller":"traceutil/trace.go:171","msg":"trace[40298530] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5020; }","duration":"518.075622ms","start":"2026-08-20T01:22:46.627368Z","end":"2026-08-20T01:22:47.145444Z","steps":["trace[40298530] 'agreement among raft nodes before linearized reading' (duration: 517.965437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.145492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:46.627349Z","time spent":"518.134123ms","remote":"127.0.0.1:33792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:22:47.145654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.517995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46b3b290-a57d-4cb5-93f0-34d828e5bcb8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-20T01:22:47.14572Z","caller":"traceutil/trace.go:171","msg":"trace[746416818] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46b3b290-a57d-4cb5-93f0-34d828e5bcb8; range_end:; response_count:1; response_revision:5020; }","duration":"482.600058ms","start":"2026-08-20T01:22:46.663103Z","end":"2026-08-20T01:22:47.145703Z","steps":["trace[746416818] 'agreement among raft nodes before linearized reading' (duration: 482.37743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.145788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:46.663012Z","time spent":"482.767465ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46b3b290-a57d-4cb5-93f0-34d828e5bcb8\" "} {"level":"info","ts":"2026-08-20T01:22:47.145793Z","caller":"traceutil/trace.go:171","msg":"trace[850684059] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"564.474722ms","start":"2026-08-20T01:22:46.581306Z","end":"2026-08-20T01:22:47.145781Z","steps":["trace[850684059] 'process raft request' (duration: 563.852129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.145867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:46.581276Z","time spent":"564.549265ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:22:47.146851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.800605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-20T01:22:47.146948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.726401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-20T01:22:47.146958Z","caller":"traceutil/trace.go:171","msg":"trace[714815914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5020; }","duration":"210.977022ms","start":"2026-08-20T01:22:46.935955Z","end":"2026-08-20T01:22:47.146932Z","steps":["trace[714815914] 'agreement among raft nodes before linearized reading' (duration: 210.597948ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:47.147009Z","caller":"traceutil/trace.go:171","msg":"trace[147821078] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5020; }","duration":"459.879286ms","start":"2026-08-20T01:22:46.687115Z","end":"2026-08-20T01:22:47.146994Z","steps":["trace[147821078] 'agreement among raft nodes before linearized reading' (duration: 459.70044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.147043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:46.687079Z","time spent":"459.95707ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-20T01:22:47.147165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.407656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:22:47.147213Z","caller":"traceutil/trace.go:171","msg":"trace[1051772475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5020; }","duration":"306.512929ms","start":"2026-08-20T01:22:46.840687Z","end":"2026-08-20T01:22:47.1472Z","steps":["trace[1051772475] 'agreement among raft nodes before linearized reading' (duration: 306.422246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.147245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:46.840655Z","time spent":"306.585182ms","remote":"127.0.0.1:58804","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-08-20T01:22:47.147374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.10993ms","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-08-20T01:22:47.147488Z","caller":"traceutil/trace.go:171","msg":"trace[1805283813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5020; }","duration":"197.309907ms","start":"2026-08-20T01:22:46.950153Z","end":"2026-08-20T01:22:47.147463Z","steps":["trace[1805283813] 'agreement among raft nodes before linearized reading' (duration: 196.918742ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:47.255998Z","caller":"traceutil/trace.go:171","msg":"trace[1955677443] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"104.030125ms","start":"2026-08-20T01:22:47.151943Z","end":"2026-08-20T01:22:47.255973Z","steps":["trace[1955677443] 'process raft request' (duration: 103.844508ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:47.256185Z","caller":"traceutil/trace.go:171","msg":"trace[376262637] linearizableReadLoop","detail":"{readStateIndex:5211; appliedIndex:5209; }","duration":"103.847628ms","start":"2026-08-20T01:22:47.152305Z","end":"2026-08-20T01:22:47.256152Z","steps":["trace[376262637] 'read index received' (duration: 22.851687ms)","trace[376262637] 'applied index is now lower than readState.Index' (duration: 80.993271ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:22:47.256411Z","caller":"traceutil/trace.go:171","msg":"trace[128295460] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"103.571878ms","start":"2026-08-20T01:22:47.15282Z","end":"2026-08-20T01:22:47.256392Z","steps":["trace[128295460] 'process raft request' (duration: 103.108051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:47.256521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.191562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9427"} {"level":"info","ts":"2026-08-20T01:22:47.256587Z","caller":"traceutil/trace.go:171","msg":"trace[2131454104] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5023; }","duration":"104.296556ms","start":"2026-08-20T01:22:47.152276Z","end":"2026-08-20T01:22:47.256572Z","steps":["trace[2131454104] 'agreement among raft nodes before linearized reading' (duration: 104.052757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.251073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.454531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:22:48.251185Z","caller":"traceutil/trace.go:171","msg":"trace[1576949741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5036; }","duration":"487.295693ms","start":"2026-08-20T01:22:47.763868Z","end":"2026-08-20T01:22:48.251164Z","steps":["trace[1576949741] 'count revisions from in-memory index tree' (duration: 486.319735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.251231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.763836Z","time spent":"487.379586ms","remote":"127.0.0.1:33934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:22:48.25158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.286786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:22:48.251605Z","caller":"traceutil/trace.go:171","msg":"trace[507316804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5036; }","duration":"410.331478ms","start":"2026-08-20T01:22:47.841266Z","end":"2026-08-20T01:22:48.251598Z","steps":["trace[507316804] 'range keys from in-memory index tree' (duration: 410.237544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.251623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.841254Z","time spent":"410.363769ms","remote":"127.0.0.1:58804","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-08-20T01:22:48.251667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.563479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:22:48.251746Z","caller":"traceutil/trace.go:171","msg":"trace[1095033761] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5036; }","duration":"427.703865ms","start":"2026-08-20T01:22:47.824023Z","end":"2026-08-20T01:22:48.251726Z","steps":["trace[1095033761] 'range keys from in-memory index tree' (duration: 427.326929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.251795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.823994Z","time spent":"427.787408ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-20T01:22:48.342442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.71299ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T01:22:48.344447Z","caller":"traceutil/trace.go:171","msg":"trace[178764726] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"388.957498ms","start":"2026-08-20T01:22:47.955453Z","end":"2026-08-20T01:22:48.344411Z","steps":["trace[178764726] 'process raft request' (duration: 388.517131ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:48.344484Z","caller":"traceutil/trace.go:171","msg":"trace[296961048] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5225; }","duration":"333.548129ms","start":"2026-08-20T01:22:48.010898Z","end":"2026-08-20T01:22:48.344446Z","steps":["trace[296961048] 'read index received' (duration: 323.116978ms)","trace[296961048] 'applied index is now lower than readState.Index' (duration: 10.426391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:48.344638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.955438Z","time spent":"389.102874ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:22:48.344669Z","caller":"traceutil/trace.go:171","msg":"trace[1077288134] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"389.106454ms","start":"2026-08-20T01:22:47.955522Z","end":"2026-08-20T01:22:48.344629Z","steps":["trace[1077288134] 'process raft request' (duration: 388.485861ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:48.344798Z","caller":"traceutil/trace.go:171","msg":"trace[837762847] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"428.449475ms","start":"2026-08-20T01:22:47.916333Z","end":"2026-08-20T01:22:48.344783Z","steps":["trace[837762847] 'process raft request' (duration: 426.23296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.344872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.916304Z","time spent":"428.536888ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:22:48.344864Z","caller":"traceutil/trace.go:171","msg":"trace[522800537] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"394.152758ms","start":"2026-08-20T01:22:47.950679Z","end":"2026-08-20T01:22:48.344832Z","steps":["trace[522800537] 'process raft request' (duration: 393.229413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.344903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.478367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46b3b290-a57d-4cb5-93f0-34d828e5bcb8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-20T01:22:48.344961Z","caller":"traceutil/trace.go:171","msg":"trace[1410649716] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46b3b290-a57d-4cb5-93f0-34d828e5bcb8; range_end:; response_count:1; response_revision:5041; }","duration":"186.588962ms","start":"2026-08-20T01:22:48.158352Z","end":"2026-08-20T01:22:48.34494Z","steps":["trace[1410649716] 'agreement among raft nodes before linearized reading' (duration: 186.404685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.345052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.950662Z","time spent":"394.273253ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:22:48.345078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.482683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:22:48.345108Z","caller":"traceutil/trace.go:171","msg":"trace[835144958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5041; }","duration":"182.542735ms","start":"2026-08-20T01:22:48.162559Z","end":"2026-08-20T01:22:48.345101Z","steps":["trace[835144958] 'agreement among raft nodes before linearized reading' (duration: 182.39565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.345347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.451354ms","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-08-20T01:22:48.345397Z","caller":"traceutil/trace.go:171","msg":"trace[886325282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5041; }","duration":"334.501286ms","start":"2026-08-20T01:22:48.010883Z","end":"2026-08-20T01:22:48.345384Z","steps":["trace[886325282] 'agreement among raft nodes before linearized reading' (duration: 334.298628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:48.345436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:48.010825Z","time spent":"334.604ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T01:22:48.345623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:22:47.955509Z","time spent":"389.187997ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:22:48.47392Z","caller":"traceutil/trace.go:171","msg":"trace[936593736] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5231; }","duration":"116.197957ms","start":"2026-08-20T01:22:48.357694Z","end":"2026-08-20T01:22:48.473892Z","steps":["trace[936593736] 'read index received' (duration: 38.313553ms)","trace[936593736] 'applied index is now lower than readState.Index' (duration: 77.883354ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:22:48.474145Z","caller":"traceutil/trace.go:171","msg":"trace[62537402] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"116.82101ms","start":"2026-08-20T01:22:48.357284Z","end":"2026-08-20T01:22:48.474105Z","steps":["trace[62537402] 'process raft request' (duration: 38.715248ms)","trace[62537402] 'compare' (duration: 77.747789ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:48.474211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.492758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a0bf6073-742e-4b0e-b27d-724221e9f3c0\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-08-20T01:22:48.474258Z","caller":"traceutil/trace.go:171","msg":"trace[322258109] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a0bf6073-742e-4b0e-b27d-724221e9f3c0; range_end:; response_count:1; response_revision:5042; }","duration":"116.581482ms","start":"2026-08-20T01:22:48.357663Z","end":"2026-08-20T01:22:48.474244Z","steps":["trace[322258109] 'agreement among raft nodes before linearized reading' (duration: 116.326312ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:48.486418Z","caller":"traceutil/trace.go:171","msg":"trace[1711269701] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"126.394968ms","start":"2026-08-20T01:22:48.359976Z","end":"2026-08-20T01:22:48.486371Z","steps":["trace[1711269701] 'process raft request' (duration: 126.1757ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:50.44954Z","caller":"traceutil/trace.go:171","msg":"trace[759094993] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"184.875804ms","start":"2026-08-20T01:22:50.264628Z","end":"2026-08-20T01:22:50.449504Z","steps":["trace[759094993] 'process raft request' (duration: 174.837148ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:51.851454Z","caller":"traceutil/trace.go:171","msg":"trace[1067405973] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"215.165544ms","start":"2026-08-20T01:22:51.63624Z","end":"2026-08-20T01:22:51.851405Z","steps":["trace[1067405973] 'process raft request' (duration: 212.283543ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:56.514396Z","caller":"traceutil/trace.go:171","msg":"trace[1110715907] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"164.608056ms","start":"2026-08-20T01:22:56.34975Z","end":"2026-08-20T01:22:56.514359Z","steps":["trace[1110715907] 'process raft request' (duration: 121.529353ms)","trace[1110715907] 'compare' (duration: 42.751121ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:02.027179Z","caller":"traceutil/trace.go:171","msg":"trace[767585331] linearizableReadLoop","detail":"{readStateIndex:5405; appliedIndex:5403; }","duration":"679.254118ms","start":"2026-08-20T01:23:01.347863Z","end":"2026-08-20T01:23:02.027117Z","steps":["trace[767585331] 'read index received' (duration: 3.85568ms)","trace[767585331] 'applied index is now lower than readState.Index' (duration: 675.397428ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:02.027334Z","caller":"traceutil/trace.go:171","msg":"trace[1758014313] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"697.548585ms","start":"2026-08-20T01:23:01.329769Z","end":"2026-08-20T01:23:02.027317Z","steps":["trace[1758014313] 'process raft request' (duration: 606.932827ms)","trace[1758014313] 'compare' (duration: 90.304206ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:23:02.027439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:23:01.32973Z","time spent":"697.632908ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:23:02.027461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.351314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:23:02.027532Z","caller":"traceutil/trace.go:171","msg":"trace[289719403] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:5209; }","duration":"249.479149ms","start":"2026-08-20T01:23:01.778031Z","end":"2026-08-20T01:23:02.02751Z","steps":["trace[289719403] 'agreement among raft nodes before linearized reading' (duration: 249.333113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.027545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.135429ms","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-08-20T01:23:02.027609Z","caller":"traceutil/trace.go:171","msg":"trace[1192850640] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5209; }","duration":"611.269375ms","start":"2026-08-20T01:23:01.416315Z","end":"2026-08-20T01:23:02.027585Z","steps":["trace[1192850640] 'agreement among raft nodes before linearized reading' (duration: 611.064767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.027598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.717885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-08-20T01:23:02.027656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:23:01.41629Z","time spent":"611.352918ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-08-20T01:23:02.027694Z","caller":"traceutil/trace.go:171","msg":"trace[1762199470] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5209; }","duration":"671.855231ms","start":"2026-08-20T01:23:01.355813Z","end":"2026-08-20T01:23:02.027668Z","steps":["trace[1762199470] 'agreement among raft nodes before linearized reading' (duration: 671.528998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.027754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:23:01.3558Z","time spent":"671.938914ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-20T01:23:02.027844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.980815ms","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-08-20T01:23:02.028126Z","caller":"traceutil/trace.go:171","msg":"trace[1274614680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5209; }","duration":"680.342439ms","start":"2026-08-20T01:23:01.347771Z","end":"2026-08-20T01:23:02.028114Z","steps":["trace[1274614680] 'agreement among raft nodes before linearized reading' (duration: 679.995256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.028159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:23:01.347745Z","time spent":"680.406601ms","remote":"127.0.0.1:59028","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":"info","ts":"2026-08-20T01:23:02.19961Z","caller":"traceutil/trace.go:171","msg":"trace[793059789] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"161.912441ms","start":"2026-08-20T01:23:02.037669Z","end":"2026-08-20T01:23:02.199582Z","steps":["trace[793059789] 'process raft request' (duration: 152.103332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.440031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.80199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:23:02.440197Z","caller":"traceutil/trace.go:171","msg":"trace[1619245977] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"178.872268ms","start":"2026-08-20T01:23:02.261297Z","end":"2026-08-20T01:23:02.440169Z","steps":["trace[1619245977] 'process raft request' (duration: 42.811498ms)","trace[1619245977] 'compare' (duration: 135.5561ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:23:02.815454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.059002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:23:02.815587Z","caller":"traceutil/trace.go:171","msg":"trace[889073228] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"277.903984ms","start":"2026-08-20T01:23:02.537654Z","end":"2026-08-20T01:23:02.815558Z","steps":["trace[889073228] 'process raft request' (duration: 166.615234ms)","trace[889073228] 'compare' (duration: 110.776891ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:02.839153Z","caller":"traceutil/trace.go:171","msg":"trace[1156857226] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"299.791962ms","start":"2026-08-20T01:23:02.539321Z","end":"2026-08-20T01:23:02.839113Z","steps":["trace[1156857226] 'process raft request' (duration: 299.46995ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.839454Z","caller":"traceutil/trace.go:171","msg":"trace[1380285699] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"295.718185ms","start":"2026-08-20T01:23:02.543699Z","end":"2026-08-20T01:23:02.839417Z","steps":["trace[1380285699] 'process raft request' (duration: 295.236516ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.839489Z","caller":"traceutil/trace.go:171","msg":"trace[1824495648] linearizableReadLoop","detail":"{readStateIndex:5416; appliedIndex:5412; }","duration":"290.70065ms","start":"2026-08-20T01:23:02.548767Z","end":"2026-08-20T01:23:02.839467Z","steps":["trace[1824495648] 'read index received' (duration: 155.513843ms)","trace[1824495648] 'applied index is now lower than readState.Index' (duration: 135.185776ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:23:02.839789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.140969ms","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-08-20T01:23:02.839835Z","caller":"traceutil/trace.go:171","msg":"trace[1960033499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5220; }","duration":"289.214742ms","start":"2026-08-20T01:23:02.550608Z","end":"2026-08-20T01:23:02.839823Z","steps":["trace[1960033499] 'agreement among raft nodes before linearized reading' (duration: 289.029475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.839956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.766307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-08-20T01:23:02.840081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.269562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-20T01:23:02.839604Z","caller":"traceutil/trace.go:171","msg":"trace[1567025039] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"295.888371ms","start":"2026-08-20T01:23:02.543701Z","end":"2026-08-20T01:23:02.839589Z","steps":["trace[1567025039] 'process raft request' (duration: 295.363121ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.840109Z","caller":"traceutil/trace.go:171","msg":"trace[644262351] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5220; }","duration":"138.080898ms","start":"2026-08-20T01:23:02.70201Z","end":"2026-08-20T01:23:02.840091Z","steps":["trace[644262351] 'agreement among raft nodes before linearized reading' (duration: 137.664963ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.840168Z","caller":"traceutil/trace.go:171","msg":"trace[419859471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5220; }","duration":"291.411817ms","start":"2026-08-20T01:23:02.548735Z","end":"2026-08-20T01:23:02.840147Z","steps":["trace[419859471] 'agreement among raft nodes before linearized reading' (duration: 291.082614ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:03.061457Z","caller":"traceutil/trace.go:171","msg":"trace[78723229] linearizableReadLoop","detail":"{readStateIndex:5420; appliedIndex:5419; }","duration":"153.616122ms","start":"2026-08-20T01:23:02.907811Z","end":"2026-08-20T01:23:03.061427Z","steps":["trace[78723229] 'read index received' (duration: 79.206399ms)","trace[78723229] 'applied index is now lower than readState.Index' (duration: 74.408533ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:03.061468Z","caller":"traceutil/trace.go:171","msg":"trace[1708191870] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"195.745144ms","start":"2026-08-20T01:23:02.865689Z","end":"2026-08-20T01:23:03.061434Z","steps":["trace[1708191870] 'process raft request' (duration: 121.474766ms)","trace[1708191870] 'compare' (duration: 74.07965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:23:03.06163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.979833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:23:03.061741Z","caller":"traceutil/trace.go:171","msg":"trace[812805841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5223; }","duration":"196.133539ms","start":"2026-08-20T01:23:02.86558Z","end":"2026-08-20T01:23:03.061713Z","steps":["trace[812805841] 'agreement among raft nodes before linearized reading' (duration: 195.893269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:03.062158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.670865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11520"} {"level":"info","ts":"2026-08-20T01:23:03.062295Z","caller":"traceutil/trace.go:171","msg":"trace[174777075] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5223; }","duration":"152.849842ms","start":"2026-08-20T01:23:02.909419Z","end":"2026-08-20T01:23:03.062269Z","steps":["trace[174777075] 'agreement among raft nodes before linearized reading' (duration: 152.512649ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:19.9029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2853} {"level":"info","ts":"2026-08-20T01:23:20.00594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2853,"took":"101.589349ms","hash":1030770844,"current-db-size-bytes":51236864,"current-db-size":"51 MB","current-db-size-in-use-bytes":27508736,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T01:23:20.005996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1030770844,"revision":2853,"compact-revision":-1} {"level":"info","ts":"2026-08-20T01:23:39.101117Z","caller":"traceutil/trace.go:171","msg":"trace[1047373514] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"153.452292ms","start":"2026-08-20T01:23:38.947631Z","end":"2026-08-20T01:23:39.101083Z","steps":["trace[1047373514] 'process raft request' (duration: 153.296556ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:39.10238Z","caller":"traceutil/trace.go:171","msg":"trace[1158537919] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"138.68233ms","start":"2026-08-20T01:23:38.963677Z","end":"2026-08-20T01:23:39.10236Z","steps":["trace[1158537919] 'process raft request' (duration: 138.057476ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:39.102504Z","caller":"traceutil/trace.go:171","msg":"trace[330517412] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"136.728143ms","start":"2026-08-20T01:23:38.965764Z","end":"2026-08-20T01:23:39.102492Z","steps":["trace[330517412] 'process raft request' (duration: 136.433692ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:39.102592Z","caller":"traceutil/trace.go:171","msg":"trace[818680161] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"136.89631ms","start":"2026-08-20T01:23:38.965688Z","end":"2026-08-20T01:23:39.102584Z","steps":["trace[818680161] 'process raft request' (duration: 136.437162ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:49.293386Z","caller":"traceutil/trace.go:171","msg":"trace[165731494] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"119.526176ms","start":"2026-08-20T01:23:49.173828Z","end":"2026-08-20T01:23:49.293354Z","steps":["trace[165731494] 'process raft request' (duration: 114.432714ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:49.346557Z","caller":"traceutil/trace.go:171","msg":"trace[579093783] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"169.010201ms","start":"2026-08-20T01:23:49.177532Z","end":"2026-08-20T01:23:49.346542Z","steps":["trace[579093783] 'process raft request' (duration: 168.846355ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:49.346854Z","caller":"traceutil/trace.go:171","msg":"trace[2112148238] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"163.67499ms","start":"2026-08-20T01:23:49.183165Z","end":"2026-08-20T01:23:49.34684Z","steps":["trace[2112148238] 'process raft request' (duration: 163.296245ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:24:01.867032Z","caller":"traceutil/trace.go:171","msg":"trace[519274138] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"136.165344ms","start":"2026-08-20T01:24:01.730828Z","end":"2026-08-20T01:24:01.866993Z","steps":["trace[519274138] 'process raft request' (duration: 135.775959ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:24:02.499394Z","caller":"traceutil/trace.go:171","msg":"trace[1158261868] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"120.083818ms","start":"2026-08-20T01:24:02.37928Z","end":"2026-08-20T01:24:02.499364Z","steps":["trace[1158261868] 'process raft request' (duration: 98.427828ms)","trace[1158261868] 'compare' (duration: 21.40477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:26:44.611996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.1604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:26:44.612159Z","caller":"traceutil/trace.go:171","msg":"trace[510770415] linearizableReadLoop","detail":"{readStateIndex:7064; appliedIndex:7063; }","duration":"199.94439ms","start":"2026-08-20T01:26:44.412195Z","end":"2026-08-20T01:26:44.612139Z","steps":["trace[510770415] 'read index received' (duration: 22.553081ms)","trace[510770415] 'applied index is now lower than readState.Index' (duration: 177.389849ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:26:44.612208Z","caller":"traceutil/trace.go:171","msg":"trace[1716046909] transaction","detail":"{read_only:false; response_revision:6814; number_of_response:1; }","duration":"209.335144ms","start":"2026-08-20T01:26:44.402823Z","end":"2026-08-20T01:26:44.612158Z","steps":["trace[1716046909] 'process raft request' (duration: 31.899663ms)","trace[1716046909] 'compare' (duration: 176.992003ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:26:44.612333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.136089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:26:44.612379Z","caller":"traceutil/trace.go:171","msg":"trace[414872761] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:6814; }","duration":"200.19894ms","start":"2026-08-20T01:26:44.412166Z","end":"2026-08-20T01:26:44.612365Z","steps":["trace[414872761] 'agreement among raft nodes before linearized reading' (duration: 200.121867ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:26:47.080057Z","caller":"traceutil/trace.go:171","msg":"trace[1727723170] transaction","detail":"{read_only:false; response_revision:6826; number_of_response:1; }","duration":"153.721693ms","start":"2026-08-20T01:26:46.926301Z","end":"2026-08-20T01:26:47.080023Z","steps":["trace[1727723170] 'process raft request' (duration: 153.490124ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:26:47.496923Z","caller":"traceutil/trace.go:171","msg":"trace[1742293979] transaction","detail":"{read_only:false; response_revision:6827; number_of_response:1; }","duration":"121.144402ms","start":"2026-08-20T01:26:47.375753Z","end":"2026-08-20T01:26:47.496897Z","steps":["trace[1742293979] 'process raft request' (duration: 120.953754ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:26:47.49817Z","caller":"traceutil/trace.go:171","msg":"trace[501217194] transaction","detail":"{read_only:false; response_revision:6828; number_of_response:1; }","duration":"121.651742ms","start":"2026-08-20T01:26:47.376487Z","end":"2026-08-20T01:26:47.498139Z","steps":["trace[501217194] 'process raft request' (duration: 121.160722ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:12.64636Z","caller":"traceutil/trace.go:171","msg":"trace[1199858215] transaction","detail":"{read_only:false; response_revision:7013; number_of_response:1; }","duration":"115.354504ms","start":"2026-08-20T01:27:12.530982Z","end":"2026-08-20T01:27:12.646336Z","steps":["trace[1199858215] 'process raft request' (duration: 115.179277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:12.935945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.389856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T01:27:12.936044Z","caller":"traceutil/trace.go:171","msg":"trace[1333303595] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:7013; }","duration":"136.548093ms","start":"2026-08-20T01:27:12.799477Z","end":"2026-08-20T01:27:12.936026Z","steps":["trace[1333303595] 'range keys from in-memory index tree' (duration: 136.253451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:27:12.936335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.330602ms","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":"warn","ts":"2026-08-20T01:27:12.936399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.028539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:27:12.936448Z","caller":"traceutil/trace.go:171","msg":"trace[1952932378] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7013; }","duration":"249.119913ms","start":"2026-08-20T01:27:12.687313Z","end":"2026-08-20T01:27:12.936433Z","steps":["trace[1952932378] 'count revisions from in-memory index tree' (duration: 248.972997ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:12.936422Z","caller":"traceutil/trace.go:171","msg":"trace[83541788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7013; }","duration":"102.629403ms","start":"2026-08-20T01:27:12.833775Z","end":"2026-08-20T01:27:12.936405Z","steps":["trace[83541788] 'range keys from in-memory index tree' (duration: 102.115843ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:31.617507Z","caller":"traceutil/trace.go:171","msg":"trace[708118890] transaction","detail":"{read_only:false; response_revision:7112; number_of_response:1; }","duration":"181.279832ms","start":"2026-08-20T01:27:31.436198Z","end":"2026-08-20T01:27:31.617478Z","steps":["trace[708118890] 'process raft request' (duration: 181.044973ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:28:19.910126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5323} {"level":"info","ts":"2026-08-20T01:28:20.031832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5323,"took":"119.889808ms","hash":161336603,"current-db-size-bytes":51236864,"current-db-size":"51 MB","current-db-size-in-use-bytes":18739200,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-20T01:28:20.031908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":161336603,"revision":5323,"compact-revision":2853}