{"level":"warn","ts":"2026-05-27T19:37:25.608237Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:37:25.608397Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.103: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.103:2380","--initial-cluster=instance=https://199.19.213.103:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.103:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.103:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T19:37:25.608499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:37:25.608518Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-05-27T19:37:25.608541Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:37:25.609352Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"]} {"level":"info","ts":"2026-05-27T19:37:25.609444Z","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.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.103:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T19:37:25.614391Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.694254ms"} {"level":"info","ts":"2026-05-27T19:37:25.624003Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d82047944f922143","cluster-id":"223a09c0da413f53"} {"level":"info","ts":"2026-05-27T19:37:25.624079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:37:25.624173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 0"} {"level":"info","ts":"2026-05-27T19:37:25.62421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d82047944f922143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:37:25.624218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became follower at term 1"} {"level":"info","ts":"2026-05-27T19:37:25.624252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"warn","ts":"2026-05-27T19:37:25.633873Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:37:25.635154Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:37:25.636341Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T19:37:25.638708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d82047944f922143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:37:25.639068Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d82047944f922143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:37:25.639097Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:37:25.639281Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:37:25.639321Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:37:25.640111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 switched to configuration voters=(15573526213762883907)"} {"level":"info","ts":"2026-05-27T19:37:25.640459Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","added-peer-id":"d82047944f922143","added-peer-peer-urls":["https://199.19.213.103:2380"]} {"level":"info","ts":"2026-05-27T19:37:25.64372Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:37:25.643829Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-05-27T19:37:25.643858Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.103:2380"} {"level":"info","ts":"2026-05-27T19:37:25.643963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d82047944f922143","initial-advertise-peer-urls":["https://199.19.213.103:2380"],"listen-peer-urls":["https://199.19.213.103:2380"],"advertise-client-urls":["https://199.19.213.103:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.103:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:37:25.643988Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:37:25.72509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:37:25.725145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:37:25.725168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgPreVoteResp from d82047944f922143 at term 1"} {"level":"info","ts":"2026-05-27T19:37:25.725186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:37:25.725198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 received MsgVoteResp from d82047944f922143 at term 2"} {"level":"info","ts":"2026-05-27T19:37:25.725214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d82047944f922143 became leader at term 2"} {"level":"info","ts":"2026-05-27T19:37:25.725227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d82047944f922143 elected leader d82047944f922143 at term 2"} {"level":"info","ts":"2026-05-27T19:37:25.72622Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d82047944f922143","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.103:2379]}","request-path":"/0/members/d82047944f922143/attributes","cluster-id":"223a09c0da413f53","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:37:25.726257Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:25.726512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:37:25.726596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:37:25.726867Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:37:25.726937Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:37:25.729022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.103:2379"} {"level":"info","ts":"2026-05-27T19:37:25.729395Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"223a09c0da413f53","local-member-id":"d82047944f922143","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:25.729528Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:25.729574Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:37:25.730186Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:37:27.851651Z","caller":"traceutil/trace.go:171","msg":"trace[1532294000] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"127.688016ms","start":"2026-05-27T19:37:27.723915Z","end":"2026-05-27T19:37:27.851603Z","steps":["trace[1532294000] 'process raft request' (duration: 63.364858ms)","trace[1532294000] 'compare' (duration: 64.183537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:28.355812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396933608839805154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:37:28.872803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.370603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-27T19:37:28.872946Z","caller":"traceutil/trace.go:171","msg":"trace[125014244] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"1.017871939s","start":"2026-05-27T19:37:27.855053Z","end":"2026-05-27T19:37:28.872925Z","steps":["trace[125014244] 'read index received' (duration: 904.787601ms)","trace[125014244] 'applied index is now lower than readState.Index' (duration: 113.082718ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:28.872995Z","caller":"traceutil/trace.go:171","msg":"trace[1982583453] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"1.018372294s","start":"2026-05-27T19:37:27.854585Z","end":"2026-05-27T19:37:28.872957Z","steps":["trace[1982583453] 'process raft request' (duration: 905.303385ms)","trace[1982583453] 'compare' (duration: 112.219561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:37:28.873179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.690457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T19:37:28.873181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018118301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b3834d0f3d1c8c\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-05-27T19:37:28.873234Z","caller":"traceutil/trace.go:171","msg":"trace[267120679] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:81; }","duration":"399.785769ms","start":"2026-05-27T19:37:28.473425Z","end":"2026-05-27T19:37:28.873211Z","steps":["trace[267120679] 'agreement among raft nodes before linearized reading' (duration: 399.644878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:28.873192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.854552Z","time spent":"1.018572336s","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:37:28.873259Z","caller":"traceutil/trace.go:171","msg":"trace[1365412057] range","detail":"{range_begin:/registry/events/default/instance.18b3834d0f3d1c8c; range_end:; response_count:1; response_revision:81; }","duration":"1.018209232s","start":"2026-05-27T19:37:27.855022Z","end":"2026-05-27T19:37:28.873231Z","steps":["trace[1365412057] 'agreement among raft nodes before linearized reading' (duration: 1.01801163s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:28.873308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:27.855013Z","time spent":"1.018287183s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":671,"request content":"key:\"/registry/events/default/instance.18b3834d0f3d1c8c\" "} {"level":"warn","ts":"2026-05-27T19:37:28.873316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.080864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:37:28.873339Z","caller":"traceutil/trace.go:171","msg":"trace[698652683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:81; }","duration":"297.148104ms","start":"2026-05-27T19:37:28.576184Z","end":"2026-05-27T19:37:28.873332Z","steps":["trace[698652683] 'agreement among raft nodes before linearized reading' (duration: 297.114124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:28.87327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:28.4734Z","time spent":"399.861299ms","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-27T19:37:28.873587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.553026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:37:28.873672Z","caller":"traceutil/trace.go:171","msg":"trace[1364109919] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:81; }","duration":"399.630196ms","start":"2026-05-27T19:37:28.473984Z","end":"2026-05-27T19:37:28.873615Z","steps":["trace[1364109919] 'agreement among raft nodes before linearized reading' (duration: 399.562096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:28.873769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:28.473902Z","time spent":"399.851259ms","remote":"127.0.0.1:42194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-27T19:37:29.377036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396933608839805158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:37:29.87768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396933608839805158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:37:30.196588Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.320449099s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:37:30.197015Z","caller":"traceutil/trace.go:171","msg":"trace[2008680111] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"1.321147365s","start":"2026-05-27T19:37:28.875846Z","end":"2026-05-27T19:37:30.196994Z","steps":["trace[2008680111] 'process raft request' (duration: 1.321009163s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:30.197338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:28.875816Z","time spent":"1.321276336s","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:37:30.197811Z","caller":"traceutil/trace.go:171","msg":"trace[206072255] linearizableReadLoop","detail":"{readStateIndex:88; appliedIndex:86; }","duration":"1.321316357s","start":"2026-05-27T19:37:28.876473Z","end":"2026-05-27T19:37:30.197789Z","steps":["trace[206072255] 'read index received' (duration: 1.320469649s)","trace[206072255] 'applied index is now lower than readState.Index' (duration: 845.758µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:30.197858Z","caller":"traceutil/trace.go:171","msg":"trace[2094864916] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"1.321343006s","start":"2026-05-27T19:37:28.876484Z","end":"2026-05-27T19:37:30.197827Z","steps":["trace[2094864916] 'process raft request' (duration: 1.321211615s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:30.19792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321444718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:37:30.197947Z","caller":"traceutil/trace.go:171","msg":"trace[1006401886] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:83; }","duration":"1.321501178s","start":"2026-05-27T19:37:28.876438Z","end":"2026-05-27T19:37:30.197939Z","steps":["trace[1006401886] 'agreement among raft nodes before linearized reading' (duration: 1.321422037s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:30.197975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:28.876426Z","time spent":"1.321542348s","remote":"127.0.0.1:42202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-05-27T19:37:30.197998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:28.87646Z","time spent":"1.321476008s","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:37:30.198124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.964705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T19:37:30.198154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.335976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:37:30.198223Z","caller":"traceutil/trace.go:171","msg":"trace[459246037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:83; }","duration":"621.458286ms","start":"2026-05-27T19:37:29.576749Z","end":"2026-05-27T19:37:30.198207Z","steps":["trace[459246037] 'agreement among raft nodes before linearized reading' (duration: 621.325585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:30.198259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:37:29.57672Z","time spent":"621.532788ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:37:30.1983Z","caller":"traceutil/trace.go:171","msg":"trace[1858773313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:83; }","duration":"149.080196ms","start":"2026-05-27T19:37:30.049096Z","end":"2026-05-27T19:37:30.198176Z","steps":["trace[1858773313] 'agreement among raft nodes before linearized reading' (duration: 148.976805ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:37:32.365894Z","caller":"traceutil/trace.go:171","msg":"trace[200497618] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"136.987632ms","start":"2026-05-27T19:37:32.228879Z","end":"2026-05-27T19:37:32.365867Z","steps":["trace[200497618] 'read index received' (duration: 136.828254ms)","trace[200497618] 'applied index is now lower than readState.Index' (duration: 158.498µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:37:32.366246Z","caller":"traceutil/trace.go:171","msg":"trace[2111563682] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"157.475432ms","start":"2026-05-27T19:37:32.208758Z","end":"2026-05-27T19:37:32.366234Z","steps":["trace[2111563682] 'process raft request' (duration: 157.00687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:32.366375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.676145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-05-27T19:37:32.366453Z","caller":"traceutil/trace.go:171","msg":"trace[492253526] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:267; }","duration":"127.807343ms","start":"2026-05-27T19:37:32.238627Z","end":"2026-05-27T19:37:32.366434Z","steps":["trace[492253526] 'agreement among raft nodes before linearized reading' (duration: 127.613373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:37:32.36662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.724706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-05-27T19:37:32.366662Z","caller":"traceutil/trace.go:171","msg":"trace[527350814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:267; }","duration":"137.778399ms","start":"2026-05-27T19:37:32.228871Z","end":"2026-05-27T19:37:32.366649Z","steps":["trace[527350814] 'agreement among raft nodes before linearized reading' (duration: 137.689585ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:03.230987Z","caller":"traceutil/trace.go:171","msg":"trace[1609300146] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"226.142952ms","start":"2026-05-27T19:38:03.004819Z","end":"2026-05-27T19:38:03.230962Z","steps":["trace[1609300146] 'read index received' (duration: 226.035968ms)","trace[1609300146] 'applied index is now lower than readState.Index' (duration: 105.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:03.231074Z","caller":"traceutil/trace.go:171","msg":"trace[630863325] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"234.054475ms","start":"2026-05-27T19:38:02.996992Z","end":"2026-05-27T19:38:03.231047Z","steps":["trace[630863325] 'process raft request' (duration: 233.783075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:03.231392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.557356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-05-27T19:38:03.231444Z","caller":"traceutil/trace.go:171","msg":"trace[1156023795] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:527; }","duration":"226.621498ms","start":"2026-05-27T19:38:03.004808Z","end":"2026-05-27T19:38:03.231429Z","steps":["trace[1156023795] 'agreement among raft nodes before linearized reading' (duration: 226.254485ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:03.376316Z","caller":"traceutil/trace.go:171","msg":"trace[1926024937] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"147.699288ms","start":"2026-05-27T19:38:03.228595Z","end":"2026-05-27T19:38:03.376294Z","steps":["trace[1926024937] 'process raft request' (duration: 139.012501ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.639679Z","caller":"traceutil/trace.go:171","msg":"trace[1421631090] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"111.638897ms","start":"2026-05-27T19:38:04.528014Z","end":"2026-05-27T19:38:04.639652Z","steps":["trace[1421631090] 'process raft request' (duration: 111.354038ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.885115Z","caller":"traceutil/trace.go:171","msg":"trace[260721572] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"323.365103ms","start":"2026-05-27T19:38:04.561695Z","end":"2026-05-27T19:38:04.88506Z","steps":["trace[260721572] 'process raft request' (duration: 323.091024ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.885257Z","caller":"traceutil/trace.go:171","msg":"trace[757359604] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"315.765957ms","start":"2026-05-27T19:38:04.569455Z","end":"2026-05-27T19:38:04.885221Z","steps":["trace[757359604] 'process raft request' (duration: 315.534269ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.885332Z","caller":"traceutil/trace.go:171","msg":"trace[63799785] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"299.164975ms","start":"2026-05-27T19:38:04.586143Z","end":"2026-05-27T19:38:04.885308Z","steps":["trace[63799785] 'process raft request' (duration: 299.049101ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.88529Z","caller":"traceutil/trace.go:171","msg":"trace[1447700032] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"310.210839ms","start":"2026-05-27T19:38:04.575028Z","end":"2026-05-27T19:38:04.885239Z","steps":["trace[1447700032] 'process raft request' (duration: 310.013242ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.885412Z","caller":"traceutil/trace.go:171","msg":"trace[1526639990] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"299.218066ms","start":"2026-05-27T19:38:04.586182Z","end":"2026-05-27T19:38:04.8854Z","steps":["trace[1526639990] 'process raft request' (duration: 299.052671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:04.885502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:04.575012Z","time spent":"310.403865ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:38:04.885351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:04.561674Z","time spent":"323.57441ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:04.885275Z","caller":"traceutil/trace.go:171","msg":"trace[557807043] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"316.232022ms","start":"2026-05-27T19:38:04.568985Z","end":"2026-05-27T19:38:04.885217Z","steps":["trace[557807043] 'process raft request' (duration: 315.945682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:04.885619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:04.569446Z","time spent":"315.914832ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:04.885712Z","caller":"traceutil/trace.go:171","msg":"trace[1379588588] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"290.846873ms","start":"2026-05-27T19:38:04.59485Z","end":"2026-05-27T19:38:04.885697Z","steps":["trace[1379588588] 'process raft request' (duration: 290.428549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:04.88607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:04.56896Z","time spent":"316.694698ms","remote":"127.0.0.1:41900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:04.886136Z","caller":"traceutil/trace.go:171","msg":"trace[2098524472] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"262.985099ms","start":"2026-05-27T19:38:04.623136Z","end":"2026-05-27T19:38:04.886121Z","steps":["trace[2098524472] 'process raft request' (duration: 262.923957ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.886164Z","caller":"traceutil/trace.go:171","msg":"trace[1994381641] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"273.200365ms","start":"2026-05-27T19:38:04.612945Z","end":"2026-05-27T19:38:04.886146Z","steps":["trace[1994381641] 'process raft request' (duration: 272.953817ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.886035Z","caller":"traceutil/trace.go:171","msg":"trace[166878600] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"291.042009ms","start":"2026-05-27T19:38:04.594956Z","end":"2026-05-27T19:38:04.885998Z","steps":["trace[166878600] 'process raft request' (duration: 290.800601ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.892169Z","caller":"traceutil/trace.go:171","msg":"trace[397641236] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"151.640203ms","start":"2026-05-27T19:38:04.740502Z","end":"2026-05-27T19:38:04.892142Z","steps":["trace[397641236] 'process raft request' (duration: 151.579981ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:04.892148Z","caller":"traceutil/trace.go:171","msg":"trace[1335000484] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"252.90986ms","start":"2026-05-27T19:38:04.639226Z","end":"2026-05-27T19:38:04.892136Z","steps":["trace[1335000484] 'process raft request' (duration: 252.715762ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:10.968712Z","caller":"traceutil/trace.go:171","msg":"trace[1906701363] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"252.302861ms","start":"2026-05-27T19:38:10.716371Z","end":"2026-05-27T19:38:10.968674Z","steps":["trace[1906701363] 'process raft request' (duration: 242.449046ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:11.168105Z","caller":"traceutil/trace.go:171","msg":"trace[1306378261] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"183.528048ms","start":"2026-05-27T19:38:10.984545Z","end":"2026-05-27T19:38:11.168073Z","steps":["trace[1306378261] 'process raft request' (duration: 183.333452ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:11.356716Z","caller":"traceutil/trace.go:171","msg":"trace[716178575] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"191.264125ms","start":"2026-05-27T19:38:11.165425Z","end":"2026-05-27T19:38:11.356689Z","steps":["trace[716178575] 'read index received' (duration: 2.498319ms)","trace[716178575] 'applied index is now lower than readState.Index' (duration: 188.764406ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:11.356794Z","caller":"traceutil/trace.go:171","msg":"trace[1106218769] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"329.157713ms","start":"2026-05-27T19:38:11.027601Z","end":"2026-05-27T19:38:11.356758Z","steps":["trace[1106218769] 'process raft request' (duration: 328.940856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:11.356949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.492692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:38:11.356949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.02758Z","time spent":"329.291727ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:38:11.356983Z","caller":"traceutil/trace.go:171","msg":"trace[370375886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"191.603245ms","start":"2026-05-27T19:38:11.16537Z","end":"2026-05-27T19:38:11.356973Z","steps":["trace[370375886] 'agreement among raft nodes before linearized reading' (duration: 191.496142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:11.357466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:10.986708Z","time spent":"370.751784ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-27T19:38:11.984425Z","caller":"traceutil/trace.go:171","msg":"trace[882932263] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"617.357133ms","start":"2026-05-27T19:38:11.367035Z","end":"2026-05-27T19:38:11.984392Z","steps":["trace[882932263] 'read index received' (duration: 3.138719ms)","trace[882932263] 'applied index is now lower than readState.Index' (duration: 614.217644ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:11.984583Z","caller":"traceutil/trace.go:171","msg":"trace[1449220900] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"622.658861ms","start":"2026-05-27T19:38:11.361888Z","end":"2026-05-27T19:38:11.984547Z","steps":["trace[1449220900] 'process raft request' (duration: 622.380262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:11.984763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.905302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-27T19:38:11.984782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.361862Z","time spent":"622.804365ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:38:11.984841Z","caller":"traceutil/trace.go:171","msg":"trace[1592910987] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:633; }","duration":"613.020315ms","start":"2026-05-27T19:38:11.371805Z","end":"2026-05-27T19:38:11.984825Z","steps":["trace[1592910987] 'agreement among raft nodes before linearized reading' (duration: 612.86202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:11.984884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.371795Z","time spent":"613.080817ms","remote":"127.0.0.1:42010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-27T19:38:11.984926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.386756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:38:11.984782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.681964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:11.98504Z","caller":"traceutil/trace.go:171","msg":"trace[944108458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"258.483239ms","start":"2026-05-27T19:38:11.726494Z","end":"2026-05-27T19:38:11.984978Z","steps":["trace[944108458] 'agreement among raft nodes before linearized reading' (duration: 258.248792ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:11.985128Z","caller":"traceutil/trace.go:171","msg":"trace[1463749067] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:633; }","duration":"618.016323ms","start":"2026-05-27T19:38:11.366996Z","end":"2026-05-27T19:38:11.985012Z","steps":["trace[1463749067] 'agreement among raft nodes before linearized reading' (duration: 617.571289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:11.985222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.366985Z","time spent":"618.190259ms","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-27T19:38:12.394927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.367671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:38:12.395326Z","caller":"traceutil/trace.go:171","msg":"trace[667348086] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"406.288274ms","start":"2026-05-27T19:38:11.989022Z","end":"2026-05-27T19:38:12.395311Z","steps":["trace[667348086] 'process raft request' (duration: 230.488511ms)","trace[667348086] 'compare' (duration: 175.275197ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:12.395363Z","caller":"traceutil/trace.go:171","msg":"trace[662408293] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"405.55143ms","start":"2026-05-27T19:38:11.989795Z","end":"2026-05-27T19:38:12.395347Z","steps":["trace[662408293] 'read index received' (duration: 229.670574ms)","trace[662408293] 'applied index is now lower than readState.Index' (duration: 175.878256ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:12.395391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.989006Z","time spent":"406.347115ms","remote":"127.0.0.1:42090","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-05-27T19:38:12.39546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.711386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-27T19:38:12.395483Z","caller":"traceutil/trace.go:171","msg":"trace[567492638] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:634; }","duration":"405.763417ms","start":"2026-05-27T19:38:11.989712Z","end":"2026-05-27T19:38:12.395476Z","steps":["trace[567492638] 'agreement among raft nodes before linearized reading' (duration: 405.714046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:12.395503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.989697Z","time spent":"405.802158ms","remote":"127.0.0.1:42104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-27T19:38:12.395649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.972643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:12.395787Z","caller":"traceutil/trace.go:171","msg":"trace[670555459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"233.144669ms","start":"2026-05-27T19:38:12.162634Z","end":"2026-05-27T19:38:12.395779Z","steps":["trace[670555459] 'agreement among raft nodes before linearized reading' (duration: 232.983914ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:12.395711Z","caller":"traceutil/trace.go:171","msg":"trace[399693341] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"405.718745ms","start":"2026-05-27T19:38:11.989984Z","end":"2026-05-27T19:38:12.395703Z","steps":["trace[399693341] 'process raft request' (duration: 405.352614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:12.395935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:11.989973Z","time spent":"405.937313ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:38:14.271416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.458573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:14.271583Z","caller":"traceutil/trace.go:171","msg":"trace[1401900053] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"137.678279ms","start":"2026-05-27T19:38:14.133881Z","end":"2026-05-27T19:38:14.271559Z","steps":["trace[1401900053] 'range keys from in-memory index tree' (duration: 137.3826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:14.27145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.236143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:14.271753Z","caller":"traceutil/trace.go:171","msg":"trace[1332277302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"106.534043ms","start":"2026-05-27T19:38:14.165175Z","end":"2026-05-27T19:38:14.271709Z","steps":["trace[1332277302] 'range keys from in-memory index tree' (duration: 106.1302ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:38:17.218317Z","caller":"traceutil/trace.go:171","msg":"trace[54179589] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"110.891173ms","start":"2026-05-27T19:38:17.107396Z","end":"2026-05-27T19:38:17.218288Z","steps":["trace[54179589] 'process raft request' (duration: 110.608284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:27.225438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.275708ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:38:27.22573Z","caller":"traceutil/trace.go:171","msg":"trace[1181059094] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"471.942936ms","start":"2026-05-27T19:38:26.753761Z","end":"2026-05-27T19:38:27.225704Z","steps":["trace[1181059094] 'read index received' (duration: 341.939µs)","trace[1181059094] 'applied index is now lower than readState.Index' (duration: 471.598907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:38:27.227127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.760986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rlz6l\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-27T19:38:27.227351Z","caller":"traceutil/trace.go:171","msg":"trace[1395823104] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rlz6l; range_end:; response_count:1; response_revision:798; }","duration":"212.038304ms","start":"2026-05-27T19:38:27.015288Z","end":"2026-05-27T19:38:27.227326Z","steps":["trace[1395823104] 'agreement among raft nodes before linearized reading' (duration: 210.832311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:27.22765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.867759ms","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-05-27T19:38:27.227745Z","caller":"traceutil/trace.go:171","msg":"trace[571905560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"474.011233ms","start":"2026-05-27T19:38:26.753715Z","end":"2026-05-27T19:38:27.227727Z","steps":["trace[571905560] 'agreement among raft nodes before linearized reading' (duration: 472.292556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:27.227796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:26.753691Z","time spent":"474.095755ms","remote":"127.0.0.1:42090","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":"info","ts":"2026-05-27T19:38:56.488601Z","caller":"traceutil/trace.go:171","msg":"trace[1488410854] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"428.017422ms","start":"2026-05-27T19:38:56.06056Z","end":"2026-05-27T19:38:56.488578Z","steps":["trace[1488410854] 'read index received' (duration: 427.876999ms)","trace[1488410854] 'applied index is now lower than readState.Index' (duration: 139.343µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:38:56.488638Z","caller":"traceutil/trace.go:171","msg":"trace[784530743] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"553.013684ms","start":"2026-05-27T19:38:55.935598Z","end":"2026-05-27T19:38:56.488612Z","steps":["trace[784530743] 'process raft request' (duration: 552.766368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:56.488791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:55.935587Z","time spent":"553.130067ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:38:56.488839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.256587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-msx4m\" ","response":"range_response_count:1 size:5103"} {"level":"info","ts":"2026-05-27T19:38:56.488884Z","caller":"traceutil/trace.go:171","msg":"trace[1808888553] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-548949fc64-msx4m; range_end:; response_count:1; response_revision:1197; }","duration":"428.346939ms","start":"2026-05-27T19:38:56.060523Z","end":"2026-05-27T19:38:56.48887Z","steps":["trace[1808888553] 'agreement among raft nodes before linearized reading' (duration: 428.191906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:56.488926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:56.060508Z","time spent":"428.4103ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":5126,"request content":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-msx4m\" "} {"level":"info","ts":"2026-05-27T19:38:56.512097Z","caller":"traceutil/trace.go:171","msg":"trace[372750530] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"219.567098ms","start":"2026-05-27T19:38:56.292493Z","end":"2026-05-27T19:38:56.51206Z","steps":["trace[372750530] 'process raft request' (duration: 219.439055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:56.512266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.891612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:38:56.512392Z","caller":"traceutil/trace.go:171","msg":"trace[1517525065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"349.051396ms","start":"2026-05-27T19:38:56.163317Z","end":"2026-05-27T19:38:56.512369Z","steps":["trace[1517525065] 'agreement among raft nodes before linearized reading' (duration: 348.75395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:38:56.512442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.999199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T19:38:56.512447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:38:56.163303Z","time spent":"349.131308ms","remote":"127.0.0.1:41864","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-05-27T19:38:56.512489Z","caller":"traceutil/trace.go:171","msg":"trace[1302673903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"265.082182ms","start":"2026-05-27T19:38:56.247393Z","end":"2026-05-27T19:38:56.512475Z","steps":["trace[1302673903] 'agreement among raft nodes before linearized reading' (duration: 264.689623ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:00.826258Z","caller":"traceutil/trace.go:171","msg":"trace[1593131312] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"310.215269ms","start":"2026-05-27T19:39:00.516018Z","end":"2026-05-27T19:39:00.826233Z","steps":["trace[1593131312] 'process raft request' (duration: 310.072676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:00.826423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:00.515986Z","time spent":"310.349852ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:39:00.854807Z","caller":"traceutil/trace.go:171","msg":"trace[1802996507] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"289.303894ms","start":"2026-05-27T19:39:00.565472Z","end":"2026-05-27T19:39:00.854776Z","steps":["trace[1802996507] 'read index received' (duration: 260.805848ms)","trace[1802996507] 'applied index is now lower than readState.Index' (duration: 28.496706ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:00.854928Z","caller":"traceutil/trace.go:171","msg":"trace[4850846] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"324.193155ms","start":"2026-05-27T19:39:00.530699Z","end":"2026-05-27T19:39:00.854892Z","steps":["trace[4850846] 'process raft request' (duration: 323.93566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:00.855093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:00.530683Z","time spent":"324.329929ms","remote":"127.0.0.1:42090","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-05-27T19:39:00.855242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.755433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-27T19:39:00.855335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.276399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T19:39:00.855377Z","caller":"traceutil/trace.go:171","msg":"trace[1712524473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"183.370191ms","start":"2026-05-27T19:39:00.671996Z","end":"2026-05-27T19:39:00.855366Z","steps":["trace[1712524473] 'agreement among raft nodes before linearized reading' (duration: 183.231757ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:00.855446Z","caller":"traceutil/trace.go:171","msg":"trace[2010574963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"289.930308ms","start":"2026-05-27T19:39:00.56542Z","end":"2026-05-27T19:39:00.855351Z","steps":["trace[2010574963] 'agreement among raft nodes before linearized reading' (duration: 289.62419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:00.855542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.349127ms","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-05-27T19:39:00.855654Z","caller":"traceutil/trace.go:171","msg":"trace[1787396424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"180.533001ms","start":"2026-05-27T19:39:00.675095Z","end":"2026-05-27T19:39:00.855628Z","steps":["trace[1787396424] 'agreement among raft nodes before linearized reading' (duration: 180.190903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:01.892701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.656103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:39:01.892911Z","caller":"traceutil/trace.go:171","msg":"trace[1381215184] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1255; }","duration":"910.125407ms","start":"2026-05-27T19:39:00.982765Z","end":"2026-05-27T19:39:01.89289Z","steps":["trace[1381215184] 'read index received' (duration: 195.184418ms)","trace[1381215184] 'applied index is now lower than readState.Index' (duration: 714.939369ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:01.893023Z","caller":"traceutil/trace.go:171","msg":"trace[1651750310] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"1.031120841s","start":"2026-05-27T19:39:00.861886Z","end":"2026-05-27T19:39:01.893007Z","steps":["trace[1651750310] 'process raft request' (duration: 1.030921647s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:01.893096Z","caller":"traceutil/trace.go:171","msg":"trace[1370289233] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"1.03152196s","start":"2026-05-27T19:39:00.861541Z","end":"2026-05-27T19:39:01.893063Z","steps":["trace[1370289233] 'process raft request' (duration: 316.400747ms)","trace[1370289233] 'compare' (duration: 714.211484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:01.893171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:00.861843Z","time spent":"1.031212813s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:39:01.893238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:00.861529Z","time spent":"1.031633002s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:39:01.893413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.734844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-05-27T19:39:01.893452Z","caller":"traceutil/trace.go:171","msg":"trace[169911907] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1219; }","duration":"889.811906ms","start":"2026-05-27T19:39:01.003628Z","end":"2026-05-27T19:39:01.893439Z","steps":["trace[169911907] 'agreement among raft nodes before linearized reading' (duration: 889.742514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:01.893491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:01.003599Z","time spent":"889.876606ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-05-27T19:39:01.893731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.979935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.103\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T19:39:01.893758Z","caller":"traceutil/trace.go:171","msg":"trace[1632164843] range","detail":"{range_begin:/registry/masterleases/199.19.213.103; range_end:; response_count:1; response_revision:1219; }","duration":"911.057757ms","start":"2026-05-27T19:39:00.982692Z","end":"2026-05-27T19:39:01.89375Z","steps":["trace[1632164843] 'agreement among raft nodes before linearized reading' (duration: 910.949445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:01.893783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:00.982663Z","time spent":"911.114418ms","remote":"127.0.0.1:41878","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.103\" "} {"level":"warn","ts":"2026-05-27T19:39:01.893943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.30465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:01.893998Z","caller":"traceutil/trace.go:171","msg":"trace[734786099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"727.405642ms","start":"2026-05-27T19:39:01.166576Z","end":"2026-05-27T19:39:01.893982Z","steps":["trace[734786099] 'agreement among raft nodes before linearized reading' (duration: 727.31802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:01.894041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:01.16654Z","time spent":"727.490244ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:39:01.894399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.406521ms","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-05-27T19:39:01.894447Z","caller":"traceutil/trace.go:171","msg":"trace[256318562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1219; }","duration":"505.490252ms","start":"2026-05-27T19:39:01.388942Z","end":"2026-05-27T19:39:01.894432Z","steps":["trace[256318562] 'agreement among raft nodes before linearized reading' (duration: 505.289408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:01.894598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:01.388921Z","time spent":"505.662507ms","remote":"127.0.0.1:42090","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":"info","ts":"2026-05-27T19:39:15.074275Z","caller":"traceutil/trace.go:171","msg":"trace[1208244315] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"117.647928ms","start":"2026-05-27T19:39:14.956608Z","end":"2026-05-27T19:39:15.074256Z","steps":["trace[1208244315] 'read index received' (duration: 117.545616ms)","trace[1208244315] 'applied index is now lower than readState.Index' (duration: 101.332µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:15.074344Z","caller":"traceutil/trace.go:171","msg":"trace[1936034912] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"133.15342ms","start":"2026-05-27T19:39:14.941153Z","end":"2026-05-27T19:39:15.074306Z","steps":["trace[1936034912] 'process raft request' (duration: 132.961736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:15.074385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.76596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:15.07456Z","caller":"traceutil/trace.go:171","msg":"trace[2142299337] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:1320; }","duration":"117.962054ms","start":"2026-05-27T19:39:14.956557Z","end":"2026-05-27T19:39:15.074519Z","steps":["trace[2142299337] 'agreement among raft nodes before linearized reading' (duration: 117.78255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:17.132138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.594215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:39:17.132455Z","caller":"traceutil/trace.go:171","msg":"trace[1882302250] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"445.600733ms","start":"2026-05-27T19:39:16.686818Z","end":"2026-05-27T19:39:17.132418Z","steps":["trace[1882302250] 'process raft request' (duration: 284.683242ms)","trace[1882302250] 'compare' (duration: 160.32403ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:17.132808Z","caller":"traceutil/trace.go:171","msg":"trace[619894613] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"227.624327ms","start":"2026-05-27T19:39:16.905173Z","end":"2026-05-27T19:39:17.132797Z","steps":["trace[619894613] 'process raft request' (duration: 227.040096ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:17.132805Z","caller":"traceutil/trace.go:171","msg":"trace[1772150770] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"234.549232ms","start":"2026-05-27T19:39:16.898233Z","end":"2026-05-27T19:39:17.132782Z","steps":["trace[1772150770] 'read index received' (duration: 73.280194ms)","trace[1772150770] 'applied index is now lower than readState.Index' (duration: 161.267118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:17.133126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.880858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:39:17.133164Z","caller":"traceutil/trace.go:171","msg":"trace[1211270611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1331; }","duration":"234.96919ms","start":"2026-05-27T19:39:16.898185Z","end":"2026-05-27T19:39:17.133154Z","steps":["trace[1211270611] 'agreement among raft nodes before linearized reading' (duration: 234.807417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:17.133407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:16.686799Z","time spent":"445.744667ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1056,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:39:17.252458Z","caller":"traceutil/trace.go:171","msg":"trace[1727388047] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1377; }","duration":"109.929049ms","start":"2026-05-27T19:39:17.142505Z","end":"2026-05-27T19:39:17.252434Z","steps":["trace[1727388047] 'read index received' (duration: 49.806295ms)","trace[1727388047] 'applied index is now lower than readState.Index' (duration: 60.121854ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:17.252595Z","caller":"traceutil/trace.go:171","msg":"trace[365530109] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"114.962396ms","start":"2026-05-27T19:39:17.137619Z","end":"2026-05-27T19:39:17.252581Z","steps":["trace[365530109] 'process raft request' (duration: 107.189237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:17.253041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.51296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:17.253146Z","caller":"traceutil/trace.go:171","msg":"trace[1689010180] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:1333; }","duration":"110.672213ms","start":"2026-05-27T19:39:17.142449Z","end":"2026-05-27T19:39:17.253122Z","steps":["trace[1689010180] 'agreement among raft nodes before linearized reading' (duration: 110.51119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:17.613352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.501682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:39:17.615018Z","caller":"traceutil/trace.go:171","msg":"trace[906987735] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"320.188183ms","start":"2026-05-27T19:39:17.294808Z","end":"2026-05-27T19:39:17.614996Z","steps":["trace[906987735] 'process raft request' (duration: 319.967059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:17.615147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:17.294777Z","time spent":"320.305216ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105319,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:39:25.616582Z","caller":"traceutil/trace.go:171","msg":"trace[1749733834] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"311.596498ms","start":"2026-05-27T19:39:25.304963Z","end":"2026-05-27T19:39:25.61656Z","steps":["trace[1749733834] 'process raft request' (duration: 311.457975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:25.616722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:25.304924Z","time spent":"311.725331ms","remote":"127.0.0.1:42090","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-05-27T19:39:26.256875Z","caller":"traceutil/trace.go:171","msg":"trace[348016062] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1533; }","duration":"159.251393ms","start":"2026-05-27T19:39:26.097602Z","end":"2026-05-27T19:39:26.256853Z","steps":["trace[348016062] 'read index received' (duration: 159.09443ms)","trace[348016062] 'applied index is now lower than readState.Index' (duration: 156.183µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:26.256936Z","caller":"traceutil/trace.go:171","msg":"trace[218546963] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"162.466592ms","start":"2026-05-27T19:39:26.094431Z","end":"2026-05-27T19:39:26.256897Z","steps":["trace[218546963] 'process raft request' (duration: 162.296759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:26.257064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.442676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:39:26.257087Z","caller":"traceutil/trace.go:171","msg":"trace[653873953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1485; }","duration":"159.493447ms","start":"2026-05-27T19:39:26.097585Z","end":"2026-05-27T19:39:26.257079Z","steps":["trace[653873953] 'agreement among raft nodes before linearized reading' (duration: 159.378185ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:27.870917Z","caller":"traceutil/trace.go:171","msg":"trace[1904745934] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"247.980251ms","start":"2026-05-27T19:39:27.622902Z","end":"2026-05-27T19:39:27.870882Z","steps":["trace[1904745934] 'process raft request' (duration: 247.772318ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:29.833079Z","caller":"traceutil/trace.go:171","msg":"trace[1633892259] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"232.688252ms","start":"2026-05-27T19:39:29.600362Z","end":"2026-05-27T19:39:29.833051Z","steps":["trace[1633892259] 'read index received' (duration: 232.402597ms)","trace[1633892259] 'applied index is now lower than readState.Index' (duration: 284.525µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:29.833299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.910006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:29.83333Z","caller":"traceutil/trace.go:171","msg":"trace[1117255498] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1498; }","duration":"233.026168ms","start":"2026-05-27T19:39:29.600295Z","end":"2026-05-27T19:39:29.833321Z","steps":["trace[1117255498] 'agreement among raft nodes before linearized reading' (duration: 232.870735ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:29.833489Z","caller":"traceutil/trace.go:171","msg":"trace[517829029] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"256.226437ms","start":"2026-05-27T19:39:29.577232Z","end":"2026-05-27T19:39:29.833459Z","steps":["trace[517829029] 'process raft request' (duration: 255.620357ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:31.036024Z","caller":"traceutil/trace.go:171","msg":"trace[1011588102] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"123.696984ms","start":"2026-05-27T19:39:30.912289Z","end":"2026-05-27T19:39:31.035986Z","steps":["trace[1011588102] 'process raft request' (duration: 123.540301ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:31.748509Z","caller":"traceutil/trace.go:171","msg":"trace[900219487] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"117.065488ms","start":"2026-05-27T19:39:31.631398Z","end":"2026-05-27T19:39:31.748463Z","steps":["trace[900219487] 'process raft request' (duration: 37.375196ms)","trace[900219487] 'compare' (duration: 79.477108ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:31.788543Z","caller":"traceutil/trace.go:171","msg":"trace[1989105849] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"156.848418ms","start":"2026-05-27T19:39:31.631652Z","end":"2026-05-27T19:39:31.788501Z","steps":["trace[1989105849] 'process raft request' (duration: 156.574683ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:31.788599Z","caller":"traceutil/trace.go:171","msg":"trace[1443649060] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"152.655644ms","start":"2026-05-27T19:39:31.635902Z","end":"2026-05-27T19:39:31.788557Z","steps":["trace[1443649060] 'process raft request' (duration: 152.483391ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:32.620337Z","caller":"traceutil/trace.go:171","msg":"trace[2115497656] linearizableReadLoop","detail":"{readStateIndex:1610; appliedIndex:1609; }","duration":"231.031433ms","start":"2026-05-27T19:39:32.389277Z","end":"2026-05-27T19:39:32.620309Z","steps":["trace[2115497656] 'read index received' (duration: 162.137699ms)","trace[2115497656] 'applied index is now lower than readState.Index' (duration: 68.891744ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:32.620425Z","caller":"traceutil/trace.go:171","msg":"trace[138556138] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"234.038225ms","start":"2026-05-27T19:39:32.386354Z","end":"2026-05-27T19:39:32.620392Z","steps":["trace[138556138] 'process raft request' (duration: 165.170652ms)","trace[138556138] 'compare' (duration: 68.604859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:32.620498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.209926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:32.620581Z","caller":"traceutil/trace.go:171","msg":"trace[1448983369] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com; range_end:; response_count:0; response_revision:1558; }","duration":"231.358498ms","start":"2026-05-27T19:39:32.389203Z","end":"2026-05-27T19:39:32.620562Z","steps":["trace[1448983369] 'agreement among raft nodes before linearized reading' (duration: 231.185445ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:41.221538Z","caller":"traceutil/trace.go:171","msg":"trace[1628857682] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"133.759221ms","start":"2026-05-27T19:39:41.087748Z","end":"2026-05-27T19:39:41.221507Z","steps":["trace[1628857682] 'process raft request' (duration: 133.480356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.197726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032356226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:56.19781Z","caller":"traceutil/trace.go:171","msg":"trace[192251387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1919; }","duration":"1.032480288s","start":"2026-05-27T19:39:55.16531Z","end":"2026-05-27T19:39:56.19779Z","steps":["trace[192251387] 'range keys from in-memory index tree' (duration: 1.032248514s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.19785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:55.165291Z","time spent":"1.032550229s","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:39:56.198662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.287636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:39:56.198766Z","caller":"traceutil/trace.go:171","msg":"trace[2109051260] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"695.313621ms","start":"2026-05-27T19:39:55.503437Z","end":"2026-05-27T19:39:56.19875Z","steps":["trace[2109051260] 'read index received' (duration: 416.669549ms)","trace[2109051260] 'applied index is now lower than readState.Index' (duration: 278.642682ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:56.198836Z","caller":"traceutil/trace.go:171","msg":"trace[2143735940] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"753.427647ms","start":"2026-05-27T19:39:55.445395Z","end":"2026-05-27T19:39:56.198822Z","steps":["trace[2143735940] 'process raft request' (duration: 474.640423ms)","trace[2143735940] 'compare' (duration: 277.781598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:56.19893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:55.445367Z","time spent":"753.52999ms","remote":"127.0.0.1:42090","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-05-27T19:39:56.199035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.599296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:39:56.19908Z","caller":"traceutil/trace.go:171","msg":"trace[117859435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1920; }","duration":"695.680128ms","start":"2026-05-27T19:39:55.503388Z","end":"2026-05-27T19:39:56.199068Z","steps":["trace[117859435] 'agreement among raft nodes before linearized reading' (duration: 695.540245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.199112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:55.503369Z","time spent":"695.735589ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:39:56.239204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.35987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b20f436e-36c3-4fd0-9adc-f23a6db3f26a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-27T19:39:56.239289Z","caller":"traceutil/trace.go:171","msg":"trace[413983396] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b20f436e-36c3-4fd0-9adc-f23a6db3f26a; range_end:; response_count:1; response_revision:1921; }","duration":"182.487543ms","start":"2026-05-27T19:39:56.056781Z","end":"2026-05-27T19:39:56.239268Z","steps":["trace[413983396] 'agreement among raft nodes before linearized reading' (duration: 182.282959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.239309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.201984ms","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-05-27T19:39:56.239416Z","caller":"traceutil/trace.go:171","msg":"trace[1782002063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1921; }","duration":"211.346657ms","start":"2026-05-27T19:39:56.02803Z","end":"2026-05-27T19:39:56.239377Z","steps":["trace[1782002063] 'agreement among raft nodes before linearized reading' (duration: 211.080533ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:56.239615Z","caller":"traceutil/trace.go:171","msg":"trace[415381921] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"502.453269ms","start":"2026-05-27T19:39:55.736829Z","end":"2026-05-27T19:39:56.239282Z","steps":["trace[415381921] 'process raft request' (duration: 502.087423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.239981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:55.736805Z","time spent":"502.920026ms","remote":"127.0.0.1:42090","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-05-27T19:39:56.24118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.334628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b20f436e-36c3-4fd0-9adc-f23a6db3f26a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-27T19:39:56.241267Z","caller":"traceutil/trace.go:171","msg":"trace[172781576] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b20f436e-36c3-4fd0-9adc-f23a6db3f26a; range_end:; response_count:1; response_revision:1921; }","duration":"564.511929ms","start":"2026-05-27T19:39:55.676735Z","end":"2026-05-27T19:39:56.241246Z","steps":["trace[172781576] 'agreement among raft nodes before linearized reading' (duration: 562.431496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.241311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:55.676716Z","time spent":"564.584671ms","remote":"127.0.0.1:42026","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-b20f436e-36c3-4fd0-9adc-f23a6db3f26a\" "} {"level":"info","ts":"2026-05-27T19:39:56.567903Z","caller":"traceutil/trace.go:171","msg":"trace[1434341961] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"324.429123ms","start":"2026-05-27T19:39:56.243442Z","end":"2026-05-27T19:39:56.567871Z","steps":["trace[1434341961] 'process raft request' (duration: 324.2304ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:39:56.568086Z","caller":"traceutil/trace.go:171","msg":"trace[694054701] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1998; }","duration":"312.978078ms","start":"2026-05-27T19:39:56.255072Z","end":"2026-05-27T19:39:56.56805Z","steps":["trace[694054701] 'read index received' (duration: 312.671983ms)","trace[694054701] 'applied index is now lower than readState.Index' (duration: 303.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:56.568295Z","caller":"traceutil/trace.go:171","msg":"trace[853763667] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"319.696677ms","start":"2026-05-27T19:39:56.248398Z","end":"2026-05-27T19:39:56.568095Z","steps":["trace[853763667] 'process raft request' (duration: 319.409132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.568323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.243426Z","time spent":"324.548815ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:39:56.568409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.315033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:39:56.568458Z","caller":"traceutil/trace.go:171","msg":"trace[1593779534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1924; }","duration":"313.384604ms","start":"2026-05-27T19:39:56.255058Z","end":"2026-05-27T19:39:56.568443Z","steps":["trace[1593779534] 'agreement among raft nodes before linearized reading' (duration: 313.250252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.568505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.255013Z","time spent":"313.484376ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:39:56.569044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.248383Z","time spent":"320.044822ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:39:56.651276Z","caller":"traceutil/trace.go:171","msg":"trace[1766634430] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"188.790683ms","start":"2026-05-27T19:39:56.462439Z","end":"2026-05-27T19:39:56.651229Z","steps":["trace[1766634430] 'process raft request' (duration: 188.460988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:56.727333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.579334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:39:56.727437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.976167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:39:56.727485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.694164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:39:56.727491Z","caller":"traceutil/trace.go:171","msg":"trace[951698062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1925; }","duration":"337.756916ms","start":"2026-05-27T19:39:56.389698Z","end":"2026-05-27T19:39:56.727455Z","steps":["trace[951698062] 'agreement among raft nodes before linearized reading' (duration: 261.503871ms)","trace[951698062] 'range keys from in-memory index tree' (duration: 76.019991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:39:56.727583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.389679Z","time spent":"337.890288ms","remote":"127.0.0.1:42090","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":"info","ts":"2026-05-27T19:39:56.727539Z","caller":"traceutil/trace.go:171","msg":"trace[1021510563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"256.777535ms","start":"2026-05-27T19:39:56.47074Z","end":"2026-05-27T19:39:56.727518Z","steps":["trace[1021510563] 'agreement among raft nodes before linearized reading' (duration: 180.459149ms)","trace[1021510563] 'range keys from in-memory index tree' (duration: 76.066272ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:56.727487Z","caller":"traceutil/trace.go:171","msg":"trace[1074724942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"265.057799ms","start":"2026-05-27T19:39:56.462412Z","end":"2026-05-27T19:39:56.72747Z","steps":["trace[1074724942] 'agreement among raft nodes before linearized reading' (duration: 188.813514ms)","trace[1074724942] 'range keys from in-memory index tree' (duration: 76.007121ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:39:57.196413Z","caller":"traceutil/trace.go:171","msg":"trace[807946451] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"480.375449ms","start":"2026-05-27T19:39:56.716007Z","end":"2026-05-27T19:39:57.196383Z","steps":["trace[807946451] 'process raft request' (duration: 480.166405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:57.196726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.715979Z","time spent":"480.631073ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:39:57.201745Z","caller":"traceutil/trace.go:171","msg":"trace[1260625253] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"469.95382ms","start":"2026-05-27T19:39:56.731779Z","end":"2026-05-27T19:39:57.201733Z","steps":["trace[1260625253] 'process raft request' (duration: 469.847628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:57.201817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.731751Z","time spent":"470.02709ms","remote":"127.0.0.1:42090","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-05-27T19:39:57.202089Z","caller":"traceutil/trace.go:171","msg":"trace[680981781] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"470.122813ms","start":"2026-05-27T19:39:56.731954Z","end":"2026-05-27T19:39:57.202076Z","steps":["trace[680981781] 'process raft request' (duration: 469.979861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:57.202144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.731934Z","time spent":"470.185014ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:39:57.202257Z","caller":"traceutil/trace.go:171","msg":"trace[1969676803] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"470.269355ms","start":"2026-05-27T19:39:56.731931Z","end":"2026-05-27T19:39:57.2022Z","steps":["trace[1969676803] 'process raft request' (duration: 469.774626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:57.202348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.731914Z","time spent":"470.411768ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:39:57.202411Z","caller":"traceutil/trace.go:171","msg":"trace[1685225785] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"418.975721ms","start":"2026-05-27T19:39:56.783429Z","end":"2026-05-27T19:39:57.202405Z","steps":["trace[1685225785] 'process raft request' (duration: 418.576625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:39:57.202446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:39:56.783404Z","time spent":"419.023833ms","remote":"127.0.0.1:42090","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-05-27T19:40:02.061958Z","caller":"traceutil/trace.go:171","msg":"trace[1014135548] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"158.516529ms","start":"2026-05-27T19:40:01.903418Z","end":"2026-05-27T19:40:02.061935Z","steps":["trace[1014135548] 'process raft request' (duration: 158.382017ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.359212Z","caller":"traceutil/trace.go:171","msg":"trace[1184444169] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"135.375893ms","start":"2026-05-27T19:40:09.22379Z","end":"2026-05-27T19:40:09.359166Z","steps":["trace[1184444169] 'process raft request' (duration: 135.19564ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.363981Z","caller":"traceutil/trace.go:171","msg":"trace[508729485] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"102.652069ms","start":"2026-05-27T19:40:09.261297Z","end":"2026-05-27T19:40:09.363949Z","steps":["trace[508729485] 'process raft request' (duration: 102.533438ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.363972Z","caller":"traceutil/trace.go:171","msg":"trace[591366635] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"130.987696ms","start":"2026-05-27T19:40:09.232944Z","end":"2026-05-27T19:40:09.363932Z","steps":["trace[591366635] 'process raft request' (duration: 130.737422ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.494982Z","caller":"traceutil/trace.go:171","msg":"trace[1920840461] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"127.646235ms","start":"2026-05-27T19:40:09.367308Z","end":"2026-05-27T19:40:09.494955Z","steps":["trace[1920840461] 'process raft request' (duration: 127.542113ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.495012Z","caller":"traceutil/trace.go:171","msg":"trace[2010429387] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"127.674065ms","start":"2026-05-27T19:40:09.367307Z","end":"2026-05-27T19:40:09.494981Z","steps":["trace[2010429387] 'process raft request' (duration: 127.35421ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.525001Z","caller":"traceutil/trace.go:171","msg":"trace[454143016] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"156.865533ms","start":"2026-05-27T19:40:09.368119Z","end":"2026-05-27T19:40:09.524984Z","steps":["trace[454143016] 'process raft request' (duration: 156.728701ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.525242Z","caller":"traceutil/trace.go:171","msg":"trace[1951699188] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"157.005885ms","start":"2026-05-27T19:40:09.368221Z","end":"2026-05-27T19:40:09.525227Z","steps":["trace[1951699188] 'process raft request' (duration: 156.72111ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:09.52561Z","caller":"traceutil/trace.go:171","msg":"trace[1551667108] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"157.167598ms","start":"2026-05-27T19:40:09.368423Z","end":"2026-05-27T19:40:09.525591Z","steps":["trace[1551667108] 'process raft request' (duration: 156.747351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:36.06602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.537835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:36.06614Z","caller":"traceutil/trace.go:171","msg":"trace[230147125] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"418.274573ms","start":"2026-05-27T19:40:35.647848Z","end":"2026-05-27T19:40:36.066122Z","steps":["trace[230147125] 'process raft request' (duration: 59.566937ms)","trace[230147125] 'compare' (duration: 357.921115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:36.0662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:35.647835Z","time spent":"418.336704ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:36.219024Z","caller":"traceutil/trace.go:171","msg":"trace[2029506741] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"432.105487ms","start":"2026-05-27T19:40:35.78687Z","end":"2026-05-27T19:40:36.218975Z","steps":["trace[2029506741] 'read index received' (duration: 409.693256ms)","trace[2029506741] 'applied index is now lower than readState.Index' (duration: 22.410491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:36.219461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.565335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:40:36.2196Z","caller":"traceutil/trace.go:171","msg":"trace[1539039181] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2187; }","duration":"432.752738ms","start":"2026-05-27T19:40:35.78682Z","end":"2026-05-27T19:40:36.219573Z","steps":["trace[1539039181] 'agreement among raft nodes before linearized reading' (duration: 432.535205ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:36.219635Z","caller":"traceutil/trace.go:171","msg":"trace[1886654024] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"565.255733ms","start":"2026-05-27T19:40:35.654343Z","end":"2026-05-27T19:40:36.219599Z","steps":["trace[1886654024] 'process raft request' (duration: 542.292813ms)","trace[1886654024] 'compare' (duration: 22.134747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:36.21975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:35.786799Z","time spent":"432.93651ms","remote":"127.0.0.1:42216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:40:36.219817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:35.654333Z","time spent":"565.358354ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:36.583097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.942504ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:40:36.583435Z","caller":"traceutil/trace.go:171","msg":"trace[1529226510] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2272; }","duration":"139.547373ms","start":"2026-05-27T19:40:36.443866Z","end":"2026-05-27T19:40:36.583414Z","steps":["trace[1529226510] 'read index received' (duration: 112.728598ms)","trace[1529226510] 'applied index is now lower than readState.Index' (duration: 26.817635ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:36.583558Z","caller":"traceutil/trace.go:171","msg":"trace[476654886] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"195.553607ms","start":"2026-05-27T19:40:36.387993Z","end":"2026-05-27T19:40:36.583547Z","steps":["trace[476654886] 'process raft request' (duration: 195.278053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:36.583808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.939928ms","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-05-27T19:40:36.583851Z","caller":"traceutil/trace.go:171","msg":"trace[729327041] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2188; }","duration":"140.03395ms","start":"2026-05-27T19:40:36.443804Z","end":"2026-05-27T19:40:36.583838Z","steps":["trace[729327041] 'agreement among raft nodes before linearized reading' (duration: 139.900328ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:36.72693Z","caller":"traceutil/trace.go:171","msg":"trace[1407568976] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"134.4692ms","start":"2026-05-27T19:40:36.592436Z","end":"2026-05-27T19:40:36.726905Z","steps":["trace[1407568976] 'process raft request' (duration: 134.405569ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:36.726967Z","caller":"traceutil/trace.go:171","msg":"trace[1228817321] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"138.128333ms","start":"2026-05-27T19:40:36.588811Z","end":"2026-05-27T19:40:36.72694Z","steps":["trace[1228817321] 'process raft request' (duration: 79.053765ms)","trace[1228817321] 'compare' (duration: 58.831705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:37.538863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.84291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:37.538976Z","caller":"traceutil/trace.go:171","msg":"trace[389836924] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2277; }","duration":"736.811767ms","start":"2026-05-27T19:40:36.802144Z","end":"2026-05-27T19:40:37.538956Z","steps":["trace[389836924] 'read index received' (duration: 379.816914ms)","trace[389836924] 'applied index is now lower than readState.Index' (duration: 356.993313ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:37.539063Z","caller":"traceutil/trace.go:171","msg":"trace[396275073] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"740.180276ms","start":"2026-05-27T19:40:36.798865Z","end":"2026-05-27T19:40:37.539045Z","steps":["trace[396275073] 'process raft request' (duration: 383.064272ms)","trace[396275073] 'compare' (duration: 356.382063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:37.539148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:36.798841Z","time spent":"740.262867ms","remote":"127.0.0.1:42090","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-05-27T19:40:37.539357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.196552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T19:40:37.539436Z","caller":"traceutil/trace.go:171","msg":"trace[131763671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2192; }","duration":"737.324553ms","start":"2026-05-27T19:40:36.802094Z","end":"2026-05-27T19:40:37.539419Z","steps":["trace[131763671] 'agreement among raft nodes before linearized reading' (duration: 737.09904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:37.539479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:36.802074Z","time spent":"737.397085ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T19:40:37.561584Z","caller":"traceutil/trace.go:171","msg":"trace[1246325287] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"180.760829ms","start":"2026-05-27T19:40:37.380801Z","end":"2026-05-27T19:40:37.561562Z","steps":["trace[1246325287] 'process raft request' (duration: 180.663647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:37.568137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.911116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:37.568238Z","caller":"traceutil/trace.go:171","msg":"trace[432229288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"400.056589ms","start":"2026-05-27T19:40:37.168169Z","end":"2026-05-27T19:40:37.568226Z","steps":["trace[432229288] 'agreement among raft nodes before linearized reading' (duration: 399.909566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:37.568274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:37.168152Z","time spent":"400.114999ms","remote":"127.0.0.1:41864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:40:38.245564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.269914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:40:38.245681Z","caller":"traceutil/trace.go:171","msg":"trace[1813695405] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2197; }","duration":"298.451796ms","start":"2026-05-27T19:40:37.9472Z","end":"2026-05-27T19:40:38.245652Z","steps":["trace[1813695405] 'count revisions from in-memory index tree' (duration: 298.148972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:38.245968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.495562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:38.246049Z","caller":"traceutil/trace.go:171","msg":"trace[1019030007] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2197; }","duration":"408.622843ms","start":"2026-05-27T19:40:37.837409Z","end":"2026-05-27T19:40:38.246032Z","steps":["trace[1019030007] 'count revisions from in-memory index tree' (duration: 408.399251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:38.246093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:37.837389Z","time spent":"408.692135ms","remote":"127.0.0.1:42060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:40:38.245967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.076011ms","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-05-27T19:40:38.24632Z","caller":"traceutil/trace.go:171","msg":"trace[1691806830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2197; }","duration":"175.494466ms","start":"2026-05-27T19:40:38.070819Z","end":"2026-05-27T19:40:38.246314Z","steps":["trace[1691806830] 'range keys from in-memory index tree' (duration: 174.662056ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:38.695591Z","caller":"traceutil/trace.go:171","msg":"trace[1059022865] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"445.10205ms","start":"2026-05-27T19:40:38.250461Z","end":"2026-05-27T19:40:38.695563Z","steps":["trace[1059022865] 'process raft request' (duration: 444.893767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:38.695746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:38.250434Z","time spent":"445.234042ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:38.703376Z","caller":"traceutil/trace.go:171","msg":"trace[971877018] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2283; }","duration":"360.108775ms","start":"2026-05-27T19:40:38.343246Z","end":"2026-05-27T19:40:38.703355Z","steps":["trace[971877018] 'read index received' (duration: 352.112771ms)","trace[971877018] 'applied index is now lower than readState.Index' (duration: 7.995064ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:38.703404Z","caller":"traceutil/trace.go:171","msg":"trace[955248687] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"452.224341ms","start":"2026-05-27T19:40:38.251162Z","end":"2026-05-27T19:40:38.703386Z","steps":["trace[955248687] 'process raft request' (duration: 452.036919ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:38.703496Z","caller":"traceutil/trace.go:171","msg":"trace[1106251541] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"114.519136ms","start":"2026-05-27T19:40:38.588937Z","end":"2026-05-27T19:40:38.703456Z","steps":["trace[1106251541] 'process raft request' (duration: 114.376334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:38.70357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.314398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b38376a4b7c6ca\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-27T19:40:38.703614Z","caller":"traceutil/trace.go:171","msg":"trace[333256720] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b38376a4b7c6ca; range_end:; response_count:1; response_revision:2200; }","duration":"360.390559ms","start":"2026-05-27T19:40:38.343206Z","end":"2026-05-27T19:40:38.703597Z","steps":["trace[333256720] 'agreement among raft nodes before linearized reading' (duration: 360.256877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:38.703648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:38.34319Z","time spent":"360.45236ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b38376a4b7c6ca\" "} {"level":"warn","ts":"2026-05-27T19:40:38.703668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:38.251147Z","time spent":"452.339253ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:40:38.703855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.004959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-05-27T19:40:38.703949Z","caller":"traceutil/trace.go:171","msg":"trace[383506557] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2200; }","duration":"219.143392ms","start":"2026-05-27T19:40:38.484785Z","end":"2026-05-27T19:40:38.703929Z","steps":["trace[383506557] 'agreement among raft nodes before linearized reading' (duration: 218.848307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:38.704138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.018197ms","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-05-27T19:40:38.704256Z","caller":"traceutil/trace.go:171","msg":"trace[49886556] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2200; }","duration":"104.153229ms","start":"2026-05-27T19:40:38.600051Z","end":"2026-05-27T19:40:38.704204Z","steps":["trace[49886556] 'agreement among raft nodes before linearized reading' (duration: 103.647031ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:39.007449Z","caller":"traceutil/trace.go:171","msg":"trace[1119789511] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2287; }","duration":"291.808169ms","start":"2026-05-27T19:40:38.715621Z","end":"2026-05-27T19:40:39.00743Z","steps":["trace[1119789511] 'read index received' (duration: 247.699439ms)","trace[1119789511] 'applied index is now lower than readState.Index' (duration: 44.1079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:39.007576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.930691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:39.00771Z","caller":"traceutil/trace.go:171","msg":"trace[1066394058] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2202; }","duration":"292.010833ms","start":"2026-05-27T19:40:38.715585Z","end":"2026-05-27T19:40:39.007596Z","steps":["trace[1066394058] 'agreement among raft nodes before linearized reading' (duration: 291.942642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:39.007711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.00954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:40:39.007711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.617701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:40:39.007796Z","caller":"traceutil/trace.go:171","msg":"trace[507144063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2202; }","duration":"198.157152ms","start":"2026-05-27T19:40:38.809622Z","end":"2026-05-27T19:40:39.007779Z","steps":["trace[507144063] 'agreement among raft nodes before linearized reading' (duration: 198.02378ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:39.007829Z","caller":"traceutil/trace.go:171","msg":"trace[1600568196] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2202; }","duration":"194.770104ms","start":"2026-05-27T19:40:38.81305Z","end":"2026-05-27T19:40:39.00782Z","steps":["trace[1600568196] 'agreement among raft nodes before linearized reading' (duration: 194.596791ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:39.008107Z","caller":"traceutil/trace.go:171","msg":"trace[1900284818] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"298.79506ms","start":"2026-05-27T19:40:38.708695Z","end":"2026-05-27T19:40:39.00749Z","steps":["trace[1900284818] 'process raft request' (duration: 254.642859ms)","trace[1900284818] 'compare' (duration: 43.809636ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:09.990444Z","caller":"traceutil/trace.go:171","msg":"trace[12912637] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"103.450083ms","start":"2026-05-27T19:41:09.886968Z","end":"2026-05-27T19:41:09.990418Z","steps":["trace[12912637] 'process raft request' (duration: 30.18685ms)","trace[12912637] 'compare' (duration: 72.844147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:09.991613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.211651ms","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-05-27T19:41:09.992168Z","caller":"traceutil/trace.go:171","msg":"trace[1083161765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2378; }","duration":"101.318933ms","start":"2026-05-27T19:41:09.890339Z","end":"2026-05-27T19:41:09.991658Z","steps":["trace[1083161765] 'agreement among raft nodes before linearized reading' (duration: 101.175641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:11.018522Z","caller":"traceutil/trace.go:171","msg":"trace[1990420243] transaction","detail":"{read_only:false; response_revision:2381; number_of_response:1; }","duration":"128.234766ms","start":"2026-05-27T19:41:10.890222Z","end":"2026-05-27T19:41:11.018457Z","steps":["trace[1990420243] 'process raft request' (duration: 61.30851ms)","trace[1990420243] 'compare' (duration: 66.593542ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:11.047508Z","caller":"traceutil/trace.go:171","msg":"trace[2118719561] transaction","detail":"{read_only:false; response_revision:2382; number_of_response:1; }","duration":"155.110409ms","start":"2026-05-27T19:41:10.892366Z","end":"2026-05-27T19:41:11.047476Z","steps":["trace[2118719561] 'process raft request' (duration: 154.967097ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:13.573127Z","caller":"traceutil/trace.go:171","msg":"trace[1736839249] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"122.38068ms","start":"2026-05-27T19:41:13.450696Z","end":"2026-05-27T19:41:13.573077Z","steps":["trace[1736839249] 'process raft request' (duration: 67.176816ms)","trace[1736839249] 'compare' (duration: 54.781359ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:18.180962Z","caller":"traceutil/trace.go:171","msg":"trace[688801745] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"123.679088ms","start":"2026-05-27T19:41:18.057246Z","end":"2026-05-27T19:41:18.180925Z","steps":["trace[688801745] 'process raft request' (duration: 123.434925ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:19.225416Z","caller":"traceutil/trace.go:171","msg":"trace[411387432] transaction","detail":"{read_only:false; response_revision:2429; number_of_response:1; }","duration":"119.102975ms","start":"2026-05-27T19:41:19.106263Z","end":"2026-05-27T19:41:19.225365Z","steps":["trace[411387432] 'process raft request' (duration: 41.660568ms)","trace[411387432] 'compare' (duration: 77.137162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:20.217234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.6499ms","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-05-27T19:41:20.217329Z","caller":"traceutil/trace.go:171","msg":"trace[1086773526] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2435; }","duration":"199.798853ms","start":"2026-05-27T19:41:20.017512Z","end":"2026-05-27T19:41:20.217311Z","steps":["trace[1086773526] 'count revisions from in-memory index tree' (duration: 199.508418ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:20.85912Z","caller":"traceutil/trace.go:171","msg":"trace[2117737158] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"339.453839ms","start":"2026-05-27T19:41:20.519639Z","end":"2026-05-27T19:41:20.859093Z","steps":["trace[2117737158] 'process raft request' (duration: 339.250096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:20.859313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:20.519619Z","time spent":"339.60506ms","remote":"127.0.0.1:56636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5636,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:41:27.145446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:26.429435Z","time spent":"716.004869ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T19:41:27.145517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.871314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:41:27.145586Z","caller":"traceutil/trace.go:171","msg":"trace[907553921] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2505; }","duration":"714.965145ms","start":"2026-05-27T19:41:26.430603Z","end":"2026-05-27T19:41:27.145568Z","steps":["trace[907553921] 'range keys from in-memory index tree' (duration: 714.759102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:27.145699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:26.430594Z","time spent":"715.093196ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-27T19:41:27.23775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.93957ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:41:27.238272Z","caller":"traceutil/trace.go:171","msg":"trace[1038360231] linearizableReadLoop","detail":"{readStateIndex:2602; appliedIndex:2601; }","duration":"559.835893ms","start":"2026-05-27T19:41:26.678417Z","end":"2026-05-27T19:41:27.238253Z","steps":["trace[1038360231] 'read index received' (duration: 412.301265ms)","trace[1038360231] 'applied index is now lower than readState.Index' (duration: 147.532988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:27.238416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.980886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:41:27.238447Z","caller":"traceutil/trace.go:171","msg":"trace[1099718643] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2505; }","duration":"560.083646ms","start":"2026-05-27T19:41:26.678353Z","end":"2026-05-27T19:41:27.238437Z","steps":["trace[1099718643] 'agreement among raft nodes before linearized reading' (duration: 559.997235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:27.238519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:26.678329Z","time spent":"560.180218ms","remote":"127.0.0.1:42082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:41:27.238679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.485476ms","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-05-27T19:41:27.238767Z","caller":"traceutil/trace.go:171","msg":"trace[2077245552] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2506; }","duration":"223.615498ms","start":"2026-05-27T19:41:27.015129Z","end":"2026-05-27T19:41:27.238745Z","steps":["trace[2077245552] 'agreement among raft nodes before linearized reading' (duration: 223.334555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:27.238841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.276767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/percona-xtradb\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-05-27T19:41:27.238895Z","caller":"traceutil/trace.go:171","msg":"trace[1838247473] range","detail":"{range_begin:/registry/secrets/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2506; }","duration":"148.376509ms","start":"2026-05-27T19:41:27.090506Z","end":"2026-05-27T19:41:27.238883Z","steps":["trace[1838247473] 'agreement among raft nodes before linearized reading' (duration: 148.208937ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:27.238894Z","caller":"traceutil/trace.go:171","msg":"trace[2032848139] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"165.80823ms","start":"2026-05-27T19:41:27.073065Z","end":"2026-05-27T19:41:27.238873Z","steps":["trace[2032848139] 'process raft request' (duration: 164.880718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:27.239233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.938233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-vzvm8\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-27T19:41:27.239309Z","caller":"traceutil/trace.go:171","msg":"trace[304408540] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-vzvm8; range_end:; response_count:1; response_revision:2506; }","duration":"151.256947ms","start":"2026-05-27T19:41:27.088031Z","end":"2026-05-27T19:41:27.239288Z","steps":["trace[304408540] 'agreement among raft nodes before linearized reading' (duration: 150.801711ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:28.3042Z","caller":"traceutil/trace.go:171","msg":"trace[1669661449] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"209.128941ms","start":"2026-05-27T19:41:28.095049Z","end":"2026-05-27T19:41:28.304177Z","steps":["trace[1669661449] 'process raft request' (duration: 208.99593ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:28.42205Z","caller":"traceutil/trace.go:171","msg":"trace[1487001970] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2615; }","duration":"160.074342ms","start":"2026-05-27T19:41:28.261958Z","end":"2026-05-27T19:41:28.422032Z","steps":["trace[1487001970] 'read index received' (duration: 42.121568ms)","trace[1487001970] 'applied index is now lower than readState.Index' (duration: 117.951814ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:28.422186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.220954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:41:28.422204Z","caller":"traceutil/trace.go:171","msg":"trace[1172562525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2520; }","duration":"160.289356ms","start":"2026-05-27T19:41:28.261908Z","end":"2026-05-27T19:41:28.422198Z","steps":["trace[1172562525] 'agreement among raft nodes before linearized reading' (duration: 160.191744ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:28.422145Z","caller":"traceutil/trace.go:171","msg":"trace[296508127] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"319.320434ms","start":"2026-05-27T19:41:28.102791Z","end":"2026-05-27T19:41:28.422112Z","steps":["trace[296508127] 'process raft request' (duration: 319.131792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:28.422414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:28.102771Z","time spent":"319.524357ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:41:30.549913Z","caller":"traceutil/trace.go:171","msg":"trace[788635088] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"112.431237ms","start":"2026-05-27T19:41:30.437464Z","end":"2026-05-27T19:41:30.549895Z","steps":["trace[788635088] 'process raft request' (duration: 112.323526ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:33.892623Z","caller":"traceutil/trace.go:171","msg":"trace[462093366] linearizableReadLoop","detail":"{readStateIndex:2688; appliedIndex:2684; }","duration":"164.269106ms","start":"2026-05-27T19:41:33.728318Z","end":"2026-05-27T19:41:33.892587Z","steps":["trace[462093366] 'read index received' (duration: 24.593613ms)","trace[462093366] 'applied index is now lower than readState.Index' (duration: 139.673953ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:33.892662Z","caller":"traceutil/trace.go:171","msg":"trace[1129598023] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"165.231669ms","start":"2026-05-27T19:41:33.727369Z","end":"2026-05-27T19:41:33.892601Z","steps":["trace[1129598023] 'process raft request' (duration: 105.709914ms)","trace[1129598023] 'compare' (duration: 58.941658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:33.892981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.737002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:6937"} {"level":"info","ts":"2026-05-27T19:41:33.89303Z","caller":"traceutil/trace.go:171","msg":"trace[1912793666] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:2589; }","duration":"164.902454ms","start":"2026-05-27T19:41:33.728112Z","end":"2026-05-27T19:41:33.893015Z","steps":["trace[1912793666] 'agreement among raft nodes before linearized reading' (duration: 164.646031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:33.893495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.742198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/auth-system/keycloak\" ","response":"range_response_count:1 size:1460"} {"level":"info","ts":"2026-05-27T19:41:33.89355Z","caller":"traceutil/trace.go:171","msg":"trace[1528063448] range","detail":"{range_begin:/registry/poddisruptionbudgets/auth-system/keycloak; range_end:; response_count:1; response_revision:2590; }","duration":"134.831969ms","start":"2026-05-27T19:41:33.758701Z","end":"2026-05-27T19:41:33.893533Z","steps":["trace[1528063448] 'agreement among raft nodes before linearized reading' (duration: 134.683247ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:33.893514Z","caller":"traceutil/trace.go:171","msg":"trace[1696756157] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"125.704158ms","start":"2026-05-27T19:41:33.767761Z","end":"2026-05-27T19:41:33.893465Z","steps":["trace[1696756157] 'process raft request' (duration: 125.528956ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.131708Z","caller":"traceutil/trace.go:171","msg":"trace[493629489] linearizableReadLoop","detail":"{readStateIndex:2691; appliedIndex:2689; }","duration":"189.354857ms","start":"2026-05-27T19:41:33.942295Z","end":"2026-05-27T19:41:34.13165Z","steps":["trace[493629489] 'read index received' (duration: 15.173659ms)","trace[493629489] 'applied index is now lower than readState.Index' (duration: 174.180208ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:34.13173Z","caller":"traceutil/trace.go:171","msg":"trace[1266066596] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"200.449413ms","start":"2026-05-27T19:41:33.931247Z","end":"2026-05-27T19:41:34.131696Z","steps":["trace[1266066596] 'process raft request' (duration: 135.634177ms)","trace[1266066596] 'compare' (duration: 64.571813ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:34.132049Z","caller":"traceutil/trace.go:171","msg":"trace[1151563650] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"156.509823ms","start":"2026-05-27T19:41:33.975524Z","end":"2026-05-27T19:41:34.132033Z","steps":["trace[1151563650] 'process raft request' (duration: 156.096188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:34.132072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.742892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-05-27T19:41:34.132183Z","caller":"traceutil/trace.go:171","msg":"trace[2024280067] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"113.922192ms","start":"2026-05-27T19:41:34.018251Z","end":"2026-05-27T19:41:34.132174Z","steps":["trace[2024280067] 'process raft request' (duration: 113.645259ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.132189Z","caller":"traceutil/trace.go:171","msg":"trace[832230474] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:2593; }","duration":"189.926314ms","start":"2026-05-27T19:41:33.942235Z","end":"2026-05-27T19:41:34.132162Z","steps":["trace[832230474] 'agreement among raft nodes before linearized reading' (duration: 189.58063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:34.429493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.850591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:41:34.429787Z","caller":"traceutil/trace.go:171","msg":"trace[206425070] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"227.813231ms","start":"2026-05-27T19:41:34.201955Z","end":"2026-05-27T19:41:34.429768Z","steps":["trace[206425070] 'process raft request' (duration: 227.655099ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.430014Z","caller":"traceutil/trace.go:171","msg":"trace[1539102477] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"227.69201ms","start":"2026-05-27T19:41:34.202301Z","end":"2026-05-27T19:41:34.429993Z","steps":["trace[1539102477] 'process raft request' (duration: 227.426977ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.430508Z","caller":"traceutil/trace.go:171","msg":"trace[240779173] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"223.393463ms","start":"2026-05-27T19:41:34.207106Z","end":"2026-05-27T19:41:34.430499Z","steps":["trace[240779173] 'process raft request' (duration: 223.118759ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.430669Z","caller":"traceutil/trace.go:171","msg":"trace[777963699] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"227.481148ms","start":"2026-05-27T19:41:34.20318Z","end":"2026-05-27T19:41:34.430661Z","steps":["trace[777963699] 'process raft request' (duration: 226.726498ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.432337Z","caller":"traceutil/trace.go:171","msg":"trace[1185779749] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"227.842062ms","start":"2026-05-27T19:41:34.201883Z","end":"2026-05-27T19:41:34.429725Z","steps":["trace[1185779749] 'process raft request' (duration: 103.665206ms)","trace[1185779749] 'compare' (duration: 123.441376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:34.725566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.606452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:41:34.725738Z","caller":"traceutil/trace.go:171","msg":"trace[164076236] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2700; }","duration":"154.936782ms","start":"2026-05-27T19:41:34.570774Z","end":"2026-05-27T19:41:34.725711Z","steps":["trace[164076236] 'read index received' (duration: 52.016186ms)","trace[164076236] 'applied index is now lower than readState.Index' (duration: 102.918546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:34.726024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.253766ms","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-05-27T19:41:34.726073Z","caller":"traceutil/trace.go:171","msg":"trace[563009753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2602; }","duration":"155.341338ms","start":"2026-05-27T19:41:34.570716Z","end":"2026-05-27T19:41:34.726057Z","steps":["trace[563009753] 'agreement among raft nodes before linearized reading' (duration: 155.071254ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:34.726166Z","caller":"traceutil/trace.go:171","msg":"trace[925950948] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"277.536586ms","start":"2026-05-27T19:41:34.448593Z","end":"2026-05-27T19:41:34.726129Z","steps":["trace[925950948] 'process raft request' (duration: 174.217795ms)","trace[925950948] 'compare' (duration: 102.382309ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:36.333939Z","caller":"traceutil/trace.go:171","msg":"trace[1885166675] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"196.08867ms","start":"2026-05-27T19:41:36.137824Z","end":"2026-05-27T19:41:36.333912Z","steps":["trace[1885166675] 'process raft request' (duration: 195.934608ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:36.351504Z","caller":"traceutil/trace.go:171","msg":"trace[1692107535] linearizableReadLoop","detail":"{readStateIndex:2712; appliedIndex:2709; }","duration":"185.915816ms","start":"2026-05-27T19:41:36.165575Z","end":"2026-05-27T19:41:36.35149Z","steps":["trace[1692107535] 'read index received' (duration: 168.097032ms)","trace[1692107535] 'applied index is now lower than readState.Index' (duration: 17.818264ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:36.351575Z","caller":"traceutil/trace.go:171","msg":"trace[2103423123] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"212.696518ms","start":"2026-05-27T19:41:36.13887Z","end":"2026-05-27T19:41:36.351567Z","steps":["trace[2103423123] 'process raft request' (duration: 212.590327ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:36.351601Z","caller":"traceutil/trace.go:171","msg":"trace[2129401321] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"212.84268ms","start":"2026-05-27T19:41:36.138718Z","end":"2026-05-27T19:41:36.351561Z","steps":["trace[2129401321] 'process raft request' (duration: 212.674158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:36.351871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.28714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:41:36.351917Z","caller":"traceutil/trace.go:171","msg":"trace[886172281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2613; }","duration":"186.414732ms","start":"2026-05-27T19:41:36.165489Z","end":"2026-05-27T19:41:36.351904Z","steps":["trace[886172281] 'agreement among raft nodes before linearized reading' (duration: 186.302251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:36.351908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.009839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T19:41:36.351969Z","caller":"traceutil/trace.go:171","msg":"trace[139379678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2613; }","duration":"136.130611ms","start":"2026-05-27T19:41:36.215814Z","end":"2026-05-27T19:41:36.351945Z","steps":["trace[139379678] 'agreement among raft nodes before linearized reading' (duration: 135.970849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:36.491363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.144389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:41:36.491622Z","caller":"traceutil/trace.go:171","msg":"trace[1762433114] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"135.514223ms","start":"2026-05-27T19:41:36.356078Z","end":"2026-05-27T19:41:36.491592Z","steps":["trace[1762433114] 'process raft request' (duration: 19.050409ms)","trace[1762433114] 'compare' (duration: 116.029838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:36.635194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.685571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:41:36.871393Z","caller":"traceutil/trace.go:171","msg":"trace[2082655544] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"107.693168ms","start":"2026-05-27T19:41:36.763674Z","end":"2026-05-27T19:41:36.871367Z","steps":["trace[2082655544] 'process raft request' (duration: 107.554326ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:37.438242Z","caller":"traceutil/trace.go:171","msg":"trace[2033006068] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"120.466002ms","start":"2026-05-27T19:41:37.317741Z","end":"2026-05-27T19:41:37.438207Z","steps":["trace[2033006068] 'process raft request' (duration: 119.579051ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:38.591264Z","caller":"traceutil/trace.go:171","msg":"trace[784826317] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"252.276423ms","start":"2026-05-27T19:41:38.338965Z","end":"2026-05-27T19:41:38.591242Z","steps":["trace[784826317] 'process raft request' (duration: 252.123791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:39.248686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.881566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:41:39.248948Z","caller":"traceutil/trace.go:171","msg":"trace[1810630060] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"893.061443ms","start":"2026-05-27T19:41:38.355852Z","end":"2026-05-27T19:41:39.248913Z","steps":["trace[1810630060] 'process raft request' (duration: 758.837732ms)","trace[1810630060] 'compare' (duration: 133.729024ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:39.249095Z","caller":"traceutil/trace.go:171","msg":"trace[1444920867] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"891.476403ms","start":"2026-05-27T19:41:38.357601Z","end":"2026-05-27T19:41:39.249077Z","steps":["trace[1444920867] 'process raft request' (duration: 891.196899ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:39.24915Z","caller":"traceutil/trace.go:171","msg":"trace[924047826] linearizableReadLoop","detail":"{readStateIndex:2724; appliedIndex:2721; }","duration":"753.736773ms","start":"2026-05-27T19:41:38.49539Z","end":"2026-05-27T19:41:39.249127Z","steps":["trace[924047826] 'read index received' (duration: 95.676236ms)","trace[924047826] 'applied index is now lower than readState.Index' (duration: 658.058057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:41:39.249196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:38.35759Z","time spent":"891.559984ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:41:39.249112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:38.355823Z","time spent":"893.231764ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:41:39.249335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.945466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T19:41:39.249373Z","caller":"traceutil/trace.go:171","msg":"trace[750827316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2625; }","duration":"754.019967ms","start":"2026-05-27T19:41:38.495343Z","end":"2026-05-27T19:41:39.249363Z","steps":["trace[750827316] 'agreement among raft nodes before linearized reading' (duration: 753.891996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:39.249394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.715654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:41:39.249401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:38.495326Z","time spent":"754.069629ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T19:41:39.249425Z","caller":"traceutil/trace.go:171","msg":"trace[60789780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2625; }","duration":"373.829496ms","start":"2026-05-27T19:41:38.875588Z","end":"2026-05-27T19:41:39.249417Z","steps":["trace[60789780] 'agreement among raft nodes before linearized reading' (duration: 373.684933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:39.249449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:41:38.875559Z","time spent":"373.885027ms","remote":"127.0.0.1:42090","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-05-27T19:41:51.604448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.128371ms","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-05-27T19:41:51.604528Z","caller":"traceutil/trace.go:171","msg":"trace[292664174] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2699; }","duration":"145.288627ms","start":"2026-05-27T19:41:51.459222Z","end":"2026-05-27T19:41:51.604511Z","steps":["trace[292664174] 'count revisions from in-memory index tree' (duration: 144.989586ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:51.605025Z","caller":"traceutil/trace.go:171","msg":"trace[1243887641] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"124.093367ms","start":"2026-05-27T19:41:51.480892Z","end":"2026-05-27T19:41:51.604985Z","steps":["trace[1243887641] 'process raft request' (duration: 79.144402ms)","trace[1243887641] 'compare' (duration: 44.762578ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:51.961248Z","caller":"traceutil/trace.go:171","msg":"trace[455962986] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"118.285992ms","start":"2026-05-27T19:41:51.842935Z","end":"2026-05-27T19:41:51.961221Z","steps":["trace[455962986] 'process raft request' (duration: 118.137147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:02.866996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.1975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:42:02.867106Z","caller":"traceutil/trace.go:171","msg":"trace[797162000] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2756; }","duration":"190.360755ms","start":"2026-05-27T19:42:02.67672Z","end":"2026-05-27T19:42:02.867081Z","steps":["trace[797162000] 'count revisions from in-memory index tree' (duration: 190.103426ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:02.867707Z","caller":"traceutil/trace.go:171","msg":"trace[97536871] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"119.94819ms","start":"2026-05-27T19:42:02.747361Z","end":"2026-05-27T19:42:02.867309Z","steps":["trace[97536871] 'process raft request' (duration: 105.996592ms)","trace[97536871] 'compare' (duration: 13.453851ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:42:14.015626Z","caller":"traceutil/trace.go:171","msg":"trace[1618493061] linearizableReadLoop","detail":"{readStateIndex:2923; appliedIndex:2922; }","duration":"304.77023ms","start":"2026-05-27T19:42:13.710836Z","end":"2026-05-27T19:42:14.015606Z","steps":["trace[1618493061] 'read index received' (duration: 304.551003ms)","trace[1618493061] 'applied index is now lower than readState.Index' (duration: 218.597µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:42:14.015785Z","caller":"traceutil/trace.go:171","msg":"trace[517493300] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"327.21709ms","start":"2026-05-27T19:42:13.688533Z","end":"2026-05-27T19:42:14.01575Z","steps":["trace[517493300] 'process raft request' (duration: 326.92006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.01597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.122952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-27T19:42:14.016016Z","caller":"traceutil/trace.go:171","msg":"trace[1722251914] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2816; }","duration":"305.215295ms","start":"2026-05-27T19:42:13.710792Z","end":"2026-05-27T19:42:14.016007Z","steps":["trace[1722251914] 'agreement among raft nodes before linearized reading' (duration: 305.048229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.016045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:13.71077Z","time spent":"305.267527ms","remote":"127.0.0.1:42034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-27T19:42:14.016196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:13.688499Z","time spent":"327.476798ms","remote":"127.0.0.1:42090","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-05-27T19:42:14.016362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.956559ms","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-05-27T19:42:14.016393Z","caller":"traceutil/trace.go:171","msg":"trace[439036666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2816; }","duration":"297.031871ms","start":"2026-05-27T19:42:13.719353Z","end":"2026-05-27T19:42:14.016385Z","steps":["trace[439036666] 'agreement among raft nodes before linearized reading' (duration: 296.952119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.016398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.828761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:42:14.016487Z","caller":"traceutil/trace.go:171","msg":"trace[1208722010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2816; }","duration":"286.981476ms","start":"2026-05-27T19:42:13.729482Z","end":"2026-05-27T19:42:14.016464Z","steps":["trace[1208722010] 'agreement among raft nodes before linearized reading' (duration: 286.763709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.016629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.093578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:42:14.016741Z","caller":"traceutil/trace.go:171","msg":"trace[489285655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2816; }","duration":"248.332046ms","start":"2026-05-27T19:42:13.768391Z","end":"2026-05-27T19:42:14.016723Z","steps":["trace[489285655] 'agreement among raft nodes before linearized reading' (duration: 247.877741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.017194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.845215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T19:42:14.0174Z","caller":"traceutil/trace.go:171","msg":"trace[1366153654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2816; }","duration":"303.077943ms","start":"2026-05-27T19:42:13.714307Z","end":"2026-05-27T19:42:14.017385Z","steps":["trace[1366153654] 'agreement among raft nodes before linearized reading' (duration: 301.917465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.017545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:13.714298Z","time spent":"303.208548ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:42:14.129156Z","caller":"traceutil/trace.go:171","msg":"trace[1415042833] linearizableReadLoop","detail":"{readStateIndex:2925; appliedIndex:2923; }","duration":"106.84747ms","start":"2026-05-27T19:42:14.022286Z","end":"2026-05-27T19:42:14.129133Z","steps":["trace[1415042833] 'read index received' (duration: 47.87428ms)","trace[1415042833] 'applied index is now lower than readState.Index' (duration: 58.97222ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:42:14.129197Z","caller":"traceutil/trace.go:171","msg":"trace[168801316] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"106.630463ms","start":"2026-05-27T19:42:14.022549Z","end":"2026-05-27T19:42:14.129179Z","steps":["trace[168801316] 'process raft request' (duration: 106.54205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:14.129478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.182641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-27T19:42:14.12955Z","caller":"traceutil/trace.go:171","msg":"trace[1312680095] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2819; }","duration":"107.297356ms","start":"2026-05-27T19:42:14.02224Z","end":"2026-05-27T19:42:14.129537Z","steps":["trace[1312680095] 'agreement among raft nodes before linearized reading' (duration: 107.053717ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:14.130083Z","caller":"traceutil/trace.go:171","msg":"trace[81237342] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"108.401072ms","start":"2026-05-27T19:42:14.021665Z","end":"2026-05-27T19:42:14.130066Z","steps":["trace[81237342] 'process raft request' (duration: 107.257764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.039033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.936304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b3838aaa59d107\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-27T19:42:15.039385Z","caller":"traceutil/trace.go:171","msg":"trace[925227882] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b3838aaa59d107; range_end:; response_count:1; response_revision:2819; }","duration":"709.329847ms","start":"2026-05-27T19:42:14.330038Z","end":"2026-05-27T19:42:15.039368Z","steps":["trace[925227882] 'range keys from in-memory index tree' (duration: 708.763218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.039457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:14.330021Z","time spent":"709.42588ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b3838aaa59d107\" "} {"level":"info","ts":"2026-05-27T19:42:15.428885Z","caller":"traceutil/trace.go:171","msg":"trace[1552656651] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"719.418006ms","start":"2026-05-27T19:42:14.709447Z","end":"2026-05-27T19:42:15.428865Z","steps":["trace[1552656651] 'process raft request' (duration: 719.257331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.429019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:14.709422Z","time spent":"719.52718ms","remote":"127.0.0.1:42090","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-05-27T19:42:15.684621Z","caller":"traceutil/trace.go:171","msg":"trace[2828183] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"779.208999ms","start":"2026-05-27T19:42:14.90537Z","end":"2026-05-27T19:42:15.684579Z","steps":["trace[2828183] 'process raft request' (duration: 767.579743ms)","trace[2828183] 'compare' (duration: 11.242472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:15.684738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:14.905347Z","time spent":"779.331193ms","remote":"127.0.0.1:42090","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-05-27T19:42:15.684711Z","caller":"traceutil/trace.go:171","msg":"trace[1961282204] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"643.370729ms","start":"2026-05-27T19:42:15.041312Z","end":"2026-05-27T19:42:15.684683Z","steps":["trace[1961282204] 'process raft request' (duration: 643.198333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.684856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:15.041292Z","time spent":"643.486253ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:15.684651Z","caller":"traceutil/trace.go:171","msg":"trace[781839797] linearizableReadLoop","detail":"{readStateIndex:2928; appliedIndex:2927; }","duration":"720.720515ms","start":"2026-05-27T19:42:14.963905Z","end":"2026-05-27T19:42:15.684626Z","steps":["trace[781839797] 'read index received' (duration: 465.011993ms)","trace[781839797] 'applied index is now lower than readState.Index' (duration: 255.707622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:15.685338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.424858ms","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-05-27T19:42:15.685392Z","caller":"traceutil/trace.go:171","msg":"trace[1139609816] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2823; }","duration":"721.519932ms","start":"2026-05-27T19:42:14.963859Z","end":"2026-05-27T19:42:15.685379Z","steps":["trace[1139609816] 'agreement among raft nodes before linearized reading' (duration: 721.408698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.685428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:14.963838Z","time spent":"721.580113ms","remote":"127.0.0.1:56620","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-05-27T19:42:15.685576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.019403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:42:15.685654Z","caller":"traceutil/trace.go:171","msg":"trace[762124569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2824; }","duration":"519.185689ms","start":"2026-05-27T19:42:15.166451Z","end":"2026-05-27T19:42:15.685637Z","steps":["trace[762124569] 'agreement among raft nodes before linearized reading' (duration: 519.004663ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:15.685444Z","caller":"traceutil/trace.go:171","msg":"trace[273132933] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"217.133026ms","start":"2026-05-27T19:42:15.468208Z","end":"2026-05-27T19:42:15.685341Z","steps":["trace[273132933] 'process raft request' (duration: 217.084844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.685694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:15.16643Z","time spent":"519.256991ms","remote":"127.0.0.1:41864","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-05-27T19:42:15.685774Z","caller":"traceutil/trace.go:171","msg":"trace[1762338863] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"253.085824ms","start":"2026-05-27T19:42:15.432677Z","end":"2026-05-27T19:42:15.685763Z","steps":["trace[1762338863] 'process raft request' (duration: 252.503665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.685877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.426716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T19:42:15.685907Z","caller":"traceutil/trace.go:171","msg":"trace[216696483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2824; }","duration":"182.485558ms","start":"2026-05-27T19:42:15.503412Z","end":"2026-05-27T19:42:15.685898Z","steps":["trace[216696483] 'agreement among raft nodes before linearized reading' (duration: 182.371794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:15.685948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.056571ms","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-05-27T19:42:15.685979Z","caller":"traceutil/trace.go:171","msg":"trace[759650105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2824; }","duration":"184.143553ms","start":"2026-05-27T19:42:15.501828Z","end":"2026-05-27T19:42:15.685971Z","steps":["trace[759650105] 'agreement among raft nodes before linearized reading' (duration: 184.027939ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:19.779138Z","caller":"traceutil/trace.go:171","msg":"trace[232582731] linearizableReadLoop","detail":"{readStateIndex:3280; appliedIndex:3279; }","duration":"420.076228ms","start":"2026-05-27T19:43:19.359036Z","end":"2026-05-27T19:43:19.779112Z","steps":["trace[232582731] 'read index received' (duration: 419.912683ms)","trace[232582731] 'applied index is now lower than readState.Index' (duration: 162.194µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:19.779182Z","caller":"traceutil/trace.go:171","msg":"trace[34249528] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"446.750421ms","start":"2026-05-27T19:43:19.332385Z","end":"2026-05-27T19:43:19.779136Z","steps":["trace[34249528] 'process raft request' (duration: 446.539426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:19.779326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:19.332373Z","time spent":"446.861724ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:19.779347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.310294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:19.779396Z","caller":"traceutil/trace.go:171","msg":"trace[666341963] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3159; }","duration":"420.392915ms","start":"2026-05-27T19:43:19.358987Z","end":"2026-05-27T19:43:19.77938Z","steps":["trace[666341963] 'agreement among raft nodes before linearized reading' (duration: 420.286193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:19.779443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:19.358965Z","time spent":"420.466468ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-27T19:43:30.239398Z","caller":"traceutil/trace.go:171","msg":"trace[1262661830] transaction","detail":"{read_only:false; response_revision:3212; number_of_response:1; }","duration":"146.249983ms","start":"2026-05-27T19:43:30.093115Z","end":"2026-05-27T19:43:30.239365Z","steps":["trace[1262661830] 'process raft request' (duration: 146.016807ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:30.250085Z","caller":"traceutil/trace.go:171","msg":"trace[1760579579] transaction","detail":"{read_only:false; response_revision:3213; number_of_response:1; }","duration":"136.744745ms","start":"2026-05-27T19:43:30.113309Z","end":"2026-05-27T19:43:30.250054Z","steps":["trace[1760579579] 'process raft request' (duration: 131.119014ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:30.250131Z","caller":"traceutil/trace.go:171","msg":"trace[1188864624] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"136.102809ms","start":"2026-05-27T19:43:30.113997Z","end":"2026-05-27T19:43:30.2501Z","steps":["trace[1188864624] 'process raft request' (duration: 135.994836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:13.354186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.092459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:45:13.354271Z","caller":"traceutil/trace.go:171","msg":"trace[2025514210] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3882; }","duration":"159.235262ms","start":"2026-05-27T19:45:13.195019Z","end":"2026-05-27T19:45:13.354255Z","steps":["trace[2025514210] 'count revisions from in-memory index tree' (duration: 158.471596ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.241231Z","caller":"traceutil/trace.go:171","msg":"trace[1203886542] linearizableReadLoop","detail":"{readStateIndex:4041; appliedIndex:4040; }","duration":"112.353167ms","start":"2026-05-27T19:45:14.128848Z","end":"2026-05-27T19:45:14.241201Z","steps":["trace[1203886542] 'read index received' (duration: 31.958555ms)","trace[1203886542] 'applied index is now lower than readState.Index' (duration: 80.393432ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:14.241268Z","caller":"traceutil/trace.go:171","msg":"trace[1352387247] transaction","detail":"{read_only:false; response_revision:3888; number_of_response:1; }","duration":"120.534498ms","start":"2026-05-27T19:45:14.120694Z","end":"2026-05-27T19:45:14.241229Z","steps":["trace[1352387247] 'process raft request' (duration: 40.224088ms)","trace[1352387247] 'compare' (duration: 80.138377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:14.24143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.558262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:14.241666Z","caller":"traceutil/trace.go:171","msg":"trace[201173476] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3888; }","duration":"112.830578ms","start":"2026-05-27T19:45:14.128815Z","end":"2026-05-27T19:45:14.241646Z","steps":["trace[201173476] 'agreement among raft nodes before linearized reading' (duration: 112.4946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.270714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.936153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:14.270798Z","caller":"traceutil/trace.go:171","msg":"trace[131510214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3889; }","duration":"104.057226ms","start":"2026-05-27T19:45:14.166722Z","end":"2026-05-27T19:45:14.270779Z","steps":["trace[131510214] 'agreement among raft nodes before linearized reading' (duration: 103.935813ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.540752Z","caller":"traceutil/trace.go:171","msg":"trace[962339218] transaction","detail":"{read_only:false; response_revision:3890; number_of_response:1; }","duration":"176.048234ms","start":"2026-05-27T19:45:14.364672Z","end":"2026-05-27T19:45:14.54072Z","steps":["trace[962339218] 'process raft request' (duration: 175.260569ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.550039Z","caller":"traceutil/trace.go:171","msg":"trace[305036362] transaction","detail":"{read_only:false; response_revision:3891; number_of_response:1; }","duration":"176.891541ms","start":"2026-05-27T19:45:14.373122Z","end":"2026-05-27T19:45:14.550014Z","steps":["trace[305036362] 'process raft request' (duration: 175.86242ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.760522Z","caller":"traceutil/trace.go:171","msg":"trace[2140215019] transaction","detail":"{read_only:false; response_revision:3893; number_of_response:1; }","duration":"146.300392ms","start":"2026-05-27T19:45:14.614196Z","end":"2026-05-27T19:45:14.760497Z","steps":["trace[2140215019] 'process raft request' (duration: 115.661512ms)","trace[2140215019] 'compare' (duration: 30.462507ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:14.764856Z","caller":"traceutil/trace.go:171","msg":"trace[1799515691] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"134.561312ms","start":"2026-05-27T19:45:14.630272Z","end":"2026-05-27T19:45:14.764833Z","steps":["trace[1799515691] 'process raft request' (duration: 133.937ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.937736Z","caller":"traceutil/trace.go:171","msg":"trace[243192203] transaction","detail":"{read_only:false; response_revision:3897; number_of_response:1; }","duration":"148.899193ms","start":"2026-05-27T19:45:14.788791Z","end":"2026-05-27T19:45:14.93769Z","steps":["trace[243192203] 'process raft request' (duration: 97.845144ms)","trace[243192203] 'compare' (duration: 50.774623ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:14.938324Z","caller":"traceutil/trace.go:171","msg":"trace[391398957] transaction","detail":"{read_only:false; response_revision:3899; number_of_response:1; }","duration":"138.396177ms","start":"2026-05-27T19:45:14.79988Z","end":"2026-05-27T19:45:14.938276Z","steps":["trace[391398957] 'process raft request' (duration: 137.757255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.93852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.700652ms","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-05-27T19:45:14.938587Z","caller":"traceutil/trace.go:171","msg":"trace[1873987988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3900; }","duration":"141.796434ms","start":"2026-05-27T19:45:14.796757Z","end":"2026-05-27T19:45:14.938553Z","steps":["trace[1873987988] 'agreement among raft nodes before linearized reading' (duration: 141.635491ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.938723Z","caller":"traceutil/trace.go:171","msg":"trace[1001205065] transaction","detail":"{read_only:false; response_revision:3898; number_of_response:1; }","duration":"141.495178ms","start":"2026-05-27T19:45:14.797219Z","end":"2026-05-27T19:45:14.938714Z","steps":["trace[1001205065] 'process raft request' (duration: 140.353506ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.938345Z","caller":"traceutil/trace.go:171","msg":"trace[925858910] linearizableReadLoop","detail":"{readStateIndex:4050; appliedIndex:4048; }","duration":"141.449417ms","start":"2026-05-27T19:45:14.796814Z","end":"2026-05-27T19:45:14.938264Z","steps":["trace[925858910] 'read index received' (duration: 12.077546ms)","trace[925858910] 'applied index is now lower than readState.Index' (duration: 129.371101ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:14.939016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.041541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T19:45:14.939043Z","caller":"traceutil/trace.go:171","msg":"trace[1309300237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3900; }","duration":"139.114002ms","start":"2026-05-27T19:45:14.799922Z","end":"2026-05-27T19:45:14.939036Z","steps":["trace[1309300237] 'agreement among raft nodes before linearized reading' (duration: 138.99899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.546355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.03959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:3242"} {"level":"info","ts":"2026-05-27T19:46:00.546448Z","caller":"traceutil/trace.go:171","msg":"trace[677102517] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4550; }","duration":"114.171653ms","start":"2026-05-27T19:46:00.432252Z","end":"2026-05-27T19:46:00.546424Z","steps":["trace[677102517] 'range keys from in-memory index tree' (duration: 113.964818ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.217462Z","caller":"traceutil/trace.go:171","msg":"trace[1888285709] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"120.533588ms","start":"2026-05-27T19:46:03.096893Z","end":"2026-05-27T19:46:03.217427Z","steps":["trace[1888285709] 'process raft request' (duration: 54.091872ms)","trace[1888285709] 'compare' (duration: 66.038379ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:03.374174Z","caller":"traceutil/trace.go:171","msg":"trace[1011392783] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"150.149987ms","start":"2026-05-27T19:46:03.224Z","end":"2026-05-27T19:46:03.37415Z","steps":["trace[1011392783] 'process raft request' (duration: 141.279596ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.374314Z","caller":"traceutil/trace.go:171","msg":"trace[852052367] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"149.710018ms","start":"2026-05-27T19:46:03.224588Z","end":"2026-05-27T19:46:03.374298Z","steps":["trace[852052367] 'process raft request' (duration: 149.519355ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:06.397434Z","caller":"traceutil/trace.go:171","msg":"trace[460912973] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"193.237436ms","start":"2026-05-27T19:46:06.204166Z","end":"2026-05-27T19:46:06.397403Z","steps":["trace[460912973] 'read index received' (duration: 176.151737ms)","trace[460912973] 'applied index is now lower than readState.Index' (duration: 17.084719ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:06.397697Z","caller":"traceutil/trace.go:171","msg":"trace[325632538] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"197.175538ms","start":"2026-05-27T19:46:06.200507Z","end":"2026-05-27T19:46:06.397683Z","steps":["trace[325632538] 'process raft request' (duration: 179.723311ms)","trace[325632538] 'compare' (duration: 17.06813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:06.397884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.692664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-27T19:46:06.397993Z","caller":"traceutil/trace.go:171","msg":"trace[41622736] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4681; }","duration":"193.806276ms","start":"2026-05-27T19:46:06.20414Z","end":"2026-05-27T19:46:06.397946Z","steps":["trace[41622736] 'agreement among raft nodes before linearized reading' (duration: 193.572282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.417386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.699668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa239bf1-b69d-4225-a67b-4eb6fc6e8672\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-27T19:46:06.417452Z","caller":"traceutil/trace.go:171","msg":"trace[1562710304] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa239bf1-b69d-4225-a67b-4eb6fc6e8672; range_end:; response_count:1; response_revision:4681; }","duration":"107.814981ms","start":"2026-05-27T19:46:06.309621Z","end":"2026-05-27T19:46:06.417436Z","steps":["trace[1562710304] 'agreement among raft nodes before linearized reading' (duration: 107.628818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:09.082285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.848682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43722020-3b14-4566-8a77-4d4a0646d4ce\" ","response":"range_response_count:1 size:4050"} {"level":"info","ts":"2026-05-27T19:46:09.08237Z","caller":"traceutil/trace.go:171","msg":"trace[781037739] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43722020-3b14-4566-8a77-4d4a0646d4ce; range_end:; response_count:1; response_revision:4740; }","duration":"180.988124ms","start":"2026-05-27T19:46:08.901361Z","end":"2026-05-27T19:46:09.082349Z","steps":["trace[781037739] 'range keys from in-memory index tree' (duration: 180.672178ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:09.085068Z","caller":"traceutil/trace.go:171","msg":"trace[1667586780] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"100.642024ms","start":"2026-05-27T19:46:08.984403Z","end":"2026-05-27T19:46:09.085045Z","steps":["trace[1667586780] 'process raft request' (duration: 100.502961ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:15.365006Z","caller":"traceutil/trace.go:171","msg":"trace[1847180765] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"740.66493ms","start":"2026-05-27T19:46:14.62431Z","end":"2026-05-27T19:46:15.364975Z","steps":["trace[1847180765] 'process raft request' (duration: 740.491517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:15.365174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:14.624298Z","time spent":"740.799362ms","remote":"127.0.0.1:50530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:15.392651Z","caller":"traceutil/trace.go:171","msg":"trace[493651689] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"292.659666ms","start":"2026-05-27T19:46:15.099963Z","end":"2026-05-27T19:46:15.392622Z","steps":["trace[493651689] 'process raft request' (duration: 292.385951ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:15.392801Z","caller":"traceutil/trace.go:171","msg":"trace[1708697736] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"133.231434ms","start":"2026-05-27T19:46:15.25955Z","end":"2026-05-27T19:46:15.392781Z","steps":["trace[1708697736] 'process raft request' (duration: 133.022ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:15.392896Z","caller":"traceutil/trace.go:171","msg":"trace[151525202] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"289.547741ms","start":"2026-05-27T19:46:15.103286Z","end":"2026-05-27T19:46:15.392834Z","steps":["trace[151525202] 'process raft request' (duration: 289.201135ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:15.39265Z","caller":"traceutil/trace.go:171","msg":"trace[1602532176] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"216.28105ms","start":"2026-05-27T19:46:15.176341Z","end":"2026-05-27T19:46:15.392622Z","steps":["trace[1602532176] 'process raft request' (duration: 216.186738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:16.215075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.880165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:16.215193Z","caller":"traceutil/trace.go:171","msg":"trace[1243598043] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5030; }","duration":"449.889373ms","start":"2026-05-27T19:46:15.765279Z","end":"2026-05-27T19:46:16.215168Z","steps":["trace[1243598043] 'read index received' (duration: 265.920107ms)","trace[1243598043] 'applied index is now lower than readState.Index' (duration: 183.967806ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:16.215658Z","caller":"traceutil/trace.go:171","msg":"trace[1073428072] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"458.382585ms","start":"2026-05-27T19:46:15.757257Z","end":"2026-05-27T19:46:16.215639Z","steps":["trace[1073428072] 'process raft request' (duration: 273.879659ms)","trace[1073428072] 'compare' (duration: 183.551839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:16.21591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.947645ms","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-05-27T19:46:16.216002Z","caller":"traceutil/trace.go:171","msg":"trace[1597229480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4842; }","duration":"275.026376ms","start":"2026-05-27T19:46:15.940939Z","end":"2026-05-27T19:46:16.215965Z","steps":["trace[1597229480] 'agreement among raft nodes before linearized reading' (duration: 274.757821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:16.216028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.688128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T19:46:16.216162Z","caller":"traceutil/trace.go:171","msg":"trace[1704192267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4842; }","duration":"450.910282ms","start":"2026-05-27T19:46:15.765229Z","end":"2026-05-27T19:46:16.216139Z","steps":["trace[1704192267] 'agreement among raft nodes before linearized reading' (duration: 450.592516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:16.216288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:15.765207Z","time spent":"451.059515ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:16.215779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:15.757237Z","time spent":"458.472307ms","remote":"127.0.0.1:42034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1438,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:46:16.454689Z","caller":"traceutil/trace.go:171","msg":"trace[1477462983] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"165.64646ms","start":"2026-05-27T19:46:16.289015Z","end":"2026-05-27T19:46:16.454662Z","steps":["trace[1477462983] 'process raft request' (duration: 161.318613ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:16.454801Z","caller":"traceutil/trace.go:171","msg":"trace[832617364] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5038; }","duration":"162.616935ms","start":"2026-05-27T19:46:16.292164Z","end":"2026-05-27T19:46:16.454781Z","steps":["trace[832617364] 'read index received' (duration: 158.107975ms)","trace[832617364] 'applied index is now lower than readState.Index' (duration: 4.50704ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:16.455174Z","caller":"traceutil/trace.go:171","msg":"trace[1438655139] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"159.738724ms","start":"2026-05-27T19:46:16.295025Z","end":"2026-05-27T19:46:16.454763Z","steps":["trace[1438655139] 'process raft request' (duration: 159.613862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:16.462602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.417186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-05-27T19:46:16.462679Z","caller":"traceutil/trace.go:171","msg":"trace[1813924053] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4851; }","duration":"170.524317ms","start":"2026-05-27T19:46:16.292133Z","end":"2026-05-27T19:46:16.462657Z","steps":["trace[1813924053] 'agreement among raft nodes before linearized reading' (duration: 162.722238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:17.286163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.594185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:17.286309Z","caller":"traceutil/trace.go:171","msg":"trace[841440078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4894; }","duration":"119.793198ms","start":"2026-05-27T19:46:17.166497Z","end":"2026-05-27T19:46:17.28629Z","steps":["trace[841440078] 'range keys from in-memory index tree' (duration: 119.473452ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:20.010651Z","caller":"traceutil/trace.go:171","msg":"trace[1075512211] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5106; }","duration":"270.065948ms","start":"2026-05-27T19:46:19.74056Z","end":"2026-05-27T19:46:20.010626Z","steps":["trace[1075512211] 'read index received' (duration: 269.797524ms)","trace[1075512211] 'applied index is now lower than readState.Index' (duration: 267.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:20.010774Z","caller":"traceutil/trace.go:171","msg":"trace[1693628508] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"357.978925ms","start":"2026-05-27T19:46:19.652785Z","end":"2026-05-27T19:46:20.010763Z","steps":["trace[1693628508] 'process raft request' (duration: 357.644669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.010874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:19.652763Z","time spent":"358.035566ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:20.010991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.596918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:46:20.011066Z","caller":"traceutil/trace.go:171","msg":"trace[967130904] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4917; }","duration":"256.713951ms","start":"2026-05-27T19:46:19.754332Z","end":"2026-05-27T19:46:20.011046Z","steps":["trace[967130904] 'agreement among raft nodes before linearized reading' (duration: 256.596338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.01109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.469776ms","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-05-27T19:46:20.011236Z","caller":"traceutil/trace.go:171","msg":"trace[550410130] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4917; }","duration":"270.710509ms","start":"2026-05-27T19:46:19.740504Z","end":"2026-05-27T19:46:20.011215Z","steps":["trace[550410130] 'agreement among raft nodes before linearized reading' (duration: 270.474225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.465536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.199826ms","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-05-27T19:46:20.465692Z","caller":"traceutil/trace.go:171","msg":"trace[1120349945] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4917; }","duration":"351.404561ms","start":"2026-05-27T19:46:20.114269Z","end":"2026-05-27T19:46:20.465673Z","steps":["trace[1120349945] 'count revisions from in-memory index tree' (duration: 351.118305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.465791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:20.114254Z","time spent":"351.515093ms","remote":"127.0.0.1:56546","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-05-27T19:46:20.466122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.497955ms","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-05-27T19:46:20.46623Z","caller":"traceutil/trace.go:171","msg":"trace[1679199752] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4917; }","duration":"183.606287ms","start":"2026-05-27T19:46:20.282612Z","end":"2026-05-27T19:46:20.466219Z","steps":["trace[1679199752] 'range keys from in-memory index tree' (duration: 183.486165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.466139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.993712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-27T19:46:20.466162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.042022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T19:46:20.46655Z","caller":"traceutil/trace.go:171","msg":"trace[1601320530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4917; }","duration":"171.48271ms","start":"2026-05-27T19:46:20.29504Z","end":"2026-05-27T19:46:20.466522Z","steps":["trace[1601320530] 'range keys from in-memory index tree' (duration: 170.751547ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:20.466868Z","caller":"traceutil/trace.go:171","msg":"trace[925154006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4917; }","duration":"171.253666ms","start":"2026-05-27T19:46:20.29508Z","end":"2026-05-27T19:46:20.466333Z","steps":["trace[925154006] 'range keys from in-memory index tree' (duration: 170.824129ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:21.106298Z","caller":"traceutil/trace.go:171","msg":"trace[362304065] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5125; }","duration":"113.567628ms","start":"2026-05-27T19:46:20.992713Z","end":"2026-05-27T19:46:21.10628Z","steps":["trace[362304065] 'read index received' (duration: 109.436395ms)","trace[362304065] 'applied index is now lower than readState.Index' (duration: 4.129983ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:21.106512Z","caller":"traceutil/trace.go:171","msg":"trace[1677541639] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"123.402522ms","start":"2026-05-27T19:46:20.982914Z","end":"2026-05-27T19:46:21.106317Z","steps":["trace[1677541639] 'process raft request' (duration: 119.32207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:21.106746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.024355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b383c8e38cc7e2\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-05-27T19:46:21.106791Z","caller":"traceutil/trace.go:171","msg":"trace[1753250866] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b383c8e38cc7e2; range_end:; response_count:1; response_revision:4935; }","duration":"114.104346ms","start":"2026-05-27T19:46:20.992671Z","end":"2026-05-27T19:46:21.106775Z","steps":["trace[1753250866] 'agreement among raft nodes before linearized reading' (duration: 113.695819ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:24.704024Z","caller":"traceutil/trace.go:171","msg":"trace[1076205239] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"124.180519ms","start":"2026-05-27T19:46:24.579826Z","end":"2026-05-27T19:46:24.704007Z","steps":["trace[1076205239] 'process raft request' (duration: 123.944494ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:24.952034Z","caller":"traceutil/trace.go:171","msg":"trace[1320306528] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"186.769157ms","start":"2026-05-27T19:46:24.765238Z","end":"2026-05-27T19:46:24.952007Z","steps":["trace[1320306528] 'process raft request' (duration: 184.371295ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:25.610904Z","caller":"traceutil/trace.go:171","msg":"trace[2090856685] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"179.659367ms","start":"2026-05-27T19:46:25.431226Z","end":"2026-05-27T19:46:25.610886Z","steps":["trace[2090856685] 'process raft request' (duration: 179.530625ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:25.839281Z","caller":"traceutil/trace.go:171","msg":"trace[261427416] linearizableReadLoop","detail":"{readStateIndex:5214; appliedIndex:5210; }","duration":"345.224035ms","start":"2026-05-27T19:46:25.494034Z","end":"2026-05-27T19:46:25.839258Z","steps":["trace[261427416] 'read index received' (duration: 116.782175ms)","trace[261427416] 'applied index is now lower than readState.Index' (duration: 228.441ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:25.839413Z","caller":"traceutil/trace.go:171","msg":"trace[1853418832] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"407.15268ms","start":"2026-05-27T19:46:25.432246Z","end":"2026-05-27T19:46:25.839399Z","steps":["trace[1853418832] 'process raft request' (duration: 328.177884ms)","trace[1853418832] 'compare' (duration: 78.510188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:25.839516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.432231Z","time spent":"407.214012ms","remote":"127.0.0.1:42090","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-05-27T19:46:25.839638Z","caller":"traceutil/trace.go:171","msg":"trace[2068817667] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"406.269074ms","start":"2026-05-27T19:46:25.43336Z","end":"2026-05-27T19:46:25.839629Z","steps":["trace[2068817667] 'process raft request' (duration: 405.739825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.839688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.433349Z","time spent":"406.313365ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:25.839849Z","caller":"traceutil/trace.go:171","msg":"trace[1560942665] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"388.411289ms","start":"2026-05-27T19:46:25.451406Z","end":"2026-05-27T19:46:25.839817Z","steps":["trace[1560942665] 'process raft request' (duration: 387.736967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.839897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.852286ms","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-05-27T19:46:25.839947Z","caller":"traceutil/trace.go:171","msg":"trace[1046164712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5021; }","duration":"345.951689ms","start":"2026-05-27T19:46:25.493982Z","end":"2026-05-27T19:46:25.839934Z","steps":["trace[1046164712] 'agreement among raft nodes before linearized reading' (duration: 345.792486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.83999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.493964Z","time spent":"346.018539ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T19:46:25.84001Z","caller":"traceutil/trace.go:171","msg":"trace[262914023] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"324.53182ms","start":"2026-05-27T19:46:25.515466Z","end":"2026-05-27T19:46:25.839998Z","steps":["trace[262914023] 'process raft request' (duration: 323.715006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.840015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.451386Z","time spent":"388.549962ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:25.840077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.515434Z","time spent":"324.60765ms","remote":"127.0.0.1:42090","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-05-27T19:46:25.840165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.05938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:25.840225Z","caller":"traceutil/trace.go:171","msg":"trace[1867739432] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:5021; }","duration":"203.130791ms","start":"2026-05-27T19:46:25.637077Z","end":"2026-05-27T19:46:25.840208Z","steps":["trace[1867739432] 'agreement among raft nodes before linearized reading' (duration: 203.04727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.840252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.557497ms","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-05-27T19:46:25.840292Z","caller":"traceutil/trace.go:171","msg":"trace[1081305302] 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:5021; }","duration":"204.626769ms","start":"2026-05-27T19:46:25.635654Z","end":"2026-05-27T19:46:25.840281Z","steps":["trace[1081305302] 'agreement among raft nodes before linearized reading' (duration: 204.503066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.840411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.049359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T19:46:25.84055Z","caller":"traceutil/trace.go:171","msg":"trace[799905709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5021; }","duration":"325.215341ms","start":"2026-05-27T19:46:25.51532Z","end":"2026-05-27T19:46:25.840536Z","steps":["trace[799905709] 'agreement among raft nodes before linearized reading' (duration: 324.56233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:25.840643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.515306Z","time spent":"325.315003ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T19:46:58.228799Z","caller":"traceutil/trace.go:171","msg":"trace[1112798626] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"136.628778ms","start":"2026-05-27T19:46:58.092146Z","end":"2026-05-27T19:46:58.228775Z","steps":["trace[1112798626] 'process raft request' (duration: 136.498526ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:58.233283Z","caller":"traceutil/trace.go:171","msg":"trace[479695394] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"140.30004ms","start":"2026-05-27T19:46:58.09296Z","end":"2026-05-27T19:46:58.23326Z","steps":["trace[479695394] 'process raft request' (duration: 140.185678ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:08.289929Z","caller":"traceutil/trace.go:171","msg":"trace[440188538] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5599; }","duration":"215.156009ms","start":"2026-05-27T19:47:08.074734Z","end":"2026-05-27T19:47:08.28989Z","steps":["trace[440188538] 'read index received' (duration: 175.320134ms)","trace[440188538] 'applied index is now lower than readState.Index' (duration: 39.833795ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:08.290009Z","caller":"traceutil/trace.go:171","msg":"trace[2138413776] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"247.733681ms","start":"2026-05-27T19:47:08.042234Z","end":"2026-05-27T19:47:08.289968Z","steps":["trace[2138413776] 'process raft request' (duration: 207.832365ms)","trace[2138413776] 'compare' (duration: 39.644382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:08.290107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.349812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:08.290247Z","caller":"traceutil/trace.go:171","msg":"trace[32760940] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5396; }","duration":"215.539535ms","start":"2026-05-27T19:47:08.074685Z","end":"2026-05-27T19:47:08.290224Z","steps":["trace[32760940] 'agreement among raft nodes before linearized reading' (duration: 215.336981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.290163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.748562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:08.290663Z","caller":"traceutil/trace.go:171","msg":"trace[1615834902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5396; }","duration":"126.267802ms","start":"2026-05-27T19:47:08.16438Z","end":"2026-05-27T19:47:08.290648Z","steps":["trace[1615834902] 'agreement among raft nodes before linearized reading' (duration: 125.713782ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:08.623397Z","caller":"traceutil/trace.go:171","msg":"trace[996903826] linearizableReadLoop","detail":"{readStateIndex:5602; appliedIndex:5601; }","duration":"262.607294ms","start":"2026-05-27T19:47:08.36076Z","end":"2026-05-27T19:47:08.623368Z","steps":["trace[996903826] 'read index received' (duration: 172.038968ms)","trace[996903826] 'applied index is now lower than readState.Index' (duration: 90.567226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:08.623676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.544979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:47:08.6237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.919639ms","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-05-27T19:47:08.623742Z","caller":"traceutil/trace.go:171","msg":"trace[1966052553] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:5398; }","duration":"260.649083ms","start":"2026-05-27T19:47:08.363079Z","end":"2026-05-27T19:47:08.623728Z","steps":["trace[1966052553] 'agreement among raft nodes before linearized reading' (duration: 260.510989ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:08.623758Z","caller":"traceutil/trace.go:171","msg":"trace[731446274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5398; }","duration":"263.014892ms","start":"2026-05-27T19:47:08.360726Z","end":"2026-05-27T19:47:08.623741Z","steps":["trace[731446274] 'agreement among raft nodes before linearized reading' (duration: 262.825388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.623843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.008722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-27T19:47:08.623917Z","caller":"traceutil/trace.go:171","msg":"trace[2098278627] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5398; }","duration":"131.110325ms","start":"2026-05-27T19:47:08.492788Z","end":"2026-05-27T19:47:08.623898Z","steps":["trace[2098278627] 'agreement among raft nodes before linearized reading' (duration: 130.910781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.624041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.733663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T19:47:08.624083Z","caller":"traceutil/trace.go:171","msg":"trace[618759136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5398; }","duration":"168.812434ms","start":"2026-05-27T19:47:08.455261Z","end":"2026-05-27T19:47:08.624074Z","steps":["trace[618759136] 'agreement among raft nodes before linearized reading' (duration: 168.702263ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:08.623431Z","caller":"traceutil/trace.go:171","msg":"trace[146106563] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"302.892557ms","start":"2026-05-27T19:47:08.320502Z","end":"2026-05-27T19:47:08.623395Z","steps":["trace[146106563] 'process raft request' (duration: 212.324221ms)","trace[146106563] 'compare' (duration: 90.409254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:08.624203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.320486Z","time spent":"303.635111ms","remote":"127.0.0.1:56902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:09.242989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.998656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:47:09.243315Z","caller":"traceutil/trace.go:171","msg":"trace[488577189] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"432.846419ms","start":"2026-05-27T19:47:08.810423Z","end":"2026-05-27T19:47:09.24327Z","steps":["trace[488577189] 'process raft request' (duration: 63.481597ms)","trace[488577189] 'compare' (duration: 368.831522ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:09.243342Z","caller":"traceutil/trace.go:171","msg":"trace[23399883] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"430.50615ms","start":"2026-05-27T19:47:08.812799Z","end":"2026-05-27T19:47:09.243305Z","steps":["trace[23399883] 'process raft request' (duration: 430.284396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:09.243448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.810405Z","time spent":"432.973571ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:09.24373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.812768Z","time spent":"430.789954ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:47:26.268049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-05-27T19:47:26.402263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"131.975377ms","hash":3154373711,"current-db-size-bytes":53428224,"current-db-size":"53 MB","current-db-size-in-use-bytes":28839936,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T19:47:26.402339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3154373711,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-05-27T19:47:36.746153Z","caller":"traceutil/trace.go:171","msg":"trace[351831282] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5827; }","duration":"134.620506ms","start":"2026-05-27T19:47:36.611502Z","end":"2026-05-27T19:47:36.746123Z","steps":["trace[351831282] 'read index received' (duration: 127.436977ms)","trace[351831282] 'applied index is now lower than readState.Index' (duration: 7.181669ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:36.746457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.938341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T19:47:36.746525Z","caller":"traceutil/trace.go:171","msg":"trace[1898914738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5616; }","duration":"135.057074ms","start":"2026-05-27T19:47:36.611455Z","end":"2026-05-27T19:47:36.746512Z","steps":["trace[1898914738] 'agreement among raft nodes before linearized reading' (duration: 134.82649ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:36.746557Z","caller":"traceutil/trace.go:171","msg":"trace[1514402637] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"171.827595ms","start":"2026-05-27T19:47:36.574716Z","end":"2026-05-27T19:47:36.746544Z","steps":["trace[1514402637] 'process raft request' (duration: 164.286029ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:59.882408Z","caller":"traceutil/trace.go:171","msg":"trace[1151170002] transaction","detail":"{read_only:false; response_revision:5777; number_of_response:1; }","duration":"106.518644ms","start":"2026-05-27T19:47:59.775864Z","end":"2026-05-27T19:47:59.882382Z","steps":["trace[1151170002] 'process raft request' (duration: 106.343541ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:59.894456Z","caller":"traceutil/trace.go:171","msg":"trace[822474950] transaction","detail":"{read_only:false; response_revision:5778; number_of_response:1; }","duration":"118.45007ms","start":"2026-05-27T19:47:59.775984Z","end":"2026-05-27T19:47:59.894434Z","steps":["trace[822474950] 'process raft request' (duration: 118.328688ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:12.622284Z","caller":"traceutil/trace.go:171","msg":"trace[232659862] linearizableReadLoop","detail":"{readStateIndex:6918; appliedIndex:6917; }","duration":"199.580082ms","start":"2026-05-27T19:50:12.422679Z","end":"2026-05-27T19:50:12.622259Z","steps":["trace[232659862] 'read index received' (duration: 199.307367ms)","trace[232659862] 'applied index is now lower than readState.Index' (duration: 271.655µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:50:12.622399Z","caller":"traceutil/trace.go:171","msg":"trace[1829449446] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"211.305864ms","start":"2026-05-27T19:50:12.411043Z","end":"2026-05-27T19:50:12.622349Z","steps":["trace[1829449446] 'process raft request' (duration: 211.07245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:12.622556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.859846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T19:50:12.622627Z","caller":"traceutil/trace.go:171","msg":"trace[178258135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6669; }","duration":"199.966757ms","start":"2026-05-27T19:50:12.422642Z","end":"2026-05-27T19:50:12.622609Z","steps":["trace[178258135] 'agreement among raft nodes before linearized reading' (duration: 199.749904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:12.623108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.402903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:50:12.62317Z","caller":"traceutil/trace.go:171","msg":"trace[493647560] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:6669; }","duration":"182.497764ms","start":"2026-05-27T19:50:12.440653Z","end":"2026-05-27T19:50:12.623151Z","steps":["trace[493647560] 'agreement among raft nodes before linearized reading' (duration: 181.812484ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:13.13941Z","caller":"traceutil/trace.go:171","msg":"trace[1037661704] transaction","detail":"{read_only:false; response_revision:6671; number_of_response:1; }","duration":"245.471656ms","start":"2026-05-27T19:50:12.893912Z","end":"2026-05-27T19:50:13.139383Z","steps":["trace[1037661704] 'process raft request' (duration: 245.311044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.666241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2396933608839820430,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:50:13.855761Z","caller":"traceutil/trace.go:171","msg":"trace[2029376278] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"843.437134ms","start":"2026-05-27T19:50:13.012301Z","end":"2026-05-27T19:50:13.855738Z","steps":["trace[2029376278] 'process raft request' (duration: 843.312102ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:13.855762Z","caller":"traceutil/trace.go:171","msg":"trace[1445582952] transaction","detail":"{read_only:false; response_revision:6672; number_of_response:1; }","duration":"961.730298ms","start":"2026-05-27T19:50:12.893998Z","end":"2026-05-27T19:50:13.855728Z","steps":["trace[1445582952] 'process raft request' (duration: 944.646561ms)","trace[1445582952] 'compare' (duration: 16.64538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:50:13.856015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:50:12.893935Z","time spent":"961.921631ms","remote":"127.0.0.1:42090","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-05-27T19:50:13.856621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:50:13.01227Z","time spent":"843.575716ms","remote":"127.0.0.1:42090","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-05-27T19:50:13.896711Z","caller":"traceutil/trace.go:171","msg":"trace[350367565] linearizableReadLoop","detail":"{readStateIndex:6923; appliedIndex:6920; }","duration":"731.064993ms","start":"2026-05-27T19:50:13.165617Z","end":"2026-05-27T19:50:13.896682Z","steps":["trace[350367565] 'read index received' (duration: 673.001819ms)","trace[350367565] 'applied index is now lower than readState.Index' (duration: 58.061904ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:50:13.896785Z","caller":"traceutil/trace.go:171","msg":"trace[1959831420] transaction","detail":"{read_only:false; response_revision:6674; number_of_response:1; }","duration":"323.42692ms","start":"2026-05-27T19:50:13.573325Z","end":"2026-05-27T19:50:13.896752Z","steps":["trace[1959831420] 'process raft request' (duration: 323.183516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.896952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:50:13.573292Z","time spent":"323.572963ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:50:13.897252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.096307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-27T19:50:13.897259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.88374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T19:50:13.897344Z","caller":"traceutil/trace.go:171","msg":"trace[984257413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6674; }","duration":"293.984452ms","start":"2026-05-27T19:50:13.603346Z","end":"2026-05-27T19:50:13.897331Z","steps":["trace[984257413] 'agreement among raft nodes before linearized reading' (duration: 293.529825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.897478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.9223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T19:50:13.897564Z","caller":"traceutil/trace.go:171","msg":"trace[197686642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6674; }","duration":"241.205669ms","start":"2026-05-27T19:50:13.656122Z","end":"2026-05-27T19:50:13.897327Z","steps":["trace[197686642] 'agreement among raft nodes before linearized reading' (duration: 240.886984ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:13.897523Z","caller":"traceutil/trace.go:171","msg":"trace[38775628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6674; }","duration":"224.006861ms","start":"2026-05-27T19:50:13.673505Z","end":"2026-05-27T19:50:13.897512Z","steps":["trace[38775628] 'agreement among raft nodes before linearized reading' (duration: 223.391311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.898434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.811671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:50:13.898613Z","caller":"traceutil/trace.go:171","msg":"trace[184609001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6674; }","duration":"733.023713ms","start":"2026-05-27T19:50:13.165575Z","end":"2026-05-27T19:50:13.898599Z","steps":["trace[184609001] 'agreement among raft nodes before linearized reading' (duration: 732.82115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.898684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:50:13.165559Z","time spent":"733.088124ms","remote":"127.0.0.1:41864","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-05-27T19:50:41.902706Z","caller":"traceutil/trace.go:171","msg":"trace[1990795960] transaction","detail":"{read_only:false; response_revision:6873; number_of_response:1; }","duration":"107.893415ms","start":"2026-05-27T19:50:41.794778Z","end":"2026-05-27T19:50:41.902671Z","steps":["trace[1990795960] 'process raft request' (duration: 107.48158ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:26.275915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5533} {"level":"info","ts":"2026-05-27T19:52:26.358409Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5533,"took":"80.625467ms","hash":322278564,"current-db-size-bytes":53428224,"current-db-size":"53 MB","current-db-size-in-use-bytes":18436096,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-27T19:52:26.358509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":322278564,"revision":5533,"compact-revision":2885}