{"level":"warn","ts":"2026-08-19T20:28:33.154941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T20:28:33.155031Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.240: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.240:2380","--initial-cluster=instance=https://199.19.213.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-19T20:28:33.155097Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T20:28:33.155104Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.240:2380"]} {"level":"info","ts":"2026-08-19T20:28:33.155122Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-19T20:28:33.155716Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"]} {"level":"info","ts":"2026-08-19T20:28:33.155783Z","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.240:2380"],"listen-peer-urls":["https://199.19.213.240:2380"],"advertise-client-urls":["https://199.19.213.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-19T20:28:33.162039Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.022958ms"} {"level":"info","ts":"2026-08-19T20:28:33.17197Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"65ae7913d9d30912","cluster-id":"74cb0f8fbd7d5970"} {"level":"info","ts":"2026-08-19T20:28:33.172025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T20:28:33.172047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became follower at term 0"} {"level":"info","ts":"2026-08-19T20:28:33.172055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 65ae7913d9d30912 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T20:28:33.172063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became follower at term 1"} {"level":"info","ts":"2026-08-19T20:28:33.17209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=(7326926769944201490)"} {"level":"warn","ts":"2026-08-19T20:28:33.181283Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T20:28:33.182844Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T20:28:33.184067Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-19T20:28:33.186677Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"65ae7913d9d30912","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T20:28:33.186862Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"65ae7913d9d30912","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T20:28:33.186981Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-19T20:28:33.18705Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-19T20:28:33.187062Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-19T20:28:33.187985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 switched to configuration voters=(7326926769944201490)"} {"level":"info","ts":"2026-08-19T20:28:33.192954Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"74cb0f8fbd7d5970","local-member-id":"65ae7913d9d30912","added-peer-id":"65ae7913d9d30912","added-peer-peer-urls":["https://199.19.213.240:2380"]} {"level":"info","ts":"2026-08-19T20:28:33.194632Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-19T20:28:33.194752Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.240:2380"} {"level":"info","ts":"2026-08-19T20:28:33.194794Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.240:2380"} {"level":"info","ts":"2026-08-19T20:28:33.194901Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"65ae7913d9d30912","initial-advertise-peer-urls":["https://199.19.213.240:2380"],"listen-peer-urls":["https://199.19.213.240:2380"],"advertise-client-urls":["https://199.19.213.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T20:28:33.194935Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T20:28:34.172908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T20:28:34.172967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T20:28:34.172982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 received MsgPreVoteResp from 65ae7913d9d30912 at term 1"} {"level":"info","ts":"2026-08-19T20:28:34.172996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became candidate at term 2"} {"level":"info","ts":"2026-08-19T20:28:34.173004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 received MsgVoteResp from 65ae7913d9d30912 at term 2"} {"level":"info","ts":"2026-08-19T20:28:34.173037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"65ae7913d9d30912 became leader at term 2"} {"level":"info","ts":"2026-08-19T20:28:34.173051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 65ae7913d9d30912 elected leader 65ae7913d9d30912 at term 2"} {"level":"info","ts":"2026-08-19T20:28:34.174125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"65ae7913d9d30912","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.240:2379]}","request-path":"/0/members/65ae7913d9d30912/attributes","cluster-id":"74cb0f8fbd7d5970","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T20:28:34.174131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:28:34.174165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:28:34.174195Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:28:34.174478Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T20:28:34.174524Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T20:28:34.175764Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"74cb0f8fbd7d5970","local-member-id":"65ae7913d9d30912","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:28:34.175904Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:28:34.175949Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:28:34.176235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.240:2379"} {"level":"info","ts":"2026-08-19T20:28:34.178347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T20:28:36.702521Z","caller":"traceutil/trace.go:171","msg":"trace[683797488] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:136; }","duration":"145.142836ms","start":"2026-08-19T20:28:36.557352Z","end":"2026-08-19T20:28:36.702495Z","steps":["trace[683797488] 'read index received' (duration: 144.508111ms)","trace[683797488] 'applied index is now lower than readState.Index' (duration: 633.575µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:36.702643Z","caller":"traceutil/trace.go:171","msg":"trace[1597177200] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"160.698697ms","start":"2026-08-19T20:28:36.541913Z","end":"2026-08-19T20:28:36.702611Z","steps":["trace[1597177200] 'process raft request' (duration: 159.867894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:36.702881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.507831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:36.703191Z","caller":"traceutil/trace.go:171","msg":"trace[1469097556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:133; }","duration":"145.871365ms","start":"2026-08-19T20:28:36.557302Z","end":"2026-08-19T20:28:36.703173Z","steps":["trace[1469097556] 'agreement among raft nodes before linearized reading' (duration: 145.389986ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:36.704088Z","caller":"traceutil/trace.go:171","msg":"trace[503031122] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"144.456159ms","start":"2026-08-19T20:28:36.55961Z","end":"2026-08-19T20:28:36.704066Z","steps":["trace[503031122] 'process raft request' (duration: 144.311684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:37.203645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.916793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:37.203797Z","caller":"traceutil/trace.go:171","msg":"trace[254679396] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"249.827996ms","start":"2026-08-19T20:28:36.953945Z","end":"2026-08-19T20:28:37.203773Z","steps":["trace[254679396] 'process raft request' (duration: 27.179344ms)","trace[254679396] 'compare' (duration: 221.741285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:38.559561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.61956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:38.559739Z","caller":"traceutil/trace.go:171","msg":"trace[23705085] linearizableReadLoop","detail":"{readStateIndex:158; appliedIndex:157; }","duration":"1.102014182s","start":"2026-08-19T20:28:37.457699Z","end":"2026-08-19T20:28:38.559713Z","steps":["trace[23705085] 'read index received' (duration: 247.029227ms)","trace[23705085] 'applied index is now lower than readState.Index' (duration: 854.983725ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:38.559769Z","caller":"traceutil/trace.go:171","msg":"trace[1168566670] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"1.349751297s","start":"2026-08-19T20:28:37.209973Z","end":"2026-08-19T20:28:38.559725Z","steps":["trace[1168566670] 'process raft request' (duration: 494.842936ms)","trace[1168566670] 'compare' (duration: 854.406511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:38.559874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102161678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:38.5599Z","caller":"traceutil/trace.go:171","msg":"trace[1934675731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:154; }","duration":"1.10222481s","start":"2026-08-19T20:28:37.457665Z","end":"2026-08-19T20:28:38.559889Z","steps":["trace[1934675731] 'agreement among raft nodes before linearized reading' (duration: 1.102128846s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:38.559932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:37.457646Z","time spent":"1.102279163s","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:28:38.559961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:37.209959Z","time spent":"1.349894963s","remote":"127.0.0.1:56926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:38.807563Z","caller":"traceutil/trace.go:171","msg":"trace[396657460] linearizableReadLoop","detail":"{readStateIndex:159; appliedIndex:158; }","duration":"247.745072ms","start":"2026-08-19T20:28:38.559762Z","end":"2026-08-19T20:28:38.807507Z","steps":["trace[396657460] 'read index received' (duration: 246.864846ms)","trace[396657460] 'applied index is now lower than readState.Index' (duration: 879.196µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:38.807619Z","caller":"traceutil/trace.go:171","msg":"trace[805615436] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"452.990417ms","start":"2026-08-19T20:28:38.354599Z","end":"2026-08-19T20:28:38.80759Z","steps":["trace[805615436] 'process raft request' (duration: 452.013767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:38.807737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.706154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:28:38.807758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.910253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:38.807807Z","caller":"traceutil/trace.go:171","msg":"trace[398275377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:155; }","duration":"664.003957ms","start":"2026-08-19T20:28:38.143792Z","end":"2026-08-19T20:28:38.807796Z","steps":["trace[398275377] 'agreement among raft nodes before linearized reading' (duration: 663.900533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:38.807855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:38.143779Z","time spent":"664.06774ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T20:28:38.807861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.686547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:28:38.807875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204420347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-19T20:28:38.807871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.805804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:28:38.807893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:38.354577Z","time spent":"453.205096ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:28:38.807976Z","caller":"traceutil/trace.go:171","msg":"trace[1024027493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:155; }","duration":"293.809108ms","start":"2026-08-19T20:28:38.513972Z","end":"2026-08-19T20:28:38.807781Z","steps":["trace[1024027493] 'agreement among raft nodes before linearized reading' (duration: 293.659322ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:38.808016Z","caller":"traceutil/trace.go:171","msg":"trace[31168804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:155; }","duration":"246.988061ms","start":"2026-08-19T20:28:38.560978Z","end":"2026-08-19T20:28:38.807966Z","steps":["trace[31168804] 'agreement among raft nodes before linearized reading' (duration: 246.789743ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:38.807923Z","caller":"traceutil/trace.go:171","msg":"trace[1580253951] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:0; response_revision:155; }","duration":"244.75718ms","start":"2026-08-19T20:28:38.563148Z","end":"2026-08-19T20:28:38.807906Z","steps":["trace[1580253951] 'agreement among raft nodes before linearized reading' (duration: 244.653866ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:38.807928Z","caller":"traceutil/trace.go:171","msg":"trace[994147822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:155; }","duration":"1.20450694s","start":"2026-08-19T20:28:37.603405Z","end":"2026-08-19T20:28:38.807912Z","steps":["trace[994147822] 'agreement among raft nodes before linearized reading' (duration: 1.204407816s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:38.80819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:37.603378Z","time spent":"1.204802603s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T20:28:41.318377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.412671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:41.318494Z","caller":"traceutil/trace.go:171","msg":"trace[1203681012] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:300; }","duration":"241.358953ms","start":"2026-08-19T20:28:41.077114Z","end":"2026-08-19T20:28:41.318473Z","steps":["trace[1203681012] 'read index received' (duration: 108.859969ms)","trace[1203681012] 'applied index is now lower than readState.Index' (duration: 132.497674ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:41.318541Z","caller":"traceutil/trace.go:171","msg":"trace[1758103762] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"289.841976ms","start":"2026-08-19T20:28:41.028657Z","end":"2026-08-19T20:28:41.318499Z","steps":["trace[1758103762] 'process raft request' (duration: 157.233687ms)","trace[1758103762] 'compare' (duration: 132.263325ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:41.318608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.485399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-19T20:28:41.318669Z","caller":"traceutil/trace.go:171","msg":"trace[19225219] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"241.547141ms","start":"2026-08-19T20:28:41.077108Z","end":"2026-08-19T20:28:41.318655Z","steps":["trace[19225219] 'agreement among raft nodes before linearized reading' (duration: 241.422776ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322081Z","caller":"traceutil/trace.go:171","msg":"trace[2066527722] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"191.240019ms","start":"2026-08-19T20:28:41.13082Z","end":"2026-08-19T20:28:41.32206Z","steps":["trace[2066527722] 'process raft request' (duration: 191.214258ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322143Z","caller":"traceutil/trace.go:171","msg":"trace[1676085574] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"208.424668ms","start":"2026-08-19T20:28:41.1137Z","end":"2026-08-19T20:28:41.322125Z","steps":["trace[1676085574] 'process raft request' (duration: 208.204579ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322182Z","caller":"traceutil/trace.go:171","msg":"trace[826662421] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"192.722193ms","start":"2026-08-19T20:28:41.129439Z","end":"2026-08-19T20:28:41.322161Z","steps":["trace[826662421] 'process raft request' (duration: 192.520954ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322345Z","caller":"traceutil/trace.go:171","msg":"trace[1615153134] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"193.35573ms","start":"2026-08-19T20:28:41.128979Z","end":"2026-08-19T20:28:41.322334Z","steps":["trace[1615153134] 'process raft request' (duration: 192.956353ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322388Z","caller":"traceutil/trace.go:171","msg":"trace[379639509] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"244.167554ms","start":"2026-08-19T20:28:41.078208Z","end":"2026-08-19T20:28:41.322376Z","steps":["trace[379639509] 'process raft request' (duration: 243.559988ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322406Z","caller":"traceutil/trace.go:171","msg":"trace[1705435262] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"192.229692ms","start":"2026-08-19T20:28:41.130165Z","end":"2026-08-19T20:28:41.322395Z","steps":["trace[1705435262] 'process raft request' (duration: 191.831635ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.32249Z","caller":"traceutil/trace.go:171","msg":"trace[1908396979] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"191.928409ms","start":"2026-08-19T20:28:41.130555Z","end":"2026-08-19T20:28:41.322484Z","steps":["trace[1908396979] 'process raft request' (duration: 191.462679ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.322517Z","caller":"traceutil/trace.go:171","msg":"trace[433550582] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"209.721314ms","start":"2026-08-19T20:28:41.112787Z","end":"2026-08-19T20:28:41.322508Z","steps":["trace[433550582] 'process raft request' (duration: 209.083636ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.577931Z","caller":"traceutil/trace.go:171","msg":"trace[870515711] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"224.404985ms","start":"2026-08-19T20:28:41.353477Z","end":"2026-08-19T20:28:41.577882Z","steps":["trace[870515711] 'process raft request' (duration: 174.247229ms)","trace[870515711] 'compare' (duration: 49.922466ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:41.577977Z","caller":"traceutil/trace.go:171","msg":"trace[569907459] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:312; }","duration":"142.828198ms","start":"2026-08-19T20:28:41.435123Z","end":"2026-08-19T20:28:41.577951Z","steps":["trace[569907459] 'read index received' (duration: 92.780287ms)","trace[569907459] 'applied index is now lower than readState.Index' (duration: 50.047001ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:41.578009Z","caller":"traceutil/trace.go:171","msg":"trace[601947685] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"184.472079ms","start":"2026-08-19T20:28:41.393516Z","end":"2026-08-19T20:28:41.577988Z","steps":["trace[601947685] 'process raft request' (duration: 184.353254ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.578061Z","caller":"traceutil/trace.go:171","msg":"trace[288536281] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"218.364064ms","start":"2026-08-19T20:28:41.359685Z","end":"2026-08-19T20:28:41.578049Z","steps":["trace[288536281] 'process raft request' (duration: 218.103493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:41.578268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.131962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T20:28:41.578328Z","caller":"traceutil/trace.go:171","msg":"trace[733849385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:305; }","duration":"143.227606ms","start":"2026-08-19T20:28:41.435082Z","end":"2026-08-19T20:28:41.57831Z","steps":["trace[733849385] 'agreement among raft nodes before linearized reading' (duration: 143.072269ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:41.578274Z","caller":"traceutil/trace.go:171","msg":"trace[1494603633] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"183.565959ms","start":"2026-08-19T20:28:41.394546Z","end":"2026-08-19T20:28:41.578112Z","steps":["trace[1494603633] 'process raft request' (duration: 183.35932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:42.019313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.950211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:42.019486Z","caller":"traceutil/trace.go:171","msg":"trace[1039653999] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"368.759837ms","start":"2026-08-19T20:28:41.650709Z","end":"2026-08-19T20:28:42.019469Z","steps":["trace[1039653999] 'read index received' (duration: 108.603658ms)","trace[1039653999] 'applied index is now lower than readState.Index' (duration: 260.154609ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:42.019573Z","caller":"traceutil/trace.go:171","msg":"trace[2061099368] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"370.659539ms","start":"2026-08-19T20:28:41.648872Z","end":"2026-08-19T20:28:42.019531Z","steps":["trace[2061099368] 'process raft request' (duration: 110.419236ms)","trace[2061099368] 'compare' (duration: 259.676158ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:42.019752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:41.648854Z","time spent":"370.805115ms","remote":"127.0.0.1:56820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T20:28:42.019869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.145704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-19T20:28:42.019957Z","caller":"traceutil/trace.go:171","msg":"trace[698925261] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"369.249999ms","start":"2026-08-19T20:28:41.650693Z","end":"2026-08-19T20:28:42.019943Z","steps":["trace[698925261] 'agreement among raft nodes before linearized reading' (duration: 369.025409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:42.020038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:41.650686Z","time spent":"369.339192ms","remote":"127.0.0.1:56734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-19T20:28:42.225163Z","caller":"traceutil/trace.go:171","msg":"trace[2030081067] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"201.171175ms","start":"2026-08-19T20:28:42.02394Z","end":"2026-08-19T20:28:42.225111Z","steps":["trace[2030081067] 'process raft request' (duration: 153.144082ms)","trace[2030081067] 'compare' (duration: 47.711669ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:42.225246Z","caller":"traceutil/trace.go:171","msg":"trace[308300957] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"175.783139ms","start":"2026-08-19T20:28:42.049455Z","end":"2026-08-19T20:28:42.225238Z","steps":["trace[308300957] 'process raft request' (duration: 175.628893ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:42.225305Z","caller":"traceutil/trace.go:171","msg":"trace[934869600] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:321; }","duration":"176.27167ms","start":"2026-08-19T20:28:42.049006Z","end":"2026-08-19T20:28:42.225278Z","steps":["trace[934869600] 'read index received' (duration: 9.435391ms)","trace[934869600] 'applied index is now lower than readState.Index' (duration: 166.833809ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:42.225266Z","caller":"traceutil/trace.go:171","msg":"trace[1453701499] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"163.727554ms","start":"2026-08-19T20:28:42.061523Z","end":"2026-08-19T20:28:42.225251Z","steps":["trace[1453701499] 'process raft request' (duration: 163.612179ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:42.225166Z","caller":"traceutil/trace.go:171","msg":"trace[873910460] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"176.609795ms","start":"2026-08-19T20:28:42.048534Z","end":"2026-08-19T20:28:42.225143Z","steps":["trace[873910460] 'process raft request' (duration: 176.47971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:42.225466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.439358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-08-19T20:28:42.2255Z","caller":"traceutil/trace.go:171","msg":"trace[663841898] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:313; }","duration":"176.48882ms","start":"2026-08-19T20:28:42.049001Z","end":"2026-08-19T20:28:42.225489Z","steps":["trace[663841898] 'agreement among raft nodes before linearized reading' (duration: 176.369415ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:42.225652Z","caller":"traceutil/trace.go:171","msg":"trace[226453620] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"176.084342ms","start":"2026-08-19T20:28:42.04956Z","end":"2026-08-19T20:28:42.225644Z","steps":["trace[226453620] 'process raft request' (duration: 175.546389ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:42.225721Z","caller":"traceutil/trace.go:171","msg":"trace[848804562] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"176.589464ms","start":"2026-08-19T20:28:42.049107Z","end":"2026-08-19T20:28:42.225696Z","steps":["trace[848804562] 'process raft request' (duration: 175.954257ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:42.363426Z","caller":"traceutil/trace.go:171","msg":"trace[1630272959] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"117.479018ms","start":"2026-08-19T20:28:42.245919Z","end":"2026-08-19T20:28:42.363398Z","steps":["trace[1630272959] 'process raft request' (duration: 95.611445ms)","trace[1630272959] 'compare' (duration: 21.695876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:42.363545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.704128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-19T20:28:42.363582Z","caller":"traceutil/trace.go:171","msg":"trace[1517233707] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"100.783691ms","start":"2026-08-19T20:28:42.262789Z","end":"2026-08-19T20:28:42.363573Z","steps":["trace[1517233707] 'agreement among raft nodes before linearized reading' (duration: 100.683527ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:42.363698Z","caller":"traceutil/trace.go:171","msg":"trace[619222399] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"100.523219ms","start":"2026-08-19T20:28:42.262833Z","end":"2026-08-19T20:28:42.363356Z","steps":["trace[619222399] 'read index received' (duration: 78.681008ms)","trace[619222399] 'applied index is now lower than readState.Index' (duration: 21.841531ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:42.577986Z","caller":"traceutil/trace.go:171","msg":"trace[1512204934] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"117.493409ms","start":"2026-08-19T20:28:42.460467Z","end":"2026-08-19T20:28:42.577961Z","steps":["trace[1512204934] 'read index received' (duration: 70.384397ms)","trace[1512204934] 'applied index is now lower than readState.Index' (duration: 47.107942ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:42.578049Z","caller":"traceutil/trace.go:171","msg":"trace[1020372693] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"129.603417ms","start":"2026-08-19T20:28:42.448417Z","end":"2026-08-19T20:28:42.57802Z","steps":["trace[1020372693] 'process raft request' (duration: 82.448812ms)","trace[1020372693] 'compare' (duration: 46.980827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:42.578113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.618394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:28:42.578148Z","caller":"traceutil/trace.go:171","msg":"trace[461429768] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:320; }","duration":"117.704479ms","start":"2026-08-19T20:28:42.460431Z","end":"2026-08-19T20:28:42.578136Z","steps":["trace[461429768] 'agreement among raft nodes before linearized reading' (duration: 117.624185ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:44.588832Z","caller":"traceutil/trace.go:171","msg":"trace[300962579] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"172.263976ms","start":"2026-08-19T20:28:44.416543Z","end":"2026-08-19T20:28:44.588807Z","steps":["trace[300962579] 'process raft request' (duration: 172.124169ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:44.588853Z","caller":"traceutil/trace.go:171","msg":"trace[1552795243] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"143.337201ms","start":"2026-08-19T20:28:44.445482Z","end":"2026-08-19T20:28:44.588819Z","steps":["trace[1552795243] 'process raft request' (duration: 143.270578ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:44.58909Z","caller":"traceutil/trace.go:171","msg":"trace[1897293279] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"172.721496ms","start":"2026-08-19T20:28:44.416354Z","end":"2026-08-19T20:28:44.589076Z","steps":["trace[1897293279] 'process raft request' (duration: 99.319862ms)","trace[1897293279] 'compare' (duration: 72.792797ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:44.589425Z","caller":"traceutil/trace.go:171","msg":"trace[933239416] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:359; }","duration":"168.508628ms","start":"2026-08-19T20:28:44.420902Z","end":"2026-08-19T20:28:44.589411Z","steps":["trace[933239416] 'read index received' (duration: 19.809536ms)","trace[933239416] 'applied index is now lower than readState.Index' (duration: 148.697982ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:44.589607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.695085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-19T20:28:44.58965Z","caller":"traceutil/trace.go:171","msg":"trace[1492164605] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"168.78022ms","start":"2026-08-19T20:28:44.420857Z","end":"2026-08-19T20:28:44.589638Z","steps":["trace[1492164605] 'agreement among raft nodes before linearized reading' (duration: 168.644084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:44.589814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.073221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-19T20:28:44.589851Z","caller":"traceutil/trace.go:171","msg":"trace[798392454] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"118.155825ms","start":"2026-08-19T20:28:44.471686Z","end":"2026-08-19T20:28:44.589842Z","steps":["trace[798392454] 'agreement among raft nodes before linearized reading' (duration: 118.093932ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:45.809945Z","caller":"traceutil/trace.go:171","msg":"trace[447689242] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"177.92483ms","start":"2026-08-19T20:28:45.631994Z","end":"2026-08-19T20:28:45.809919Z","steps":["trace[447689242] 'process raft request' (duration: 177.745051ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:48.993779Z","caller":"traceutil/trace.go:171","msg":"trace[440937857] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"126.293099ms","start":"2026-08-19T20:28:48.867447Z","end":"2026-08-19T20:28:48.99374Z","steps":["trace[440937857] 'read index received' (duration: 124.436151ms)","trace[440937857] 'applied index is now lower than readState.Index' (duration: 1.856038ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:48.993814Z","caller":"traceutil/trace.go:171","msg":"trace[567816334] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"166.915375ms","start":"2026-08-19T20:28:48.826878Z","end":"2026-08-19T20:28:48.993793Z","steps":["trace[567816334] 'process raft request' (duration: 165.060968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:48.993905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.449825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T20:28:48.993944Z","caller":"traceutil/trace.go:171","msg":"trace[387531792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"126.52938ms","start":"2026-08-19T20:28:48.867405Z","end":"2026-08-19T20:28:48.993934Z","steps":["trace[387531792] 'agreement among raft nodes before linearized reading' (duration: 126.453126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:48.993917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.209876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T20:28:48.994037Z","caller":"traceutil/trace.go:171","msg":"trace[7523231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"119.330282ms","start":"2026-08-19T20:28:48.874696Z","end":"2026-08-19T20:28:48.994026Z","steps":["trace[7523231] 'agreement among raft nodes before linearized reading' (duration: 119.192506ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:28:49.332169Z","caller":"traceutil/trace.go:171","msg":"trace[1821179527] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"265.531336ms","start":"2026-08-19T20:28:49.066608Z","end":"2026-08-19T20:28:49.33214Z","steps":["trace[1821179527] 'process raft request' (duration: 167.721832ms)","trace[1821179527] 'compare' (duration: 97.679227ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:50.941638Z","caller":"traceutil/trace.go:171","msg":"trace[966485672] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"142.984534ms","start":"2026-08-19T20:28:50.798624Z","end":"2026-08-19T20:28:50.941609Z","steps":["trace[966485672] 'process raft request' (duration: 142.816556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:51.402489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.763411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:28:51.402718Z","caller":"traceutil/trace.go:171","msg":"trace[1013586588] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"393.497349ms","start":"2026-08-19T20:28:51.009193Z","end":"2026-08-19T20:28:51.402691Z","steps":["trace[1013586588] 'process raft request' (duration: 52.316328ms)","trace[1013586588] 'compare' (duration: 340.654976ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:51.402843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:51.009169Z","time spent":"393.616345ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:51.402711Z","caller":"traceutil/trace.go:171","msg":"trace[907913720] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"391.770265ms","start":"2026-08-19T20:28:51.010925Z","end":"2026-08-19T20:28:51.402696Z","steps":["trace[907913720] 'process raft request' (duration: 391.697062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:28:51.403328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:28:51.010891Z","time spent":"392.382365ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:28:51.638616Z","caller":"traceutil/trace.go:171","msg":"trace[1017888134] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"160.743637ms","start":"2026-08-19T20:28:51.477854Z","end":"2026-08-19T20:28:51.638598Z","steps":["trace[1017888134] 'process raft request' (duration: 118.434475ms)","trace[1017888134] 'compare' (duration: 42.233909ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:28:51.814917Z","caller":"traceutil/trace.go:171","msg":"trace[1763912399] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"141.901414ms","start":"2026-08-19T20:28:51.673Z","end":"2026-08-19T20:28:51.814901Z","steps":["trace[1763912399] 'process raft request' (duration: 73.196751ms)","trace[1763912399] 'compare' (duration: 68.63833ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:28:52.048993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.842303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T20:28:52.049043Z","caller":"traceutil/trace.go:171","msg":"trace[51738682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"128.952098ms","start":"2026-08-19T20:28:51.920081Z","end":"2026-08-19T20:28:52.049033Z","steps":["trace[51738682] 'range keys from in-memory index tree' (duration: 128.675724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:14.100626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653760936801605695,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T20:29:14.107393Z","caller":"traceutil/trace.go:171","msg":"trace[2137439917] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"507.431835ms","start":"2026-08-19T20:29:13.599929Z","end":"2026-08-19T20:29:14.107361Z","steps":["trace[2137439917] 'read index received' (duration: 507.166379ms)","trace[2137439917] 'applied index is now lower than readState.Index' (duration: 263.916µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:14.107468Z","caller":"traceutil/trace.go:171","msg":"trace[1694435488] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"535.486703ms","start":"2026-08-19T20:29:13.571958Z","end":"2026-08-19T20:29:14.107445Z","steps":["trace[1694435488] 'process raft request' (duration: 535.213217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:14.107577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.625056ms","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-08-19T20:29:14.107684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.424277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-08-19T20:29:14.107682Z","caller":"traceutil/trace.go:171","msg":"trace[404823346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"507.769875ms","start":"2026-08-19T20:29:13.599872Z","end":"2026-08-19T20:29:14.107641Z","steps":["trace[404823346] 'agreement among raft nodes before linearized reading' (duration: 507.609506ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:14.107723Z","caller":"traceutil/trace.go:171","msg":"trace[925157707] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:493; }","duration":"335.498241ms","start":"2026-08-19T20:29:13.772214Z","end":"2026-08-19T20:29:14.107713Z","steps":["trace[925157707] 'agreement among raft nodes before linearized reading' (duration: 335.418827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:14.107746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:13.772157Z","time spent":"335.582866ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12699,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-08-19T20:29:14.107746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:13.599845Z","time spent":"507.887261ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T20:29:14.108045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:13.571936Z","time spent":"535.593659ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:29:14.226136Z","caller":"traceutil/trace.go:171","msg":"trace[1240992592] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"113.801962ms","start":"2026-08-19T20:29:14.112252Z","end":"2026-08-19T20:29:14.226054Z","steps":["trace[1240992592] 'process raft request' (duration: 55.433496ms)","trace[1240992592] 'compare' (duration: 58.152974ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:17.019088Z","caller":"traceutil/trace.go:171","msg":"trace[1075144109] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"280.322596ms","start":"2026-08-19T20:29:16.738744Z","end":"2026-08-19T20:29:17.019067Z","steps":["trace[1075144109] 'process raft request' (duration: 280.23081ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030198Z","caller":"traceutil/trace.go:171","msg":"trace[384347522] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"281.984467ms","start":"2026-08-19T20:29:16.748171Z","end":"2026-08-19T20:29:17.030155Z","steps":["trace[384347522] 'process raft request' (duration: 281.827588ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030234Z","caller":"traceutil/trace.go:171","msg":"trace[1892234436] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"283.265903ms","start":"2026-08-19T20:29:16.746914Z","end":"2026-08-19T20:29:17.030179Z","steps":["trace[1892234436] 'process raft request' (duration: 282.992466ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030403Z","caller":"traceutil/trace.go:171","msg":"trace[1813597285] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"268.393764ms","start":"2026-08-19T20:29:16.761998Z","end":"2026-08-19T20:29:17.030392Z","steps":["trace[1813597285] 'process raft request' (duration: 268.120738ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030492Z","caller":"traceutil/trace.go:171","msg":"trace[1265373940] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"265.96577ms","start":"2026-08-19T20:29:16.764512Z","end":"2026-08-19T20:29:17.030477Z","steps":["trace[1265373940] 'process raft request' (duration: 265.644331ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030497Z","caller":"traceutil/trace.go:171","msg":"trace[1237207839] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"274.24175ms","start":"2026-08-19T20:29:16.756228Z","end":"2026-08-19T20:29:17.03047Z","steps":["trace[1237207839] 'process raft request' (duration: 273.817705ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030548Z","caller":"traceutil/trace.go:171","msg":"trace[962272695] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"264.571369ms","start":"2026-08-19T20:29:16.765964Z","end":"2026-08-19T20:29:17.030535Z","steps":["trace[962272695] 'process raft request' (duration: 264.317494ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030615Z","caller":"traceutil/trace.go:171","msg":"trace[336816086] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"246.279198ms","start":"2026-08-19T20:29:16.784323Z","end":"2026-08-19T20:29:17.030602Z","steps":["trace[336816086] 'process raft request' (duration: 245.998011ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030602Z","caller":"traceutil/trace.go:171","msg":"trace[982010114] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"268.516822ms","start":"2026-08-19T20:29:16.761984Z","end":"2026-08-19T20:29:17.030501Z","steps":["trace[982010114] 'process raft request' (duration: 268.098657ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030714Z","caller":"traceutil/trace.go:171","msg":"trace[567776903] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"230.591001ms","start":"2026-08-19T20:29:16.800116Z","end":"2026-08-19T20:29:17.030707Z","steps":["trace[567776903] 'process raft request' (duration: 230.248031ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030684Z","caller":"traceutil/trace.go:171","msg":"trace[1582930495] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"131.270534ms","start":"2026-08-19T20:29:16.899403Z","end":"2026-08-19T20:29:17.030674Z","steps":["trace[1582930495] 'process raft request' (duration: 131.010129ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:17.030942Z","caller":"traceutil/trace.go:171","msg":"trace[98314544] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"265.884936ms","start":"2026-08-19T20:29:16.764575Z","end":"2026-08-19T20:29:17.03046Z","steps":["trace[98314544] 'process raft request' (duration: 265.669884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:24.535335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234522357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-19T20:29:24.535448Z","caller":"traceutil/trace.go:171","msg":"trace[1897098517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"1.234666326s","start":"2026-08-19T20:29:23.300758Z","end":"2026-08-19T20:29:24.535424Z","steps":["trace[1897098517] 'range keys from in-memory index tree' (duration: 1.234340466s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:24.535531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:23.300744Z","time spent":"1.234773693s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T20:29:24.541027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.273301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:29:24.541159Z","caller":"traceutil/trace.go:171","msg":"trace[1995793259] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"486.782196ms","start":"2026-08-19T20:29:24.054332Z","end":"2026-08-19T20:29:24.541114Z","steps":["trace[1995793259] 'read index received' (duration: 314.526644ms)","trace[1995793259] 'applied index is now lower than readState.Index' (duration: 172.254172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:24.541291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.334035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T20:29:24.541324Z","caller":"traceutil/trace.go:171","msg":"trace[1532904395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"382.391169ms","start":"2026-08-19T20:29:24.158924Z","end":"2026-08-19T20:29:24.541315Z","steps":["trace[1532904395] 'agreement among raft nodes before linearized reading' (duration: 382.299823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:24.541356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:24.15889Z","time spent":"382.460553ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-19T20:29:24.541292Z","caller":"traceutil/trace.go:171","msg":"trace[1368997775] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"935.174732ms","start":"2026-08-19T20:29:23.605969Z","end":"2026-08-19T20:29:24.541144Z","steps":["trace[1368997775] 'process raft request' (duration: 763.689408ms)","trace[1368997775] 'compare' (duration: 168.247004ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:24.541549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:23.605933Z","time spent":"935.516603ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:29:24.541457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.994018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:29:24.541623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.86095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T20:29:24.541726Z","caller":"traceutil/trace.go:171","msg":"trace[1103093608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"180.01263ms","start":"2026-08-19T20:29:24.361691Z","end":"2026-08-19T20:29:24.541704Z","steps":["trace[1103093608] 'agreement among raft nodes before linearized reading' (duration: 179.764175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:24.541829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.137843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T20:29:24.541985Z","caller":"traceutil/trace.go:171","msg":"trace[2012304777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"177.318523ms","start":"2026-08-19T20:29:24.364634Z","end":"2026-08-19T20:29:24.541953Z","steps":["trace[2012304777] 'agreement among raft nodes before linearized reading' (duration: 176.646152ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:24.542073Z","caller":"traceutil/trace.go:171","msg":"trace[1713629580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"487.408864ms","start":"2026-08-19T20:29:24.054271Z","end":"2026-08-19T20:29:24.541679Z","steps":["trace[1713629580] 'agreement among raft nodes before linearized reading' (duration: 486.939605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:24.542178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:24.054252Z","time spent":"487.912575ms","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:29:25.059497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.015826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:29:25.059706Z","caller":"traceutil/trace.go:171","msg":"trace[112823110] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"514.575017ms","start":"2026-08-19T20:29:24.545106Z","end":"2026-08-19T20:29:25.059681Z","steps":["trace[112823110] 'process raft request' (duration: 299.295405ms)","trace[112823110] 'compare' (duration: 214.874057ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:25.059762Z","caller":"traceutil/trace.go:171","msg":"trace[1357567989] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"461.799153ms","start":"2026-08-19T20:29:24.597946Z","end":"2026-08-19T20:29:25.059745Z","steps":["trace[1357567989] 'process raft request' (duration: 461.739309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:25.059851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:24.545095Z","time spent":"514.673183ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:29:25.059827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:24.597908Z","time spent":"461.893038ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:29:25.059943Z","caller":"traceutil/trace.go:171","msg":"trace[277726134] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"510.585431ms","start":"2026-08-19T20:29:24.549343Z","end":"2026-08-19T20:29:25.059929Z","steps":["trace[277726134] 'process raft request' (duration: 510.305194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:25.060145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:24.549329Z","time spent":"510.744011ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:29:25.059833Z","caller":"traceutil/trace.go:171","msg":"trace[453833934] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"514.021703ms","start":"2026-08-19T20:29:24.545701Z","end":"2026-08-19T20:29:25.059722Z","steps":["trace[453833934] 'process raft request' (duration: 513.900256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:25.060266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:24.545673Z","time spent":"514.516493ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:29:26.092039Z","caller":"traceutil/trace.go:171","msg":"trace[229797366] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"985.564956ms","start":"2026-08-19T20:29:25.106449Z","end":"2026-08-19T20:29:26.092014Z","steps":["trace[229797366] 'process raft request' (duration: 985.418137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:26.092231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:25.106435Z","time spent":"985.691864ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T20:29:26.236339Z","caller":"traceutil/trace.go:171","msg":"trace[799756637] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"138.884957ms","start":"2026-08-19T20:29:26.097427Z","end":"2026-08-19T20:29:26.236312Z","steps":["trace[799756637] 'process raft request' (duration: 86.699758ms)","trace[799756637] 'compare' (duration: 51.847988ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:26.241702Z","caller":"traceutil/trace.go:171","msg":"trace[281189376] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"142.399796ms","start":"2026-08-19T20:29:26.099278Z","end":"2026-08-19T20:29:26.241678Z","steps":["trace[281189376] 'process raft request' (duration: 142.224455ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:26.241849Z","caller":"traceutil/trace.go:171","msg":"trace[1627652078] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"143.333324ms","start":"2026-08-19T20:29:26.098499Z","end":"2026-08-19T20:29:26.241832Z","steps":["trace[1627652078] 'process raft request' (duration: 142.930069ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:26.241652Z","caller":"traceutil/trace.go:171","msg":"trace[1955055628] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"143.395478ms","start":"2026-08-19T20:29:26.098189Z","end":"2026-08-19T20:29:26.241584Z","steps":["trace[1955055628] 'process raft request' (duration: 143.077498ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.553464Z","caller":"traceutil/trace.go:171","msg":"trace[1418873008] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"292.906679ms","start":"2026-08-19T20:29:29.260531Z","end":"2026-08-19T20:29:29.553437Z","steps":["trace[1418873008] 'process raft request' (duration: 279.074136ms)","trace[1418873008] 'compare' (duration: 13.518354ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:29.553607Z","caller":"traceutil/trace.go:171","msg":"trace[362742145] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:733; }","duration":"192.103796ms","start":"2026-08-19T20:29:29.361484Z","end":"2026-08-19T20:29:29.553587Z","steps":["trace[362742145] 'read index received' (duration: 178.122013ms)","trace[362742145] 'applied index is now lower than readState.Index' (duration: 13.980573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:29.553782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.288557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T20:29:29.553793Z","caller":"traceutil/trace.go:171","msg":"trace[210365077] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"292.408688ms","start":"2026-08-19T20:29:29.261348Z","end":"2026-08-19T20:29:29.553757Z","steps":["trace[210365077] 'process raft request' (duration: 291.898725ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.553843Z","caller":"traceutil/trace.go:171","msg":"trace[1116748062] range","detail":"{range_begin:/registry/masterleases/199.19.213.240; range_end:; response_count:1; response_revision:706; }","duration":"192.413685ms","start":"2026-08-19T20:29:29.361415Z","end":"2026-08-19T20:29:29.553828Z","steps":["trace[1116748062] 'agreement among raft nodes before linearized reading' (duration: 192.237904ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.651977Z","caller":"traceutil/trace.go:171","msg":"trace[1708105092] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"186.760319ms","start":"2026-08-19T20:29:29.465193Z","end":"2026-08-19T20:29:29.651953Z","steps":["trace[1708105092] 'process raft request' (duration: 186.632231ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.686616Z","caller":"traceutil/trace.go:171","msg":"trace[78274824] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"115.387403ms","start":"2026-08-19T20:29:29.571151Z","end":"2026-08-19T20:29:29.686538Z","steps":["trace[78274824] 'process raft request' (duration: 115.098405ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.907427Z","caller":"traceutil/trace.go:171","msg":"trace[1996381040] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"211.64994ms","start":"2026-08-19T20:29:29.695748Z","end":"2026-08-19T20:29:29.907398Z","steps":["trace[1996381040] 'process raft request' (duration: 211.309039ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.908206Z","caller":"traceutil/trace.go:171","msg":"trace[388152085] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:739; }","duration":"198.789549ms","start":"2026-08-19T20:29:29.709392Z","end":"2026-08-19T20:29:29.908182Z","steps":["trace[388152085] 'read index received' (duration: 197.474376ms)","trace[388152085] 'applied index is now lower than readState.Index' (duration: 1.314133ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:29.908428Z","caller":"traceutil/trace.go:171","msg":"trace[1696729551] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"208.127038ms","start":"2026-08-19T20:29:29.700263Z","end":"2026-08-19T20:29:29.90839Z","steps":["trace[1696729551] 'process raft request' (duration: 207.795227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:29.908669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.256708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-19T20:29:29.908762Z","caller":"traceutil/trace.go:171","msg":"trace[1944222476] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:711; }","duration":"199.402777ms","start":"2026-08-19T20:29:29.709346Z","end":"2026-08-19T20:29:29.908749Z","steps":["trace[1944222476] 'agreement among raft nodes before linearized reading' (duration: 199.145711ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:29.939764Z","caller":"traceutil/trace.go:171","msg":"trace[1255257160] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"126.029055ms","start":"2026-08-19T20:29:29.813705Z","end":"2026-08-19T20:29:29.939734Z","steps":["trace[1255257160] 'process raft request' (duration: 125.887626ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:39.368432Z","caller":"traceutil/trace.go:171","msg":"trace[1630931734] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"121.89685ms","start":"2026-08-19T20:29:39.246489Z","end":"2026-08-19T20:29:39.368386Z","steps":["trace[1630931734] 'process raft request' (duration: 113.136195ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:40.41353Z","caller":"traceutil/trace.go:171","msg":"trace[572998177] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"398.750021ms","start":"2026-08-19T20:29:40.01476Z","end":"2026-08-19T20:29:40.41351Z","steps":["trace[572998177] 'process raft request' (duration: 398.627923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:40.413679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:40.014746Z","time spent":"398.860368ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:29:43.754844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653760936801606299,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T20:29:43.853131Z","caller":"traceutil/trace.go:171","msg":"trace[1092749404] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"601.519142ms","start":"2026-08-19T20:29:43.251592Z","end":"2026-08-19T20:29:43.853111Z","steps":["trace[1092749404] 'process raft request' (duration: 601.245764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:43.853355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.23661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fzg7p\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-19T20:29:43.853408Z","caller":"traceutil/trace.go:171","msg":"trace[1849355325] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fzg7p; range_end:; response_count:1; response_revision:816; }","duration":"599.38933ms","start":"2026-08-19T20:29:43.254002Z","end":"2026-08-19T20:29:43.853392Z","steps":["trace[1849355325] 'agreement among raft nodes before linearized reading' (duration: 599.165965ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:43.853092Z","caller":"traceutil/trace.go:171","msg":"trace[1129259336] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"598.965362ms","start":"2026-08-19T20:29:43.25409Z","end":"2026-08-19T20:29:43.853055Z","steps":["trace[1129259336] 'read index received' (duration: 598.758608ms)","trace[1129259336] 'applied index is now lower than readState.Index' (duration: 204.884µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:43.853446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:43.253974Z","time spent":"599.463405ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fzg7p\" "} {"level":"warn","ts":"2026-08-19T20:29:43.853454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:43.251572Z","time spent":"601.710034ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:29:43.853617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.137463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-19T20:29:43.853668Z","caller":"traceutil/trace.go:171","msg":"trace[2068600961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"408.24165ms","start":"2026-08-19T20:29:43.445412Z","end":"2026-08-19T20:29:43.853654Z","steps":["trace[2068600961] 'agreement among raft nodes before linearized reading' (duration: 408.08973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:43.853698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:43.445384Z","time spent":"408.307965ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T20:29:43.85385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.683163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T20:29:43.853949Z","caller":"traceutil/trace.go:171","msg":"trace[1448616128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"389.801541ms","start":"2026-08-19T20:29:43.464136Z","end":"2026-08-19T20:29:43.853937Z","steps":["trace[1448616128] 'agreement among raft nodes before linearized reading' (duration: 389.656371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:43.853977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:43.464124Z","time spent":"389.846333ms","remote":"127.0.0.1:56878","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-08-19T20:29:43.994549Z","caller":"traceutil/trace.go:171","msg":"trace[31450300] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"136.358958ms","start":"2026-08-19T20:29:43.858166Z","end":"2026-08-19T20:29:43.994525Z","steps":["trace[31450300] 'process raft request' (duration: 135.443697ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:44.007548Z","caller":"traceutil/trace.go:171","msg":"trace[1684108480] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"144.961642ms","start":"2026-08-19T20:29:43.86255Z","end":"2026-08-19T20:29:44.007511Z","steps":["trace[1684108480] 'process raft request' (duration: 144.763739ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:44.00763Z","caller":"traceutil/trace.go:171","msg":"trace[774762760] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"149.207796ms","start":"2026-08-19T20:29:43.858381Z","end":"2026-08-19T20:29:44.007588Z","steps":["trace[774762760] 'process raft request' (duration: 148.635577ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:53.395485Z","caller":"traceutil/trace.go:171","msg":"trace[1768015988] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"247.423987ms","start":"2026-08-19T20:29:53.148037Z","end":"2026-08-19T20:29:53.395461Z","steps":["trace[1768015988] 'process raft request' (duration: 247.201261ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:54.210361Z","caller":"traceutil/trace.go:171","msg":"trace[1329149654] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"109.599854ms","start":"2026-08-19T20:29:54.10074Z","end":"2026-08-19T20:29:54.21034Z","steps":["trace[1329149654] 'read index received' (duration: 109.391799ms)","trace[1329149654] 'applied index is now lower than readState.Index' (duration: 207.095µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:29:54.210551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.799187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-19T20:29:54.210583Z","caller":"traceutil/trace.go:171","msg":"trace[1418583047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"109.883204ms","start":"2026-08-19T20:29:54.10069Z","end":"2026-08-19T20:29:54.210573Z","steps":["trace[1418583047] 'agreement among raft nodes before linearized reading' (duration: 109.758245ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:54.210565Z","caller":"traceutil/trace.go:171","msg":"trace[402587820] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"293.845197ms","start":"2026-08-19T20:29:53.916691Z","end":"2026-08-19T20:29:54.210536Z","steps":["trace[402587820] 'process raft request' (duration: 293.520825ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:57.17284Z","caller":"traceutil/trace.go:171","msg":"trace[884322246] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"118.411338ms","start":"2026-08-19T20:29:57.0544Z","end":"2026-08-19T20:29:57.172811Z","steps":["trace[884322246] 'read index received' (duration: 118.280989ms)","trace[884322246] 'applied index is now lower than readState.Index' (duration: 129.009µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:29:57.172963Z","caller":"traceutil/trace.go:171","msg":"trace[1189630358] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"306.20166ms","start":"2026-08-19T20:29:56.866732Z","end":"2026-08-19T20:29:57.172933Z","steps":["trace[1189630358] 'process raft request' (duration: 305.91051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:57.173125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.704548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:29:57.173139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:29:56.866706Z","time spent":"306.34522ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:29:57.173179Z","caller":"traceutil/trace.go:171","msg":"trace[1242115780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"118.783023ms","start":"2026-08-19T20:29:57.054383Z","end":"2026-08-19T20:29:57.173166Z","steps":["trace[1242115780] 'agreement among raft nodes before linearized reading' (duration: 118.546297ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:58.027157Z","caller":"traceutil/trace.go:171","msg":"trace[1235893213] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"283.341441ms","start":"2026-08-19T20:29:57.743788Z","end":"2026-08-19T20:29:58.027129Z","steps":["trace[1235893213] 'process raft request' (duration: 283.228773ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:29:58.04273Z","caller":"traceutil/trace.go:171","msg":"trace[1499928407] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"165.022673ms","start":"2026-08-19T20:29:57.877685Z","end":"2026-08-19T20:29:58.042708Z","steps":["trace[1499928407] 'process raft request' (duration: 164.83693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:58.397004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.011803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T20:29:58.397092Z","caller":"traceutil/trace.go:171","msg":"trace[345884872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"172.177824ms","start":"2026-08-19T20:29:58.224897Z","end":"2026-08-19T20:29:58.397075Z","steps":["trace[345884872] 'range keys from in-memory index tree' (duration: 171.791897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:58.39706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.237328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-19T20:29:58.39726Z","caller":"traceutil/trace.go:171","msg":"trace[1479369673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"146.490745ms","start":"2026-08-19T20:29:58.250748Z","end":"2026-08-19T20:29:58.397239Z","steps":["trace[1479369673] 'range keys from in-memory index tree' (duration: 145.968528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:29:58.397468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.376204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:29:58.397586Z","caller":"traceutil/trace.go:171","msg":"trace[1590665819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"125.528864ms","start":"2026-08-19T20:29:58.272036Z","end":"2026-08-19T20:29:58.397564Z","steps":["trace[1590665819] 'range keys from in-memory index tree' (duration: 125.1845ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:00.616653Z","caller":"traceutil/trace.go:171","msg":"trace[1272972384] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"122.935166ms","start":"2026-08-19T20:30:00.493694Z","end":"2026-08-19T20:30:00.616629Z","steps":["trace[1272972384] 'process raft request' (duration: 60.85027ms)","trace[1272972384] 'compare' (duration: 61.976239ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:08.715918Z","caller":"traceutil/trace.go:171","msg":"trace[1793678618] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"126.394743ms","start":"2026-08-19T20:30:08.589493Z","end":"2026-08-19T20:30:08.715888Z","steps":["trace[1793678618] 'process raft request' (duration: 108.609765ms)","trace[1793678618] 'compare' (duration: 17.473326ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:08.716112Z","caller":"traceutil/trace.go:171","msg":"trace[1761492504] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"114.383506ms","start":"2026-08-19T20:30:08.601718Z","end":"2026-08-19T20:30:08.716102Z","steps":["trace[1761492504] 'process raft request' (duration: 114.040582ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:36.629685Z","caller":"traceutil/trace.go:171","msg":"trace[422339907] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"105.116047ms","start":"2026-08-19T20:30:36.524531Z","end":"2026-08-19T20:30:36.629648Z","steps":["trace[422339907] 'process raft request' (duration: 104.964035ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:37.790367Z","caller":"traceutil/trace.go:171","msg":"trace[783731472] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"152.8445ms","start":"2026-08-19T20:30:37.637497Z","end":"2026-08-19T20:30:37.790341Z","steps":["trace[783731472] 'process raft request' (duration: 152.619683ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:38.936774Z","caller":"traceutil/trace.go:171","msg":"trace[267405316] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"146.422072ms","start":"2026-08-19T20:30:38.790324Z","end":"2026-08-19T20:30:38.936746Z","steps":["trace[267405316] 'read index received' (duration: 142.584594ms)","trace[267405316] 'applied index is now lower than readState.Index' (duration: 3.835778ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:38.936894Z","caller":"traceutil/trace.go:171","msg":"trace[1458925543] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"156.390188ms","start":"2026-08-19T20:30:38.780469Z","end":"2026-08-19T20:30:38.936859Z","steps":["trace[1458925543] 'process raft request' (duration: 152.352196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:38.937039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.703293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-19T20:30:38.937123Z","caller":"traceutil/trace.go:171","msg":"trace[1976823121] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1465; }","duration":"146.831013ms","start":"2026-08-19T20:30:38.790273Z","end":"2026-08-19T20:30:38.937104Z","steps":["trace[1976823121] 'agreement among raft nodes before linearized reading' (duration: 146.569333ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:38.953173Z","caller":"traceutil/trace.go:171","msg":"trace[1757726224] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"119.174851ms","start":"2026-08-19T20:30:38.833981Z","end":"2026-08-19T20:30:38.953156Z","steps":["trace[1757726224] 'process raft request' (duration: 119.060922ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:39.498648Z","caller":"traceutil/trace.go:171","msg":"trace[886834094] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"108.242422ms","start":"2026-08-19T20:30:39.390383Z","end":"2026-08-19T20:30:39.498625Z","steps":["trace[886834094] 'process raft request' (duration: 105.716203ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:39.498899Z","caller":"traceutil/trace.go:171","msg":"trace[277769380] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"106.064848ms","start":"2026-08-19T20:30:39.392814Z","end":"2026-08-19T20:30:39.498879Z","steps":["trace[277769380] 'process raft request' (duration: 105.768446ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:39.760629Z","caller":"traceutil/trace.go:171","msg":"trace[38632433] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"111.700672ms","start":"2026-08-19T20:30:39.648906Z","end":"2026-08-19T20:30:39.760606Z","steps":["trace[38632433] 'process raft request' (duration: 111.572382ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:40.922504Z","caller":"traceutil/trace.go:171","msg":"trace[743089964] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"110.684035ms","start":"2026-08-19T20:30:40.811802Z","end":"2026-08-19T20:30:40.922486Z","steps":["trace[743089964] 'process raft request' (duration: 110.548485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:42.658749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.329866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-pfgl5\" ","response":"range_response_count:1 size:4179"} {"level":"info","ts":"2026-08-19T20:30:42.658825Z","caller":"traceutil/trace.go:171","msg":"trace[1230316205] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-pfgl5; range_end:; response_count:1; response_revision:1492; }","duration":"102.448105ms","start":"2026-08-19T20:30:42.556356Z","end":"2026-08-19T20:30:42.658804Z","steps":["trace[1230316205] 'range keys from in-memory index tree' (duration: 102.143382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:42.658866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.8232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T20:30:42.658914Z","caller":"traceutil/trace.go:171","msg":"trace[447374137] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1492; }","duration":"205.905096ms","start":"2026-08-19T20:30:42.452996Z","end":"2026-08-19T20:30:42.658901Z","steps":["trace[447374137] 'count revisions from in-memory index tree' (duration: 205.738574ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:42.879361Z","caller":"traceutil/trace.go:171","msg":"trace[728213081] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1543; }","duration":"195.022257ms","start":"2026-08-19T20:30:42.684311Z","end":"2026-08-19T20:30:42.879333Z","steps":["trace[728213081] 'read index received' (duration: 194.93531ms)","trace[728213081] 'applied index is now lower than readState.Index' (duration: 85.807µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:42.879477Z","caller":"traceutil/trace.go:171","msg":"trace[2010938865] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"213.293642ms","start":"2026-08-19T20:30:42.666151Z","end":"2026-08-19T20:30:42.879445Z","steps":["trace[2010938865] 'process raft request' (duration: 213.027752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:42.879573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.602008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-19T20:30:42.87956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.230972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:30:42.879619Z","caller":"traceutil/trace.go:171","msg":"trace[1385715797] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1493; }","duration":"138.709136ms","start":"2026-08-19T20:30:42.740898Z","end":"2026-08-19T20:30:42.879608Z","steps":["trace[1385715797] 'agreement among raft nodes before linearized reading' (duration: 138.584516ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:42.87964Z","caller":"traceutil/trace.go:171","msg":"trace[668585271] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1493; }","duration":"195.380574ms","start":"2026-08-19T20:30:42.68424Z","end":"2026-08-19T20:30:42.879621Z","steps":["trace[668585271] 'agreement among raft nodes before linearized reading' (duration: 195.230002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:42.879702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.528507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T20:30:42.87978Z","caller":"traceutil/trace.go:171","msg":"trace[1869916148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1493; }","duration":"122.647326ms","start":"2026-08-19T20:30:42.757113Z","end":"2026-08-19T20:30:42.879761Z","steps":["trace[1869916148] 'agreement among raft nodes before linearized reading' (duration: 122.408378ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:42.99829Z","caller":"traceutil/trace.go:171","msg":"trace[287915268] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"114.820047ms","start":"2026-08-19T20:30:42.88344Z","end":"2026-08-19T20:30:42.99826Z","steps":["trace[287915268] 'process raft request' (duration: 85.309815ms)","trace[287915268] 'compare' (duration: 29.386292ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:45.493551Z","caller":"traceutil/trace.go:171","msg":"trace[515655054] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"394.75432ms","start":"2026-08-19T20:30:45.098677Z","end":"2026-08-19T20:30:45.493431Z","steps":["trace[515655054] 'process raft request' (duration: 386.376957ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:45.493594Z","caller":"traceutil/trace.go:171","msg":"trace[7794311] linearizableReadLoop","detail":"{readStateIndex:1558; appliedIndex:1555; }","duration":"371.05466ms","start":"2026-08-19T20:30:45.122505Z","end":"2026-08-19T20:30:45.49356Z","steps":["trace[7794311] 'read index received' (duration: 10.020478ms)","trace[7794311] 'applied index is now lower than readState.Index' (duration: 361.002029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:30:45.493684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:45.098655Z","time spent":"394.958066ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:30:45.493709Z","caller":"traceutil/trace.go:171","msg":"trace[437317206] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"394.730689ms","start":"2026-08-19T20:30:45.098968Z","end":"2026-08-19T20:30:45.493698Z","steps":["trace[437317206] 'process raft request' (duration: 394.386193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:45.493798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:45.098952Z","time spent":"394.778723ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:30:45.493841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.080896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:30:45.493938Z","caller":"traceutil/trace.go:171","msg":"trace[1678176715] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1506; }","duration":"124.227427ms","start":"2026-08-19T20:30:45.369685Z","end":"2026-08-19T20:30:45.493913Z","steps":["trace[1678176715] 'agreement among raft nodes before linearized reading' (duration: 124.082146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:45.494244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.747312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-19T20:30:45.494282Z","caller":"traceutil/trace.go:171","msg":"trace[2110041087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1506; }","duration":"371.816466ms","start":"2026-08-19T20:30:45.122455Z","end":"2026-08-19T20:30:45.494272Z","steps":["trace[2110041087] 'agreement among raft nodes before linearized reading' (duration: 371.681736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:45.494308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:30:45.122443Z","time spent":"371.85724ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-19T20:30:48.196351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.624803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:30:48.196423Z","caller":"traceutil/trace.go:171","msg":"trace[2059457598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1542; }","duration":"142.741692ms","start":"2026-08-19T20:30:48.053667Z","end":"2026-08-19T20:30:48.196409Z","steps":["trace[2059457598] 'range keys from in-memory index tree' (duration: 142.545317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:30:48.517071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.0909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:30:48.517517Z","caller":"traceutil/trace.go:171","msg":"trace[838784434] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"229.725597ms","start":"2026-08-19T20:30:48.28776Z","end":"2026-08-19T20:30:48.517486Z","steps":["trace[838784434] 'process raft request' (duration: 29.134138ms)","trace[838784434] 'compare' (duration: 199.980172ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:48.695582Z","caller":"traceutil/trace.go:171","msg":"trace[394492911] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"171.403205ms","start":"2026-08-19T20:30:48.524149Z","end":"2026-08-19T20:30:48.695552Z","steps":["trace[394492911] 'process raft request' (duration: 171.245583ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:49.07337Z","caller":"traceutil/trace.go:171","msg":"trace[1762357306] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"293.168712ms","start":"2026-08-19T20:30:48.780173Z","end":"2026-08-19T20:30:49.073342Z","steps":["trace[1762357306] 'process raft request' (duration: 263.054267ms)","trace[1762357306] 'compare' (duration: 29.929771ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:07.840206Z","caller":"traceutil/trace.go:171","msg":"trace[477448772] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"142.347452ms","start":"2026-08-19T20:31:07.697804Z","end":"2026-08-19T20:31:07.840152Z","steps":["trace[477448772] 'process raft request' (duration: 123.39687ms)","trace[477448772] 'compare' (duration: 18.738095ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:07.840336Z","caller":"traceutil/trace.go:171","msg":"trace[2037294167] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1884; }","duration":"106.503587ms","start":"2026-08-19T20:31:07.733806Z","end":"2026-08-19T20:31:07.84031Z","steps":["trace[2037294167] 'read index received' (duration: 87.408474ms)","trace[2037294167] 'applied index is now lower than readState.Index' (duration: 19.094643ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:07.840474Z","caller":"traceutil/trace.go:171","msg":"trace[851237392] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"132.065859ms","start":"2026-08-19T20:31:07.708394Z","end":"2026-08-19T20:31:07.84046Z","steps":["trace[851237392] 'process raft request' (duration: 131.661857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:07.840529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.405833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118113"} {"level":"info","ts":"2026-08-19T20:31:07.840556Z","caller":"traceutil/trace.go:171","msg":"trace[368836177] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1825; }","duration":"129.473078ms","start":"2026-08-19T20:31:07.711072Z","end":"2026-08-19T20:31:07.840545Z","steps":["trace[368836177] 'agreement among raft nodes before linearized reading' (duration: 129.309085ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:07.876606Z","caller":"traceutil/trace.go:171","msg":"trace[1178920212] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"138.125005ms","start":"2026-08-19T20:31:07.738239Z","end":"2026-08-19T20:31:07.876364Z","steps":["trace[1178920212] 'process raft request' (duration: 137.984994ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:08.145913Z","caller":"traceutil/trace.go:171","msg":"trace[1598230166] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"106.856339ms","start":"2026-08-19T20:31:08.039026Z","end":"2026-08-19T20:31:08.145883Z","steps":["trace[1598230166] 'process raft request' (duration: 106.579768ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:09.950869Z","caller":"traceutil/trace.go:171","msg":"trace[1772026306] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"101.660354ms","start":"2026-08-19T20:31:09.84918Z","end":"2026-08-19T20:31:09.95084Z","steps":["trace[1772026306] 'process raft request' (duration: 101.448918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:11.090984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.893633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be0ae5bf-90f6-4a2f-abf6-1a30da9d88ba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-19T20:31:11.091053Z","caller":"traceutil/trace.go:171","msg":"trace[214873182] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be0ae5bf-90f6-4a2f-abf6-1a30da9d88ba; range_end:; response_count:1; response_revision:1905; }","duration":"389.004452ms","start":"2026-08-19T20:31:10.702035Z","end":"2026-08-19T20:31:11.091039Z","steps":["trace[214873182] 'range keys from in-memory index tree' (duration: 388.723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:11.09108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:10.702009Z","time spent":"389.065347ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be0ae5bf-90f6-4a2f-abf6-1a30da9d88ba\" "} {"level":"warn","ts":"2026-08-19T20:31:11.091155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.570111ms","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":"warn","ts":"2026-08-19T20:31:11.091187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.218572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-19T20:31:11.091237Z","caller":"traceutil/trace.go:171","msg":"trace[1031180395] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1905; }","duration":"152.667539ms","start":"2026-08-19T20:31:10.938532Z","end":"2026-08-19T20:31:11.0912Z","steps":["trace[1031180395] 'range keys from in-memory index tree' (duration: 152.444502ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:11.091317Z","caller":"traceutil/trace.go:171","msg":"trace[1364209554] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1905; }","duration":"261.363333ms","start":"2026-08-19T20:31:10.829906Z","end":"2026-08-19T20:31:11.091269Z","steps":["trace[1364209554] 'range keys from in-memory index tree' (duration: 261.003246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:11.091468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.682344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-19T20:31:11.092602Z","caller":"traceutil/trace.go:171","msg":"trace[1265240618] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1905; }","duration":"341.90402ms","start":"2026-08-19T20:31:10.749718Z","end":"2026-08-19T20:31:11.091622Z","steps":["trace[1265240618] 'range keys from in-memory index tree' (duration: 341.328856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:11.092775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:10.749696Z","time spent":"343.05735ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-19T20:31:11.55468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653760936801608460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:31:12.055453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653760936801608460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-19T20:31:12.093495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058556093s","expected-duration":"1s"} {"level":"info","ts":"2026-08-19T20:31:12.093931Z","caller":"traceutil/trace.go:171","msg":"trace[1692850653] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"1.059090114s","start":"2026-08-19T20:31:11.034816Z","end":"2026-08-19T20:31:12.093906Z","steps":["trace[1692850653] 'process raft request' (duration: 1.05890822s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.094088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.034745Z","time spent":"1.059258367s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:12.661207Z","caller":"traceutil/trace.go:171","msg":"trace[2095920170] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1968; }","duration":"1.607055046s","start":"2026-08-19T20:31:11.05413Z","end":"2026-08-19T20:31:12.661185Z","steps":["trace[2095920170] 'read index received' (duration: 1.039596326s)","trace[2095920170] 'applied index is now lower than readState.Index' (duration: 567.45771ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:12.661509Z","caller":"traceutil/trace.go:171","msg":"trace[1322550613] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"1.565322147s","start":"2026-08-19T20:31:11.096173Z","end":"2026-08-19T20:31:12.661495Z","steps":["trace[1322550613] 'process raft request' (duration: 1.564736152s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.661647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.09615Z","time spent":"1.565425355s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:12.662183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.188337ms","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-08-19T20:31:12.662223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608095898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:31:12.662246Z","caller":"traceutil/trace.go:171","msg":"trace[588927311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1909; }","duration":"707.290325ms","start":"2026-08-19T20:31:11.95494Z","end":"2026-08-19T20:31:12.66223Z","steps":["trace[588927311] 'agreement among raft nodes before linearized reading' (duration: 707.127733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.662038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565968717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-19T20:31:12.662309Z","caller":"traceutil/trace.go:171","msg":"trace[1534043873] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"1.563871596s","start":"2026-08-19T20:31:11.098429Z","end":"2026-08-19T20:31:12.6623Z","steps":["trace[1534043873] 'process raft request' (duration: 1.562650121s)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:12.662329Z","caller":"traceutil/trace.go:171","msg":"trace[1731941579] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1909; }","duration":"1.566288303s","start":"2026-08-19T20:31:11.096028Z","end":"2026-08-19T20:31:12.662316Z","steps":["trace[1731941579] 'agreement among raft nodes before linearized reading' (duration: 1.565911664s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.662339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.951701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:31:12.662369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.096009Z","time spent":"1.566349367s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-19T20:31:12.662404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.098417Z","time spent":"1.563936071s","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:12.66247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.426416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-19T20:31:12.662498Z","caller":"traceutil/trace.go:171","msg":"trace[998526701] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1909; }","duration":"147.478789ms","start":"2026-08-19T20:31:12.515011Z","end":"2026-08-19T20:31:12.66249Z","steps":["trace[998526701] 'agreement among raft nodes before linearized reading' (duration: 147.378241ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:12.662522Z","caller":"traceutil/trace.go:171","msg":"trace[1558221532] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"1.447568446s","start":"2026-08-19T20:31:11.21494Z","end":"2026-08-19T20:31:12.662508Z","steps":["trace[1558221532] 'process raft request' (duration: 1.446188339s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.662587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.214922Z","time spent":"1.447628391s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:12.662806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.901379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-19T20:31:12.662838Z","caller":"traceutil/trace.go:171","msg":"trace[1016103939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1909; }","duration":"899.985966ms","start":"2026-08-19T20:31:11.762844Z","end":"2026-08-19T20:31:12.66283Z","steps":["trace[1016103939] 'agreement among raft nodes before linearized reading' (duration: 899.745567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.662859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.762822Z","time spent":"900.031068ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T20:31:12.662766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.387226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-19T20:31:12.662901Z","caller":"traceutil/trace.go:171","msg":"trace[423905175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1909; }","duration":"679.56468ms","start":"2026-08-19T20:31:11.983325Z","end":"2026-08-19T20:31:12.662889Z","steps":["trace[423905175] 'agreement among raft nodes before linearized reading' (duration: 679.356554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.662949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.983303Z","time spent":"679.627315ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T20:31:12.663023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234804593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-19T20:31:12.663063Z","caller":"traceutil/trace.go:171","msg":"trace[867281120] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1909; }","duration":"1.23489487s","start":"2026-08-19T20:31:11.428157Z","end":"2026-08-19T20:31:12.663052Z","steps":["trace[867281120] 'agreement among raft nodes before linearized reading' (duration: 1.23476268s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.663095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.428137Z","time spent":"1.234950945s","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-19T20:31:12.663155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323402108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-19T20:31:12.66316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148451341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-19T20:31:12.663206Z","caller":"traceutil/trace.go:171","msg":"trace[727922146] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1909; }","duration":"1.148534968s","start":"2026-08-19T20:31:11.51466Z","end":"2026-08-19T20:31:12.663195Z","steps":["trace[727922146] 'agreement among raft nodes before linearized reading' (duration: 1.148402858s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.663209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19109039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be0ae5bf-90f6-4a2f-abf6-1a30da9d88ba\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-08-19T20:31:12.663272Z","caller":"traceutil/trace.go:171","msg":"trace[1088617064] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be0ae5bf-90f6-4a2f-abf6-1a30da9d88ba; range_end:; response_count:1; response_revision:1909; }","duration":"1.191199159s","start":"2026-08-19T20:31:11.472061Z","end":"2026-08-19T20:31:12.66326Z","steps":["trace[1088617064] 'agreement among raft nodes before linearized reading' (duration: 1.19107877s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.663301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.472041Z","time spent":"1.191253523s","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be0ae5bf-90f6-4a2f-abf6-1a30da9d88ba\" "} {"level":"warn","ts":"2026-08-19T20:31:12.66332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.547639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T20:31:12.663348Z","caller":"traceutil/trace.go:171","msg":"trace[887116344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1909; }","duration":"942.615564ms","start":"2026-08-19T20:31:11.720724Z","end":"2026-08-19T20:31:12.66334Z","steps":["trace[887116344] 'agreement among raft nodes before linearized reading' (duration: 942.534888ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:12.662371Z","caller":"traceutil/trace.go:171","msg":"trace[1579681663] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1909; }","duration":"115.06295ms","start":"2026-08-19T20:31:12.547298Z","end":"2026-08-19T20:31:12.662361Z","steps":["trace[1579681663] 'agreement among raft nodes before linearized reading' (duration: 115.014776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.66345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.720701Z","time spent":"942.742264ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T20:31:12.663237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.514639Z","time spent":"1.148590392s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-19T20:31:12.663177Z","caller":"traceutil/trace.go:171","msg":"trace[1460838070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1909; }","duration":"1.323462063s","start":"2026-08-19T20:31:11.339709Z","end":"2026-08-19T20:31:12.663171Z","steps":["trace[1460838070] 'agreement among raft nodes before linearized reading' (duration: 1.323397648s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.663534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.339691Z","time spent":"1.323837082s","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T20:31:12.662293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.954921Z","time spent":"707.363361ms","remote":"127.0.0.1:56878","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-08-19T20:31:12.662274Z","caller":"traceutil/trace.go:171","msg":"trace[29633651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1909; }","duration":"1.608177333s","start":"2026-08-19T20:31:11.054082Z","end":"2026-08-19T20:31:12.662259Z","steps":["trace[29633651] 'agreement among raft nodes before linearized reading' (duration: 1.608087046s)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:12.663601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:11.054068Z","time spent":"1.609527378s","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:31:13.151381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.533117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:31:13.151623Z","caller":"traceutil/trace.go:171","msg":"trace[139589678] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"480.270978ms","start":"2026-08-19T20:31:12.671336Z","end":"2026-08-19T20:31:13.151606Z","steps":["trace[139589678] 'process raft request' (duration: 480.142688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.1517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.671328Z","time spent":"480.339193ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:13.151794Z","caller":"traceutil/trace.go:171","msg":"trace[1694700482] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"480.422809ms","start":"2026-08-19T20:31:12.671333Z","end":"2026-08-19T20:31:13.151756Z","steps":["trace[1694700482] 'process raft request' (duration: 108.431806ms)","trace[1694700482] 'compare' (duration: 370.863446ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:31:13.151989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.671321Z","time spent":"480.572641ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:13.203525Z","caller":"traceutil/trace.go:171","msg":"trace[553017214] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"530.280252ms","start":"2026-08-19T20:31:12.673225Z","end":"2026-08-19T20:31:13.203505Z","steps":["trace[553017214] 'process raft request' (duration: 530.199016ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:13.203542Z","caller":"traceutil/trace.go:171","msg":"trace[1958786869] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"530.309355ms","start":"2026-08-19T20:31:12.673212Z","end":"2026-08-19T20:31:13.203522Z","steps":["trace[1958786869] 'process raft request' (duration: 530.075966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.203655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.673214Z","time spent":"530.384371ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:13.203706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.673203Z","time spent":"530.447196ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:13.203758Z","caller":"traceutil/trace.go:171","msg":"trace[1254676231] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1972; }","duration":"524.481284ms","start":"2026-08-19T20:31:12.679255Z","end":"2026-08-19T20:31:13.203737Z","steps":["trace[1254676231] 'read index received' (duration: 100.449469ms)","trace[1254676231] 'applied index is now lower than readState.Index' (duration: 424.030945ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:13.203846Z","caller":"traceutil/trace.go:171","msg":"trace[756305861] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"530.602847ms","start":"2026-08-19T20:31:12.673225Z","end":"2026-08-19T20:31:13.203828Z","steps":["trace[756305861] 'process raft request' (duration: 530.24632ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:13.203882Z","caller":"traceutil/trace.go:171","msg":"trace[43168197] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"530.498359ms","start":"2026-08-19T20:31:12.67337Z","end":"2026-08-19T20:31:13.203868Z","steps":["trace[43168197] 'process raft request' (duration: 530.289853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.203935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.673202Z","time spent":"530.686174ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:13.203967Z","caller":"traceutil/trace.go:171","msg":"trace[1808570886] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"530.690915ms","start":"2026-08-19T20:31:12.673262Z","end":"2026-08-19T20:31:13.203953Z","steps":["trace[1808570886] 'process raft request' (duration: 530.300975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.203994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.673359Z","time spent":"530.557044ms","remote":"127.0.0.1:56724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:13.204027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.673251Z","time spent":"530.743678ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:13.204164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.910157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:31:13.20421Z","caller":"traceutil/trace.go:171","msg":"trace[1540128277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"524.969172ms","start":"2026-08-19T20:31:12.679229Z","end":"2026-08-19T20:31:13.204199Z","steps":["trace[1540128277] 'agreement among raft nodes before linearized reading' (duration: 524.901207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.204216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.432303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:31:13.204194Z","caller":"traceutil/trace.go:171","msg":"trace[1330620079] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"528.802598ms","start":"2026-08-19T20:31:12.675358Z","end":"2026-08-19T20:31:13.204161Z","steps":["trace[1330620079] 'process raft request' (duration: 528.342872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.204239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.67922Z","time spent":"525.012695ms","remote":"127.0.0.1:56578","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-08-19T20:31:13.204261Z","caller":"traceutil/trace.go:171","msg":"trace[427790035] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1917; }","duration":"402.51643ms","start":"2026-08-19T20:31:12.801734Z","end":"2026-08-19T20:31:13.204251Z","steps":["trace[427790035] 'agreement among raft nodes before linearized reading' (duration: 402.445815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:13.2043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.801713Z","time spent":"402.579054ms","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-19T20:31:13.204366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:12.675348Z","time spent":"528.929788ms","remote":"127.0.0.1:56724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:13.351424Z","caller":"traceutil/trace.go:171","msg":"trace[602920956] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"135.313736ms","start":"2026-08-19T20:31:13.216078Z","end":"2026-08-19T20:31:13.351392Z","steps":["trace[602920956] 'process raft request' (duration: 42.056419ms)","trace[602920956] 'compare' (duration: 92.814123ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:13.351447Z","caller":"traceutil/trace.go:171","msg":"trace[1441749982] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"133.662218ms","start":"2026-08-19T20:31:13.217752Z","end":"2026-08-19T20:31:13.351414Z","steps":["trace[1441749982] 'process raft request' (duration: 133.343053ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:19.285116Z","caller":"traceutil/trace.go:171","msg":"trace[1725692904] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"104.659603ms","start":"2026-08-19T20:31:19.180417Z","end":"2026-08-19T20:31:19.285077Z","steps":["trace[1725692904] 'process raft request' (duration: 104.432945ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:19.586951Z","caller":"traceutil/trace.go:171","msg":"trace[1130133217] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"296.546352ms","start":"2026-08-19T20:31:19.290384Z","end":"2026-08-19T20:31:19.586931Z","steps":["trace[1130133217] 'process raft request' (duration: 296.39487ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:19.586982Z","caller":"traceutil/trace.go:171","msg":"trace[509605282] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"296.794931ms","start":"2026-08-19T20:31:19.290113Z","end":"2026-08-19T20:31:19.586908Z","steps":["trace[509605282] 'process raft request' (duration: 284.913397ms)","trace[509605282] 'compare' (duration: 11.488614ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:19.586995Z","caller":"traceutil/trace.go:171","msg":"trace[640317413] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2056; }","duration":"219.485366ms","start":"2026-08-19T20:31:19.367488Z","end":"2026-08-19T20:31:19.586973Z","steps":["trace[640317413] 'read index received' (duration: 207.57252ms)","trace[640317413] 'applied index is now lower than readState.Index' (duration: 11.911676ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:31:19.587227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.725615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-19T20:31:19.587274Z","caller":"traceutil/trace.go:171","msg":"trace[542592889] range","detail":"{range_begin:/registry/masterleases/199.19.213.240; range_end:; response_count:1; response_revision:1993; }","duration":"219.818732ms","start":"2026-08-19T20:31:19.367445Z","end":"2026-08-19T20:31:19.587264Z","steps":["trace[542592889] 'agreement among raft nodes before linearized reading' (duration: 219.595555ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:19.587465Z","caller":"traceutil/trace.go:171","msg":"trace[1939993268] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"296.178883ms","start":"2026-08-19T20:31:19.291275Z","end":"2026-08-19T20:31:19.587454Z","steps":["trace[1939993268] 'process raft request' (duration: 295.616659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:19.587856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.174651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-19T20:31:19.587927Z","caller":"traceutil/trace.go:171","msg":"trace[432418579] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1993; }","duration":"192.294971ms","start":"2026-08-19T20:31:19.395613Z","end":"2026-08-19T20:31:19.587908Z","steps":["trace[432418579] 'agreement among raft nodes before linearized reading' (duration: 191.547063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:19.587921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.067903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-19T20:31:19.588049Z","caller":"traceutil/trace.go:171","msg":"trace[838024740] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1993; }","duration":"192.252048ms","start":"2026-08-19T20:31:19.395779Z","end":"2026-08-19T20:31:19.588031Z","steps":["trace[838024740] 'agreement among raft nodes before linearized reading' (duration: 191.428814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:19.829633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.321814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:31:19.829784Z","caller":"traceutil/trace.go:171","msg":"trace[1993516613] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"164.035482ms","start":"2026-08-19T20:31:19.66573Z","end":"2026-08-19T20:31:19.829766Z","steps":["trace[1993516613] 'process raft request' (duration: 58.476799ms)","trace[1993516613] 'compare' (duration: 105.220096ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:19.829976Z","caller":"traceutil/trace.go:171","msg":"trace[1865540052] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2064; }","duration":"108.915444ms","start":"2026-08-19T20:31:19.721032Z","end":"2026-08-19T20:31:19.829947Z","steps":["trace[1865540052] 'read index received' (duration: 3.185568ms)","trace[1865540052] 'applied index is now lower than readState.Index' (duration: 105.728756ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:19.830008Z","caller":"traceutil/trace.go:171","msg":"trace[1280193723] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"163.3627ms","start":"2026-08-19T20:31:19.666619Z","end":"2026-08-19T20:31:19.829982Z","steps":["trace[1280193723] 'process raft request' (duration: 163.11172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:19.830313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.174584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-19T20:31:19.830348Z","caller":"traceutil/trace.go:171","msg":"trace[234095210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1999; }","duration":"109.338957ms","start":"2026-08-19T20:31:19.720997Z","end":"2026-08-19T20:31:19.830336Z","steps":["trace[234095210] 'agreement among raft nodes before linearized reading' (duration: 109.079047ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:23.014662Z","caller":"traceutil/trace.go:171","msg":"trace[1608303416] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2084; }","duration":"163.627775ms","start":"2026-08-19T20:31:22.85101Z","end":"2026-08-19T20:31:23.014638Z","steps":["trace[1608303416] 'read index received' (duration: 163.464212ms)","trace[1608303416] 'applied index is now lower than readState.Index' (duration: 162.333µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:23.014808Z","caller":"traceutil/trace.go:171","msg":"trace[25442056] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"319.582851ms","start":"2026-08-19T20:31:22.695191Z","end":"2026-08-19T20:31:23.014774Z","steps":["trace[25442056] 'process raft request' (duration: 319.301919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:23.014874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.845922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-19T20:31:23.014916Z","caller":"traceutil/trace.go:171","msg":"trace[806174565] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2018; }","duration":"163.939179ms","start":"2026-08-19T20:31:22.850962Z","end":"2026-08-19T20:31:23.014901Z","steps":["trace[806174565] 'agreement among raft nodes before linearized reading' (duration: 163.782687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:23.014963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:22.695167Z","time spent":"319.714461ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:46.236319Z","caller":"traceutil/trace.go:171","msg":"trace[261250283] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"150.327945ms","start":"2026-08-19T20:31:46.085958Z","end":"2026-08-19T20:31:46.236286Z","steps":["trace[261250283] 'process raft request' (duration: 150.115616ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:46.24248Z","caller":"traceutil/trace.go:171","msg":"trace[434816968] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"156.176833ms","start":"2026-08-19T20:31:46.086281Z","end":"2026-08-19T20:31:46.242457Z","steps":["trace[434816968] 'process raft request' (duration: 155.926476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.166791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.17506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:31:47.166875Z","caller":"traceutil/trace.go:171","msg":"trace[381275177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2194; }","duration":"112.303874ms","start":"2026-08-19T20:31:47.054553Z","end":"2026-08-19T20:31:47.166857Z","steps":["trace[381275177] 'range keys from in-memory index tree' (duration: 112.021348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.668704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":653760936801609166,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-19T20:31:47.759893Z","caller":"traceutil/trace.go:171","msg":"trace[1020805923] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"591.670342ms","start":"2026-08-19T20:31:47.168201Z","end":"2026-08-19T20:31:47.759871Z","steps":["trace[1020805923] 'read index received' (duration: 591.483762ms)","trace[1020805923] 'applied index is now lower than readState.Index' (duration: 185.83µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:47.760004Z","caller":"traceutil/trace.go:171","msg":"trace[802550132] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"670.696737ms","start":"2026-08-19T20:31:47.089299Z","end":"2026-08-19T20:31:47.759996Z","steps":["trace[802550132] 'process raft request' (duration: 670.455559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.760102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.089268Z","time spent":"670.764874ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:47.760212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.010325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:31:47.760232Z","caller":"traceutil/trace.go:171","msg":"trace[1522978970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"592.086531ms","start":"2026-08-19T20:31:47.168139Z","end":"2026-08-19T20:31:47.760226Z","steps":["trace[1522978970] 'agreement among raft nodes before linearized reading' (duration: 592.050673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.760251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.168121Z","time spent":"592.126209ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-19T20:31:47.760486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.671501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-19T20:31:47.760559Z","caller":"traceutil/trace.go:171","msg":"trace[902508309] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2195; }","duration":"510.821242ms","start":"2026-08-19T20:31:47.249717Z","end":"2026-08-19T20:31:47.760538Z","steps":["trace[902508309] 'agreement among raft nodes before linearized reading' (duration: 510.647982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.760591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.249698Z","time spent":"510.884199ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-19T20:31:47.760795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.468684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-19T20:31:47.760819Z","caller":"traceutil/trace.go:171","msg":"trace[74805505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2195; }","duration":"233.54548ms","start":"2026-08-19T20:31:47.527266Z","end":"2026-08-19T20:31:47.760812Z","steps":["trace[74805505] 'agreement among raft nodes before linearized reading' (duration: 233.457075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.760925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.140353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-19T20:31:47.760952Z","caller":"traceutil/trace.go:171","msg":"trace[198136068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2195; }","duration":"353.228748ms","start":"2026-08-19T20:31:47.407716Z","end":"2026-08-19T20:31:47.760945Z","steps":["trace[198136068] 'agreement among raft nodes before linearized reading' (duration: 353.170361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.760976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.407695Z","time spent":"353.270926ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T20:31:47.761189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.010536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-19T20:31:47.761228Z","caller":"traceutil/trace.go:171","msg":"trace[1959306874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2195; }","duration":"337.096852ms","start":"2026-08-19T20:31:47.424122Z","end":"2026-08-19T20:31:47.761219Z","steps":["trace[1959306874] 'agreement among raft nodes before linearized reading' (duration: 337.010386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:47.761256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.424099Z","time spent":"337.148719ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-19T20:31:48.394029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.979445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:31:48.394156Z","caller":"traceutil/trace.go:171","msg":"trace[840261725] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"629.011577ms","start":"2026-08-19T20:31:47.765124Z","end":"2026-08-19T20:31:48.394136Z","steps":["trace[840261725] 'process raft request' (duration: 114.817503ms)","trace[840261725] 'compare' (duration: 513.727178ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:31:48.394218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.765109Z","time spent":"629.079313ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:48.492458Z","caller":"traceutil/trace.go:171","msg":"trace[917487248] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"726.185963ms","start":"2026-08-19T20:31:47.766251Z","end":"2026-08-19T20:31:48.492437Z","steps":["trace[917487248] 'process raft request' (duration: 726.128126ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:48.492502Z","caller":"traceutil/trace.go:171","msg":"trace[1269969725] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"727.298685ms","start":"2026-08-19T20:31:47.765185Z","end":"2026-08-19T20:31:48.492483Z","steps":["trace[1269969725] 'process raft request' (duration: 727.025449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:48.492579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.76624Z","time spent":"726.275928ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:48.492695Z","caller":"traceutil/trace.go:171","msg":"trace[981220897] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"727.323253ms","start":"2026-08-19T20:31:47.765367Z","end":"2026-08-19T20:31:48.49269Z","steps":["trace[981220897] 'process raft request' (duration: 726.974141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:48.492708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.765163Z","time spent":"727.409949ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:48.492729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.76536Z","time spent":"727.352082ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:48.492848Z","caller":"traceutil/trace.go:171","msg":"trace[704827406] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"726.494117ms","start":"2026-08-19T20:31:47.766347Z","end":"2026-08-19T20:31:48.492841Z","steps":["trace[704827406] 'process raft request' (duration: 726.0599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:48.492885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.766342Z","time spent":"726.525595ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:48.493017Z","caller":"traceutil/trace.go:171","msg":"trace[1825211747] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2274; }","duration":"233.631191ms","start":"2026-08-19T20:31:48.259378Z","end":"2026-08-19T20:31:48.493009Z","steps":["trace[1825211747] 'read index received' (duration: 232.887749ms)","trace[1825211747] 'applied index is now lower than readState.Index' (duration: 743.052µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:48.493063Z","caller":"traceutil/trace.go:171","msg":"trace[35931297] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"726.370383ms","start":"2026-08-19T20:31:47.766688Z","end":"2026-08-19T20:31:48.493058Z","steps":["trace[35931297] 'process raft request' (duration: 726.127216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:48.493102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.76668Z","time spent":"726.401622ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:31:48.493255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.877139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-19T20:31:48.493286Z","caller":"traceutil/trace.go:171","msg":"trace[207942564] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2202; }","duration":"233.952985ms","start":"2026-08-19T20:31:48.259325Z","end":"2026-08-19T20:31:48.493278Z","steps":["trace[207942564] 'agreement among raft nodes before linearized reading' (duration: 233.841861ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:48.493418Z","caller":"traceutil/trace.go:171","msg":"trace[1616641776] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"715.905851ms","start":"2026-08-19T20:31:47.777506Z","end":"2026-08-19T20:31:48.493412Z","steps":["trace[1616641776] 'process raft request' (duration: 715.468504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:48.493448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:47.777486Z","time spent":"715.948329ms","remote":"127.0.0.1:56688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T20:31:48.493586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.091298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-19T20:31:48.493662Z","caller":"traceutil/trace.go:171","msg":"trace[188067596] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2202; }","duration":"234.190942ms","start":"2026-08-19T20:31:48.259453Z","end":"2026-08-19T20:31:48.493644Z","steps":["trace[188067596] 'agreement among raft nodes before linearized reading' (duration: 233.878098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:48.50273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.764331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"info","ts":"2026-08-19T20:31:48.502797Z","caller":"traceutil/trace.go:171","msg":"trace[1496394529] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2202; }","duration":"214.871896ms","start":"2026-08-19T20:31:48.287907Z","end":"2026-08-19T20:31:48.502779Z","steps":["trace[1496394529] 'agreement among raft nodes before linearized reading' (duration: 205.963454ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:33:15.538053Z","caller":"traceutil/trace.go:171","msg":"trace[1453642208] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"114.047776ms","start":"2026-08-19T20:33:15.423978Z","end":"2026-08-19T20:33:15.538026Z","steps":["trace[1453642208] 'process raft request' (duration: 113.879595ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:33:15.608421Z","caller":"traceutil/trace.go:171","msg":"trace[1484766706] transaction","detail":"{read_only:false; response_revision:2898; number_of_response:1; }","duration":"183.74144ms","start":"2026-08-19T20:33:15.424654Z","end":"2026-08-19T20:33:15.608396Z","steps":["trace[1484766706] 'process raft request' (duration: 183.236197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:33:16.098577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.220731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:33:16.099422Z","caller":"traceutil/trace.go:171","msg":"trace[1693085621] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"486.861626ms","start":"2026-08-19T20:33:15.612527Z","end":"2026-08-19T20:33:16.099388Z","steps":["trace[1693085621] 'process raft request' (duration: 22.740749ms)","trace[1693085621] 'compare' (duration: 462.966259ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:33:16.099594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:33:15.612505Z","time spent":"487.000997ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:33:16.100064Z","caller":"traceutil/trace.go:171","msg":"trace[568848119] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"484.967394ms","start":"2026-08-19T20:33:15.615084Z","end":"2026-08-19T20:33:16.100052Z","steps":["trace[568848119] 'process raft request' (duration: 484.247449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:33:16.100138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:33:15.61506Z","time spent":"485.041874ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:33:16.558391Z","caller":"traceutil/trace.go:171","msg":"trace[387523675] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"206.007591ms","start":"2026-08-19T20:33:16.35236Z","end":"2026-08-19T20:33:16.558367Z","steps":["trace[387523675] 'process raft request' (duration: 205.84408ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:30.073586Z","caller":"traceutil/trace.go:171","msg":"trace[1754403037] linearizableReadLoop","detail":"{readStateIndex:4580; appliedIndex:4579; }","duration":"123.923543ms","start":"2026-08-19T20:35:29.949642Z","end":"2026-08-19T20:35:30.073566Z","steps":["trace[1754403037] 'read index received' (duration: 111.811556ms)","trace[1754403037] 'applied index is now lower than readState.Index' (duration: 12.110737ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:35:30.073644Z","caller":"traceutil/trace.go:171","msg":"trace[248917296] transaction","detail":"{read_only:false; response_revision:4386; number_of_response:1; }","duration":"124.142423ms","start":"2026-08-19T20:35:29.949455Z","end":"2026-08-19T20:35:30.073597Z","steps":["trace[248917296] 'process raft request' (duration: 111.844287ms)","trace[248917296] 'compare' (duration: 11.973061ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:35:30.073754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.08797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:35:30.074254Z","caller":"traceutil/trace.go:171","msg":"trace[94946213] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4386; }","duration":"124.624485ms","start":"2026-08-19T20:35:29.949615Z","end":"2026-08-19T20:35:30.07424Z","steps":["trace[94946213] 'agreement among raft nodes before linearized reading' (duration: 124.033818ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:30.077792Z","caller":"traceutil/trace.go:171","msg":"trace[777850333] transaction","detail":"{read_only:false; response_revision:4387; number_of_response:1; }","duration":"124.149484ms","start":"2026-08-19T20:35:29.95362Z","end":"2026-08-19T20:35:30.077769Z","steps":["trace[777850333] 'process raft request' (duration: 123.995527ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:30.077822Z","caller":"traceutil/trace.go:171","msg":"trace[1203746098] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"117.235492ms","start":"2026-08-19T20:35:29.960552Z","end":"2026-08-19T20:35:30.077787Z","steps":["trace[1203746098] 'process raft request' (duration: 117.155188ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:30.078052Z","caller":"traceutil/trace.go:171","msg":"trace[13538806] transaction","detail":"{read_only:false; response_revision:4389; number_of_response:1; }","duration":"109.922683ms","start":"2026-08-19T20:35:29.968118Z","end":"2026-08-19T20:35:30.078041Z","steps":["trace[13538806] 'process raft request' (duration: 109.62866ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:36.684262Z","caller":"traceutil/trace.go:171","msg":"trace[1213821015] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"161.01383ms","start":"2026-08-19T20:35:36.523199Z","end":"2026-08-19T20:35:36.684213Z","steps":["trace[1213821015] 'process raft request' (duration: 82.679925ms)","trace[1213821015] 'compare' (duration: 77.990759ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:35:36.684429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.211662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:35:36.68447Z","caller":"traceutil/trace.go:171","msg":"trace[1658426189] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"159.103801ms","start":"2026-08-19T20:35:36.52534Z","end":"2026-08-19T20:35:36.684444Z","steps":["trace[1658426189] 'process raft request' (duration: 158.753835ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:36.684534Z","caller":"traceutil/trace.go:171","msg":"trace[388069215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4535; }","duration":"116.388181ms","start":"2026-08-19T20:35:36.568129Z","end":"2026-08-19T20:35:36.684517Z","steps":["trace[388069215] 'agreement among raft nodes before linearized reading' (duration: 116.207013ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:36.684243Z","caller":"traceutil/trace.go:171","msg":"trace[273626020] linearizableReadLoop","detail":"{readStateIndex:4734; appliedIndex:4732; }","duration":"116.040204ms","start":"2026-08-19T20:35:36.568165Z","end":"2026-08-19T20:35:36.684205Z","steps":["trace[273626020] 'read index received' (duration: 37.760783ms)","trace[273626020] 'applied index is now lower than readState.Index' (duration: 78.277891ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:35:42.042325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.963919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:35:42.042526Z","caller":"traceutil/trace.go:171","msg":"trace[328592873] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"239.655874ms","start":"2026-08-19T20:35:41.802831Z","end":"2026-08-19T20:35:42.042486Z","steps":["trace[328592873] 'process raft request' (duration: 125.38768ms)","trace[328592873] 'compare' (duration: 113.723188ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:35:53.917399Z","caller":"traceutil/trace.go:171","msg":"trace[1129225871] linearizableReadLoop","detail":"{readStateIndex:5022; appliedIndex:5021; }","duration":"152.185233ms","start":"2026-08-19T20:35:53.765192Z","end":"2026-08-19T20:35:53.917378Z","steps":["trace[1129225871] 'read index received' (duration: 152.028205ms)","trace[1129225871] 'applied index is now lower than readState.Index' (duration: 156.158µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:35:53.917517Z","caller":"traceutil/trace.go:171","msg":"trace[530466348] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"215.10573ms","start":"2026-08-19T20:35:53.7024Z","end":"2026-08-19T20:35:53.917505Z","steps":["trace[530466348] 'process raft request' (duration: 214.841867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:53.917757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.785405ms","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-08-19T20:35:53.91781Z","caller":"traceutil/trace.go:171","msg":"trace[1609878381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4812; }","duration":"150.89242ms","start":"2026-08-19T20:35:53.766904Z","end":"2026-08-19T20:35:53.917797Z","steps":["trace[1609878381] 'agreement among raft nodes before linearized reading' (duration: 150.717051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:53.917863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.671547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-19T20:35:53.917895Z","caller":"traceutil/trace.go:171","msg":"trace[683042126] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4812; }","duration":"152.74083ms","start":"2026-08-19T20:35:53.765145Z","end":"2026-08-19T20:35:53.917885Z","steps":["trace[683042126] 'agreement among raft nodes before linearized reading' (duration: 152.662927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:53.918065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.012938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-19T20:35:53.918108Z","caller":"traceutil/trace.go:171","msg":"trace[2095712296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4812; }","duration":"129.078351ms","start":"2026-08-19T20:35:53.789017Z","end":"2026-08-19T20:35:53.918096Z","steps":["trace[2095712296] 'agreement among raft nodes before linearized reading' (duration: 128.981756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:53.918216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.992979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-19T20:35:53.918246Z","caller":"traceutil/trace.go:171","msg":"trace[266019526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4812; }","duration":"148.040851ms","start":"2026-08-19T20:35:53.770197Z","end":"2026-08-19T20:35:53.918238Z","steps":["trace[266019526] 'agreement among raft nodes before linearized reading' (duration: 147.970798ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:54.295164Z","caller":"traceutil/trace.go:171","msg":"trace[1543896446] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"143.205657ms","start":"2026-08-19T20:35:54.151918Z","end":"2026-08-19T20:35:54.295124Z","steps":["trace[1543896446] 'process raft request' (duration: 118.856981ms)","trace[1543896446] 'compare' (duration: 24.171717ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:35:54.29514Z","caller":"traceutil/trace.go:171","msg":"trace[1132920093] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5030; }","duration":"142.44327ms","start":"2026-08-19T20:35:54.152658Z","end":"2026-08-19T20:35:54.295101Z","steps":["trace[1132920093] 'read index received' (duration: 118.134946ms)","trace[1132920093] 'applied index is now lower than readState.Index' (duration: 24.307124ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:35:54.295693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.994907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-08-19T20:35:54.295802Z","caller":"traceutil/trace.go:171","msg":"trace[1984747971] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4821; }","duration":"143.132873ms","start":"2026-08-19T20:35:54.15263Z","end":"2026-08-19T20:35:54.295763Z","steps":["trace[1984747971] 'agreement among raft nodes before linearized reading' (duration: 142.818558ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:54.335434Z","caller":"traceutil/trace.go:171","msg":"trace[1453492757] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"182.740024ms","start":"2026-08-19T20:35:54.152647Z","end":"2026-08-19T20:35:54.335387Z","steps":["trace[1453492757] 'process raft request' (duration: 182.46944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:54.335538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.605954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-19T20:35:54.335633Z","caller":"traceutil/trace.go:171","msg":"trace[163739393] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4824; }","duration":"176.731641ms","start":"2026-08-19T20:35:54.158882Z","end":"2026-08-19T20:35:54.335614Z","steps":["trace[163739393] 'agreement among raft nodes before linearized reading' (duration: 176.492479ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:54.335638Z","caller":"traceutil/trace.go:171","msg":"trace[575442647] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"180.099565ms","start":"2026-08-19T20:35:54.155519Z","end":"2026-08-19T20:35:54.335619Z","steps":["trace[575442647] 'process raft request' (duration: 179.78811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:54.335558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.64233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T20:35:54.33685Z","caller":"traceutil/trace.go:171","msg":"trace[2009336015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4824; }","duration":"135.926842ms","start":"2026-08-19T20:35:54.200861Z","end":"2026-08-19T20:35:54.336788Z","steps":["trace[2009336015] 'agreement among raft nodes before linearized reading' (duration: 134.611578ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:35:54.335772Z","caller":"traceutil/trace.go:171","msg":"trace[2136360432] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"181.825538ms","start":"2026-08-19T20:35:54.153906Z","end":"2026-08-19T20:35:54.335732Z","steps":["trace[2136360432] 'process raft request' (duration: 181.348915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:35:54.335942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.623306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-19T20:35:54.337363Z","caller":"traceutil/trace.go:171","msg":"trace[218642444] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4824; }","duration":"138.099018ms","start":"2026-08-19T20:35:54.199233Z","end":"2026-08-19T20:35:54.337332Z","steps":["trace[218642444] 'agreement among raft nodes before linearized reading' (duration: 136.48485ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:36:30.959938Z","caller":"traceutil/trace.go:171","msg":"trace[887033541] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"123.023609ms","start":"2026-08-19T20:36:30.836892Z","end":"2026-08-19T20:36:30.959916Z","steps":["trace[887033541] 'process raft request' (duration: 122.893692ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:36:33.745222Z","caller":"traceutil/trace.go:171","msg":"trace[641851661] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"117.248918ms","start":"2026-08-19T20:36:33.627953Z","end":"2026-08-19T20:36:33.745202Z","steps":["trace[641851661] 'read index received' (duration: 23.029545ms)","trace[641851661] 'applied index is now lower than readState.Index' (duration: 94.218453ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:36:33.745358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.377045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:36:33.745348Z","caller":"traceutil/trace.go:171","msg":"trace[210575095] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"154.88589ms","start":"2026-08-19T20:36:33.590408Z","end":"2026-08-19T20:36:33.745294Z","steps":["trace[210575095] 'process raft request' (duration: 60.569672ms)","trace[210575095] 'compare' (duration: 94.075655ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:36:33.745417Z","caller":"traceutil/trace.go:171","msg":"trace[663263708] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5236; }","duration":"117.462489ms","start":"2026-08-19T20:36:33.627946Z","end":"2026-08-19T20:36:33.745409Z","steps":["trace[663263708] 'agreement among raft nodes before linearized reading' (duration: 117.350393ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:08.792565Z","caller":"traceutil/trace.go:171","msg":"trace[613593192] transaction","detail":"{read_only:false; response_revision:5547; number_of_response:1; }","duration":"138.514516ms","start":"2026-08-19T20:37:08.654028Z","end":"2026-08-19T20:37:08.792542Z","steps":["trace[613593192] 'process raft request' (duration: 138.396399ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:15.863565Z","caller":"traceutil/trace.go:171","msg":"trace[909150888] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"121.960658ms","start":"2026-08-19T20:37:15.741555Z","end":"2026-08-19T20:37:15.863516Z","steps":["trace[909150888] 'process raft request' (duration: 121.730255ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.008088Z","caller":"traceutil/trace.go:171","msg":"trace[984266023] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"132.488835ms","start":"2026-08-19T20:37:15.87557Z","end":"2026-08-19T20:37:16.008058Z","steps":["trace[984266023] 'process raft request' (duration: 131.854229ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.167753Z","caller":"traceutil/trace.go:171","msg":"trace[1372700592] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5873; }","duration":"115.100936ms","start":"2026-08-19T20:37:16.052615Z","end":"2026-08-19T20:37:16.167716Z","steps":["trace[1372700592] 'read index received' (duration: 89.147032ms)","trace[1372700592] 'applied index is now lower than readState.Index' (duration: 25.952654ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:37:16.167835Z","caller":"traceutil/trace.go:171","msg":"trace[752472644] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"149.361461ms","start":"2026-08-19T20:37:16.018433Z","end":"2026-08-19T20:37:16.167795Z","steps":["trace[752472644] 'process raft request' (duration: 123.341504ms)","trace[752472644] 'compare' (duration: 25.758492ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:37:16.167959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.296866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:37:16.168067Z","caller":"traceutil/trace.go:171","msg":"trace[661094484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5629; }","duration":"115.480677ms","start":"2026-08-19T20:37:16.052562Z","end":"2026-08-19T20:37:16.168043Z","steps":["trace[661094484] 'agreement among raft nodes before linearized reading' (duration: 115.270025ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.381943Z","caller":"traceutil/trace.go:171","msg":"trace[1797178382] linearizableReadLoop","detail":"{readStateIndex:5876; appliedIndex:5875; }","duration":"170.27749ms","start":"2026-08-19T20:37:16.211631Z","end":"2026-08-19T20:37:16.381908Z","steps":["trace[1797178382] 'read index received' (duration: 87.393123ms)","trace[1797178382] 'applied index is now lower than readState.Index' (duration: 82.881417ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:37:16.382011Z","caller":"traceutil/trace.go:171","msg":"trace[1085642781] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"170.846623ms","start":"2026-08-19T20:37:16.211097Z","end":"2026-08-19T20:37:16.381944Z","steps":["trace[1085642781] 'process raft request' (duration: 87.827108ms)","trace[1085642781] 'compare' (duration: 82.461523ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:37:16.382193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.792379ms","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-08-19T20:37:16.382461Z","caller":"traceutil/trace.go:171","msg":"trace[1521745213] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5631; }","duration":"171.111098ms","start":"2026-08-19T20:37:16.211328Z","end":"2026-08-19T20:37:16.382439Z","steps":["trace[1521745213] 'agreement among raft nodes before linearized reading' (duration: 170.723456ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.383768Z","caller":"traceutil/trace.go:171","msg":"trace[478550091] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"131.997657ms","start":"2026-08-19T20:37:16.251688Z","end":"2026-08-19T20:37:16.383685Z","steps":["trace[478550091] 'process raft request' (duration: 131.660157ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.529231Z","caller":"traceutil/trace.go:171","msg":"trace[2010345052] transaction","detail":"{read_only:false; response_revision:5634; number_of_response:1; }","duration":"136.978799ms","start":"2026-08-19T20:37:16.392226Z","end":"2026-08-19T20:37:16.529205Z","steps":["trace[2010345052] 'process raft request' (duration: 131.643086ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.530986Z","caller":"traceutil/trace.go:171","msg":"trace[897578278] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"132.75333ms","start":"2026-08-19T20:37:16.396523Z","end":"2026-08-19T20:37:16.529276Z","steps":["trace[897578278] 'process raft request' (duration: 132.488235ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.723889Z","caller":"traceutil/trace.go:171","msg":"trace[580189460] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"130.228966ms","start":"2026-08-19T20:37:16.593629Z","end":"2026-08-19T20:37:16.723858Z","steps":["trace[580189460] 'process raft request' (duration: 127.48441ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.72443Z","caller":"traceutil/trace.go:171","msg":"trace[1365235249] linearizableReadLoop","detail":"{readStateIndex:5887; appliedIndex:5886; }","duration":"105.336692ms","start":"2026-08-19T20:37:16.619077Z","end":"2026-08-19T20:37:16.724414Z","steps":["trace[1365235249] 'read index received' (duration: 102.065397ms)","trace[1365235249] 'applied index is now lower than readState.Index' (duration: 3.269665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:37:16.724727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.085454ms","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-08-19T20:37:16.724727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.593876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-domain-manage-wqbtk\" ","response":"range_response_count:1 size:6583"} {"level":"info","ts":"2026-08-19T20:37:16.724842Z","caller":"traceutil/trace.go:171","msg":"trace[649477987] range","detail":"{range_begin:/registry/pods/openstack/keystone-domain-manage-wqbtk; range_end:; response_count:1; response_revision:5643; }","duration":"124.734073ms","start":"2026-08-19T20:37:16.600086Z","end":"2026-08-19T20:37:16.72482Z","steps":["trace[649477987] 'agreement among raft nodes before linearized reading' (duration: 124.442637ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.724849Z","caller":"traceutil/trace.go:171","msg":"trace[1297487387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5643; }","duration":"125.224981ms","start":"2026-08-19T20:37:16.599578Z","end":"2026-08-19T20:37:16.724802Z","steps":["trace[1297487387] 'agreement among raft nodes before linearized reading' (duration: 124.953596ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.933352Z","caller":"traceutil/trace.go:171","msg":"trace[1390568493] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"196.895752ms","start":"2026-08-19T20:37:16.7364Z","end":"2026-08-19T20:37:16.933296Z","steps":["trace[1390568493] 'process raft request' (duration: 169.502146ms)","trace[1390568493] 'compare' (duration: 27.177563ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:37:16.933573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.237464ms","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-08-19T20:37:16.933652Z","caller":"traceutil/trace.go:171","msg":"trace[929470903] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5645; }","duration":"159.417714ms","start":"2026-08-19T20:37:16.774215Z","end":"2026-08-19T20:37:16.933633Z","steps":["trace[929470903] 'agreement among raft nodes before linearized reading' (duration: 159.214532ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:37:16.933414Z","caller":"traceutil/trace.go:171","msg":"trace[13202092] linearizableReadLoop","detail":"{readStateIndex:5890; appliedIndex:5889; }","duration":"159.084774ms","start":"2026-08-19T20:37:16.774273Z","end":"2026-08-19T20:37:16.933358Z","steps":["trace[13202092] 'read index received' (duration: 131.54474ms)","trace[13202092] 'applied index is now lower than readState.Index' (duration: 27.506472ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:38:34.206003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-08-19T20:38:34.30168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"93.616189ms","hash":2239254678,"current-db-size-bytes":57942016,"current-db-size":"58 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T20:38:34.301739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239254678,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-08-19T20:39:31.488522Z","caller":"traceutil/trace.go:171","msg":"trace[2072702181] transaction","detail":"{read_only:false; response_revision:6717; number_of_response:1; }","duration":"127.216797ms","start":"2026-08-19T20:39:31.361253Z","end":"2026-08-19T20:39:31.48847Z","steps":["trace[2072702181] 'process raft request' (duration: 44.911683ms)","trace[2072702181] 'compare' (duration: 82.038668ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:39:31.50047Z","caller":"traceutil/trace.go:171","msg":"trace[1375654021] transaction","detail":"{read_only:false; response_revision:6718; number_of_response:1; }","duration":"139.148904ms","start":"2026-08-19T20:39:31.361299Z","end":"2026-08-19T20:39:31.500448Z","steps":["trace[1375654021] 'process raft request' (duration: 139.006915ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:43:34.212966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6158} {"level":"info","ts":"2026-08-19T20:43:34.307374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6158,"took":"92.514865ms","hash":178634883,"current-db-size-bytes":57942016,"current-db-size":"58 MB","current-db-size-in-use-bytes":18714624,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-19T20:43:34.307465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178634883,"revision":6158,"compact-revision":3020}