{"level":"warn","ts":"2026-08-24T20:40:38.340548Z","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-24T20:40:38.340639Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.84: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.84:2380","--initial-cluster=instance=https://199.19.213.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.84: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-24T20:40:38.340712Z","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-24T20:40:38.340726Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.84:2380"]} {"level":"info","ts":"2026-08-24T20:40:38.340747Z","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-24T20:40:38.34146Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"]} {"level":"info","ts":"2026-08-24T20:40:38.341538Z","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.84:2380"],"listen-peer-urls":["https://199.19.213.84:2380"],"advertise-client-urls":["https://199.19.213.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.84: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-24T20:40:38.346771Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.029502ms"} {"level":"info","ts":"2026-08-24T20:40:38.361186Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f823adf772f78a4c","cluster-id":"b5473e4c769d66"} {"level":"info","ts":"2026-08-24T20:40:38.361277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T20:40:38.361306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became follower at term 0"} {"level":"info","ts":"2026-08-24T20:40:38.361319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f823adf772f78a4c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T20:40:38.361339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became follower at term 1"} {"level":"info","ts":"2026-08-24T20:40:38.361386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=(17880326223888353868)"} {"level":"warn","ts":"2026-08-24T20:40:38.369025Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T20:40:38.370389Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T20:40:38.371607Z","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-24T20:40:38.373852Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f823adf772f78a4c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T20:40:38.374142Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f823adf772f78a4c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T20:40:38.374466Z","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-24T20:40:38.37457Z","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-24T20:40:38.374584Z","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-24T20:40:38.376096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=(17880326223888353868)"} {"level":"info","ts":"2026-08-24T20:40:38.376428Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b5473e4c769d66","local-member-id":"f823adf772f78a4c","added-peer-id":"f823adf772f78a4c","added-peer-peer-urls":["https://199.19.213.84:2380"]} {"level":"info","ts":"2026-08-24T20:40:38.377401Z","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-24T20:40:38.377594Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f823adf772f78a4c","initial-advertise-peer-urls":["https://199.19.213.84:2380"],"listen-peer-urls":["https://199.19.213.84:2380"],"advertise-client-urls":["https://199.19.213.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T20:40:38.377626Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T20:40:38.377718Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.84:2380"} {"level":"info","ts":"2026-08-24T20:40:38.377732Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.84:2380"} {"level":"info","ts":"2026-08-24T20:40:38.962264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T20:40:38.962329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T20:40:38.962483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c received MsgPreVoteResp from f823adf772f78a4c at term 1"} {"level":"info","ts":"2026-08-24T20:40:38.962682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became candidate at term 2"} {"level":"info","ts":"2026-08-24T20:40:38.962704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c received MsgVoteResp from f823adf772f78a4c at term 2"} {"level":"info","ts":"2026-08-24T20:40:38.962733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became leader at term 2"} {"level":"info","ts":"2026-08-24T20:40:38.962746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f823adf772f78a4c elected leader f823adf772f78a4c at term 2"} {"level":"info","ts":"2026-08-24T20:40:38.964116Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f823adf772f78a4c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.84:2379]}","request-path":"/0/members/f823adf772f78a4c/attributes","cluster-id":"b5473e4c769d66","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T20:40:38.964204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:40:38.9643Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:40:38.964464Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:40:38.964552Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T20:40:38.967645Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T20:40:38.967902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T20:40:38.968418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.84:2379"} {"level":"info","ts":"2026-08-24T20:40:38.974027Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b5473e4c769d66","local-member-id":"f823adf772f78a4c","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:40:38.974123Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:40:38.974145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:40:40.363701Z","caller":"traceutil/trace.go:171","msg":"trace[978626824] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:7; }","duration":"133.104942ms","start":"2026-08-24T20:40:40.230575Z","end":"2026-08-24T20:40:40.36368Z","steps":["trace[978626824] 'read index received' (duration: 27.140222ms)","trace[978626824] 'applied index is now lower than readState.Index' (duration: 105.9637ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:40:40.363799Z","caller":"traceutil/trace.go:171","msg":"trace[179850816] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"133.106381ms","start":"2026-08-24T20:40:40.230672Z","end":"2026-08-24T20:40:40.363778Z","steps":["trace[179850816] 'process raft request' (duration: 132.912777ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.363905Z","caller":"traceutil/trace.go:171","msg":"trace[1281099872] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"120.292421ms","start":"2026-08-24T20:40:40.243602Z","end":"2026-08-24T20:40:40.363894Z","steps":["trace[1281099872] 'process raft request' (duration: 120.010916ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.363901Z","caller":"traceutil/trace.go:171","msg":"trace[1581569223] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"133.319835ms","start":"2026-08-24T20:40:40.230566Z","end":"2026-08-24T20:40:40.363886Z","steps":["trace[1581569223] 'process raft request' (duration: 132.937868ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.364035Z","caller":"traceutil/trace.go:171","msg":"trace[1745076919] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"133.351215ms","start":"2026-08-24T20:40:40.230601Z","end":"2026-08-24T20:40:40.363953Z","steps":["trace[1745076919] 'process raft request' (duration: 132.939698ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.364155Z","caller":"traceutil/trace.go:171","msg":"trace[1132091360] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"118.722409ms","start":"2026-08-24T20:40:40.24542Z","end":"2026-08-24T20:40:40.364142Z","steps":["trace[1132091360] 'process raft request' (duration: 118.220162ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.363754Z","caller":"traceutil/trace.go:171","msg":"trace[1075066260] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"133.208822ms","start":"2026-08-24T20:40:40.230517Z","end":"2026-08-24T20:40:40.363726Z","steps":["trace[1075066260] 'process raft request' (duration: 132.875537ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.364053Z","caller":"traceutil/trace.go:171","msg":"trace[1005928279] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"133.387055ms","start":"2026-08-24T20:40:40.230629Z","end":"2026-08-24T20:40:40.364016Z","steps":["trace[1005928279] 'process raft request' (duration: 132.934328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:40.364553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.944623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T20:40:40.364662Z","caller":"traceutil/trace.go:171","msg":"trace[2137534676] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"134.099565ms","start":"2026-08-24T20:40:40.230547Z","end":"2026-08-24T20:40:40.364647Z","steps":["trace[2137534676] 'agreement among raft nodes before linearized reading' (duration: 133.773331ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.41043Z","caller":"traceutil/trace.go:171","msg":"trace[837361794] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"149.221417ms","start":"2026-08-24T20:40:40.26119Z","end":"2026-08-24T20:40:40.410411Z","steps":["trace[837361794] 'process raft request' (duration: 149.143566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:40.410586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.176827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-24T20:40:40.410624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.924946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T20:40:40.410668Z","caller":"traceutil/trace.go:171","msg":"trace[426952464] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"112.283598ms","start":"2026-08-24T20:40:40.298369Z","end":"2026-08-24T20:40:40.410653Z","steps":["trace[426952464] 'agreement among raft nodes before linearized reading' (duration: 112.071935ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:40.410681Z","caller":"traceutil/trace.go:171","msg":"trace[217392497] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"130.008897ms","start":"2026-08-24T20:40:40.280653Z","end":"2026-08-24T20:40:40.410661Z","steps":["trace[217392497] 'agreement among raft nodes before linearized reading' (duration: 129.850725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:02.554814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121175696,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T20:41:02.843559Z","caller":"traceutil/trace.go:171","msg":"trace[1801904204] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"789.47429ms","start":"2026-08-24T20:41:02.054051Z","end":"2026-08-24T20:41:02.843525Z","steps":["trace[1801904204] 'read index received' (duration: 789.167816ms)","trace[1801904204] 'applied index is now lower than readState.Index' (duration: 304.724µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:41:02.843689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:01.935491Z","time spent":"908.186857ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T20:41:02.843854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.766313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:02.843917Z","caller":"traceutil/trace.go:171","msg":"trace[720848891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"789.898804ms","start":"2026-08-24T20:41:02.054Z","end":"2026-08-24T20:41:02.843899Z","steps":["trace[720848891] 'agreement among raft nodes before linearized reading' (duration: 789.756422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:02.843986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.053979Z","time spent":"789.992435ms","remote":"127.0.0.1:48830","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-24T20:41:02.844083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.83769ms","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-24T20:41:02.844224Z","caller":"traceutil/trace.go:171","msg":"trace[1511570692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:385; }","duration":"377.027721ms","start":"2026-08-24T20:41:02.467139Z","end":"2026-08-24T20:41:02.844167Z","steps":["trace[1511570692] 'agreement among raft nodes before linearized reading' (duration: 376.810489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:02.844272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.467102Z","time spent":"377.161043ms","remote":"127.0.0.1:56050","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-24T20:41:02.844419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.179682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:41:02.844541Z","caller":"traceutil/trace.go:171","msg":"trace[1044265719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:385; }","duration":"249.297524ms","start":"2026-08-24T20:41:02.59522Z","end":"2026-08-24T20:41:02.844517Z","steps":["trace[1044265719] 'agreement among raft nodes before linearized reading' (duration: 248.917449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:02.84476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.967163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-08-24T20:41:02.84497Z","caller":"traceutil/trace.go:171","msg":"trace[1582487324] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:385; }","duration":"129.388088ms","start":"2026-08-24T20:41:02.715556Z","end":"2026-08-24T20:41:02.844944Z","steps":["trace[1582487324] 'agreement among raft nodes before linearized reading' (duration: 128.879892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:02.844882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.161466ms","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-24T20:41:02.84545Z","caller":"traceutil/trace.go:171","msg":"trace[502005939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:385; }","duration":"355.757283ms","start":"2026-08-24T20:41:02.489651Z","end":"2026-08-24T20:41:02.845409Z","steps":["trace[502005939] 'agreement among raft nodes before linearized reading' (duration: 354.885503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:02.845522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.489623Z","time spent":"355.882474ms","remote":"127.0.0.1:56050","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-24T20:41:03.274439Z","caller":"traceutil/trace.go:171","msg":"trace[728254141] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"428.38973ms","start":"2026-08-24T20:41:02.846022Z","end":"2026-08-24T20:41:03.274412Z","steps":["trace[728254141] 'process raft request' (duration: 427.286668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:03.27498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.845651Z","time spent":"428.861885ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:41:03.324502Z","caller":"traceutil/trace.go:171","msg":"trace[1233492527] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"474.434425ms","start":"2026-08-24T20:41:02.850044Z","end":"2026-08-24T20:41:03.324479Z","steps":["trace[1233492527] 'process raft request' (duration: 474.175322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:03.324666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.850017Z","time spent":"474.561027ms","remote":"127.0.0.1:56050","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-24T20:41:03.324883Z","caller":"traceutil/trace.go:171","msg":"trace[290967194] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"473.943801ms","start":"2026-08-24T20:41:02.850921Z","end":"2026-08-24T20:41:03.324865Z","steps":["trace[290967194] 'process raft request' (duration: 473.471905ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:03.324951Z","caller":"traceutil/trace.go:171","msg":"trace[865023922] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:403; }","duration":"332.41288ms","start":"2026-08-24T20:41:02.992515Z","end":"2026-08-24T20:41:03.324928Z","steps":["trace[865023922] 'read index received' (duration: 280.916171ms)","trace[865023922] 'applied index is now lower than readState.Index' (duration: 51.495769ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:41:03.324991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.850886Z","time spent":"474.041181ms","remote":"127.0.0.1:56050","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-24T20:41:03.325035Z","caller":"traceutil/trace.go:171","msg":"trace[846545960] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"473.481475ms","start":"2026-08-24T20:41:02.851537Z","end":"2026-08-24T20:41:03.325019Z","steps":["trace[846545960] 'process raft request' (duration: 473.213082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:03.325123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.851516Z","time spent":"473.559716ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:41:03.325285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.778125ms","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-24T20:41:03.325319Z","caller":"traceutil/trace.go:171","msg":"trace[1431548651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"332.866226ms","start":"2026-08-24T20:41:02.992443Z","end":"2026-08-24T20:41:03.325309Z","steps":["trace[1431548651] 'agreement among raft nodes before linearized reading' (duration: 332.797385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:03.325342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.992417Z","time spent":"332.919726ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T20:41:03.325332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.430943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:03.325436Z","caller":"traceutil/trace.go:171","msg":"trace[474641265] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"466.209799ms","start":"2026-08-24T20:41:02.859214Z","end":"2026-08-24T20:41:03.325424Z","steps":["trace[474641265] 'process raft request' (duration: 465.643782ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:03.325473Z","caller":"traceutil/trace.go:171","msg":"trace[1319668265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"273.628184ms","start":"2026-08-24T20:41:03.051836Z","end":"2026-08-24T20:41:03.325465Z","steps":["trace[1319668265] 'agreement among raft nodes before linearized reading' (duration: 273.450722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:03.325507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:02.859198Z","time spent":"466.276219ms","remote":"127.0.0.1:56246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:41:03.548644Z","caller":"traceutil/trace.go:171","msg":"trace[1628783334] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"217.726925ms","start":"2026-08-24T20:41:03.33089Z","end":"2026-08-24T20:41:03.548617Z","steps":["trace[1628783334] 'process raft request' (duration: 152.969571ms)","trace[1628783334] 'compare' (duration: 64.626913ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:16.594716Z","caller":"traceutil/trace.go:171","msg":"trace[1312419735] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"360.515087ms","start":"2026-08-24T20:41:16.234178Z","end":"2026-08-24T20:41:16.594693Z","steps":["trace[1312419735] 'process raft request' (duration: 360.387126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.594846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.234157Z","time spent":"360.634029ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.605848Z","caller":"traceutil/trace.go:171","msg":"trace[756818763] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"370.952057ms","start":"2026-08-24T20:41:16.234874Z","end":"2026-08-24T20:41:16.605826Z","steps":["trace[756818763] 'process raft request' (duration: 370.825906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.605926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.234844Z","time spent":"371.046678ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.605999Z","caller":"traceutil/trace.go:171","msg":"trace[1424401549] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"371.119949ms","start":"2026-08-24T20:41:16.234874Z","end":"2026-08-24T20:41:16.605994Z","steps":["trace[1424401549] 'process raft request' (duration: 370.909127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.606036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.234861Z","time spent":"371.161639ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.606169Z","caller":"traceutil/trace.go:171","msg":"trace[281146951] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"351.40437ms","start":"2026-08-24T20:41:16.254756Z","end":"2026-08-24T20:41:16.606161Z","steps":["trace[281146951] 'process raft request' (duration: 351.076126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.606201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.254749Z","time spent":"351.43946ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.606191Z","caller":"traceutil/trace.go:171","msg":"trace[455331037] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"352.588753ms","start":"2026-08-24T20:41:16.253565Z","end":"2026-08-24T20:41:16.606154Z","steps":["trace[455331037] 'process raft request' (duration: 352.24319ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:16.606405Z","caller":"traceutil/trace.go:171","msg":"trace[385343289] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"350.050296ms","start":"2026-08-24T20:41:16.256349Z","end":"2026-08-24T20:41:16.606399Z","steps":["trace[385343289] 'process raft request' (duration: 349.51079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.606431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.256341Z","time spent":"350.078496ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.606557Z","caller":"traceutil/trace.go:171","msg":"trace[1350629372] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"334.318859ms","start":"2026-08-24T20:41:16.272229Z","end":"2026-08-24T20:41:16.606547Z","steps":["trace[1350629372] 'process raft request' (duration: 334.110296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.606585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.272204Z","time spent":"334.370379ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.60666Z","caller":"traceutil/trace.go:171","msg":"trace[372045250] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"349.561681ms","start":"2026-08-24T20:41:16.257093Z","end":"2026-08-24T20:41:16.606655Z","steps":["trace[372045250] 'process raft request' (duration: 349.043775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.606684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.257084Z","time spent":"349.590571ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T20:41:16.6067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.253536Z","time spent":"352.882246ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.606729Z","caller":"traceutil/trace.go:171","msg":"trace[1681164045] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"331.072654ms","start":"2026-08-24T20:41:16.275629Z","end":"2026-08-24T20:41:16.606701Z","steps":["trace[1681164045] 'process raft request' (duration: 330.756121ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:16.606868Z","caller":"traceutil/trace.go:171","msg":"trace[664023068] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"327.054591ms","start":"2026-08-24T20:41:16.279806Z","end":"2026-08-24T20:41:16.606861Z","steps":["trace[664023068] 'process raft request' (duration: 326.688257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.606893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.275617Z","time spent":"331.199455ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T20:41:16.606906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.279792Z","time spent":"327.098201ms","remote":"127.0.0.1:55858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:41:16.607338Z","caller":"traceutil/trace.go:171","msg":"trace[2013937700] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"297.69081ms","start":"2026-08-24T20:41:16.309636Z","end":"2026-08-24T20:41:16.607326Z","steps":["trace[2013937700] 'process raft request' (duration: 296.899571ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:16.607427Z","caller":"traceutil/trace.go:171","msg":"trace[612628913] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"139.784033ms","start":"2026-08-24T20:41:16.467557Z","end":"2026-08-24T20:41:16.607341Z","steps":["trace[612628913] 'process raft request' (duration: 139.681112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:22.769582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.221741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bhsfb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-24T20:41:22.769794Z","caller":"traceutil/trace.go:171","msg":"trace[1017432143] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bhsfb; range_end:; response_count:1; response_revision:591; }","duration":"136.463663ms","start":"2026-08-24T20:41:22.633313Z","end":"2026-08-24T20:41:22.769777Z","steps":["trace[1017432143] 'range keys from in-memory index tree' (duration: 136.097029ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:23.271564Z","caller":"traceutil/trace.go:171","msg":"trace[1989328546] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"218.215943ms","start":"2026-08-24T20:41:23.053318Z","end":"2026-08-24T20:41:23.271534Z","steps":["trace[1989328546] 'read index received' (duration: 215.549626ms)","trace[1989328546] 'applied index is now lower than readState.Index' (duration: 2.665257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:41:23.271724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.392575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:23.271769Z","caller":"traceutil/trace.go:171","msg":"trace[582977783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"218.482387ms","start":"2026-08-24T20:41:23.053272Z","end":"2026-08-24T20:41:23.271754Z","steps":["trace[582977783] 'agreement among raft nodes before linearized reading' (duration: 218.363165ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:23.271889Z","caller":"traceutil/trace.go:171","msg":"trace[248800545] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"286.637869ms","start":"2026-08-24T20:41:22.985071Z","end":"2026-08-24T20:41:23.271709Z","steps":["trace[248800545] 'process raft request' (duration: 284.185395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:24.1367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.967701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:41:24.136916Z","caller":"traceutil/trace.go:171","msg":"trace[682194593] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"708.737142ms","start":"2026-08-24T20:41:23.428141Z","end":"2026-08-24T20:41:24.136879Z","steps":["trace[682194593] 'read index received' (duration: 367.304626ms)","trace[682194593] 'applied index is now lower than readState.Index' (duration: 341.430526ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:24.137058Z","caller":"traceutil/trace.go:171","msg":"trace[1842246662] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"814.829178ms","start":"2026-08-24T20:41:23.322211Z","end":"2026-08-24T20:41:24.13704Z","steps":["trace[1842246662] 'process raft request' (duration: 473.292211ms)","trace[1842246662] 'compare' (duration: 340.520507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:41:24.137133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:23.322192Z","time spent":"814.903489ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T20:41:24.13732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.11396ms","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-24T20:41:24.137301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.640661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:41:24.137422Z","caller":"traceutil/trace.go:171","msg":"trace[1028568558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"676.237942ms","start":"2026-08-24T20:41:23.461161Z","end":"2026-08-24T20:41:24.137399Z","steps":["trace[1028568558] 'agreement among raft nodes before linearized reading' (duration: 675.989529ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:24.137436Z","caller":"traceutil/trace.go:171","msg":"trace[1233423914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"708.797383ms","start":"2026-08-24T20:41:23.428616Z","end":"2026-08-24T20:41:24.137413Z","steps":["trace[1233423914] 'agreement among raft nodes before linearized reading' (duration: 708.499759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:24.137421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.552407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-24T20:41:24.137441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.280288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T20:41:24.137477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:23.461144Z","time spent":"676.318454ms","remote":"127.0.0.1:56050","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-24T20:41:24.137482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:23.428607Z","time spent":"708.861734ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T20:41:24.137511Z","caller":"traceutil/trace.go:171","msg":"trace[27677872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"709.408959ms","start":"2026-08-24T20:41:23.428085Z","end":"2026-08-24T20:41:24.137494Z","steps":["trace[27677872] 'agreement among raft nodes before linearized reading' (duration: 709.023534ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:24.137511Z","caller":"traceutil/trace.go:171","msg":"trace[1579463654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"693.68792ms","start":"2026-08-24T20:41:23.443797Z","end":"2026-08-24T20:41:24.137485Z","steps":["trace[1579463654] 'agreement among raft nodes before linearized reading' (duration: 693.397126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:24.137556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:23.428057Z","time spent":"709.4866ms","remote":"127.0.0.1:56050","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-24T20:41:24.137562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:23.443764Z","time spent":"693.78937ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T20:41:25.929718Z","caller":"traceutil/trace.go:171","msg":"trace[1522234850] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"109.010002ms","start":"2026-08-24T20:41:25.820686Z","end":"2026-08-24T20:41:25.929696Z","steps":["trace[1522234850] 'process raft request' (duration: 108.895631ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:38.993771Z","caller":"traceutil/trace.go:171","msg":"trace[833533635] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"253.909921ms","start":"2026-08-24T20:41:38.739834Z","end":"2026-08-24T20:41:38.993744Z","steps":["trace[833533635] 'process raft request' (duration: 253.712479ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:58.774041Z","caller":"traceutil/trace.go:171","msg":"trace[1529131031] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"270.819564ms","start":"2026-08-24T20:41:58.503192Z","end":"2026-08-24T20:41:58.774012Z","steps":["trace[1529131031] 'read index received' (duration: 270.407381ms)","trace[1529131031] 'applied index is now lower than readState.Index' (duration: 411.273µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:58.774201Z","caller":"traceutil/trace.go:171","msg":"trace[385069856] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"294.12037ms","start":"2026-08-24T20:41:58.480066Z","end":"2026-08-24T20:41:58.774187Z","steps":["trace[385069856] 'process raft request' (duration: 293.602706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:58.774527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.305198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-08-24T20:41:58.774896Z","caller":"traceutil/trace.go:171","msg":"trace[1751812560] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1055; }","duration":"271.47234ms","start":"2026-08-24T20:41:58.503159Z","end":"2026-08-24T20:41:58.774632Z","steps":["trace[1751812560] 'agreement among raft nodes before linearized reading' (duration: 271.079207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:58.775456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.822523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:986"} {"level":"info","ts":"2026-08-24T20:41:58.775527Z","caller":"traceutil/trace.go:171","msg":"trace[285681692] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1056; }","duration":"271.894203ms","start":"2026-08-24T20:41:58.503616Z","end":"2026-08-24T20:41:58.77551Z","steps":["trace[285681692] 'agreement among raft nodes before linearized reading' (duration: 271.710532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:58.775823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.536119ms","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-24T20:41:58.775878Z","caller":"traceutil/trace.go:171","msg":"trace[1066229136] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1056; }","duration":"239.654351ms","start":"2026-08-24T20:41:58.536208Z","end":"2026-08-24T20:41:58.775863Z","steps":["trace[1066229136] 'agreement among raft nodes before linearized reading' (duration: 239.5154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:58.776199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.55035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:968"} {"level":"info","ts":"2026-08-24T20:41:58.776256Z","caller":"traceutil/trace.go:171","msg":"trace[1894374551] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1056; }","duration":"272.61363ms","start":"2026-08-24T20:41:58.503628Z","end":"2026-08-24T20:41:58.776242Z","steps":["trace[1894374551] 'agreement among raft nodes before linearized reading' (duration: 272.128205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:00.069764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.905045ms","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-24T20:42:00.070112Z","caller":"traceutil/trace.go:171","msg":"trace[491719185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"451.679191ms","start":"2026-08-24T20:41:59.618384Z","end":"2026-08-24T20:42:00.070063Z","steps":["trace[491719185] 'range keys from in-memory index tree' (duration: 449.365923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:00.070232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:59.618364Z","time spent":"451.831873ms","remote":"127.0.0.1:56050","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":"info","ts":"2026-08-24T20:42:00.070819Z","caller":"traceutil/trace.go:171","msg":"trace[1051175822] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"328.303045ms","start":"2026-08-24T20:41:59.742476Z","end":"2026-08-24T20:42:00.070779Z","steps":["trace[1051175822] 'process raft request' (duration: 328.139953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:00.070972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:59.742445Z","time spent":"328.448237ms","remote":"127.0.0.1:32852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T20:42:27.045871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.683178ms","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-24T20:42:27.045878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.933752ms","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-24T20:42:27.045997Z","caller":"traceutil/trace.go:171","msg":"trace[1742348058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"107.101123ms","start":"2026-08-24T20:42:26.93887Z","end":"2026-08-24T20:42:27.045972Z","steps":["trace[1742348058] 'range keys from in-memory index tree' (duration: 106.651691ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:27.046075Z","caller":"traceutil/trace.go:171","msg":"trace[1783811552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"115.927431ms","start":"2026-08-24T20:42:26.930128Z","end":"2026-08-24T20:42:27.046056Z","steps":["trace[1783811552] 'range keys from in-memory index tree' (duration: 115.568358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:27.045851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.756011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:42:27.046228Z","caller":"traceutil/trace.go:171","msg":"trace[400202638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"107.147894ms","start":"2026-08-24T20:42:26.939062Z","end":"2026-08-24T20:42:27.04621Z","steps":["trace[400202638] 'range keys from in-memory index tree' (duration: 106.519109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:27.045851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.902882ms","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-24T20:42:27.047164Z","caller":"traceutil/trace.go:171","msg":"trace[1689093732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"108.194172ms","start":"2026-08-24T20:42:26.938892Z","end":"2026-08-24T20:42:27.047086Z","steps":["trace[1689093732] 'range keys from in-memory index tree' (duration: 106.69812ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:27.201148Z","caller":"traceutil/trace.go:171","msg":"trace[823993958] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"149.999108ms","start":"2026-08-24T20:42:27.051122Z","end":"2026-08-24T20:42:27.201121Z","steps":["trace[823993958] 'process raft request' (duration: 149.801535ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:27.206318Z","caller":"traceutil/trace.go:171","msg":"trace[1791396817] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"154.4402ms","start":"2026-08-24T20:42:27.051862Z","end":"2026-08-24T20:42:27.206302Z","steps":["trace[1791396817] 'process raft request' (duration: 154.292249ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:27.206514Z","caller":"traceutil/trace.go:171","msg":"trace[2091344538] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"154.523971ms","start":"2026-08-24T20:42:27.051982Z","end":"2026-08-24T20:42:27.206506Z","steps":["trace[2091344538] 'process raft request' (duration: 154.278999ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:27.206539Z","caller":"traceutil/trace.go:171","msg":"trace[1614724277] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"154.559762ms","start":"2026-08-24T20:42:27.051932Z","end":"2026-08-24T20:42:27.206492Z","steps":["trace[1614724277] 'process raft request' (duration: 154.29968ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:27.206429Z","caller":"traceutil/trace.go:171","msg":"trace[847935121] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1341; }","duration":"152.613286ms","start":"2026-08-24T20:42:27.053803Z","end":"2026-08-24T20:42:27.206417Z","steps":["trace[847935121] 'read index received' (duration: 147.312287ms)","trace[847935121] 'applied index is now lower than readState.Index' (duration: 5.300159ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:42:27.206783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.96517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:42:27.206845Z","caller":"traceutil/trace.go:171","msg":"trace[1894647301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"153.05518ms","start":"2026-08-24T20:42:27.05378Z","end":"2026-08-24T20:42:27.206835Z","steps":["trace[1894647301] 'agreement among raft nodes before linearized reading' (duration: 152.906789ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:01.528869Z","caller":"traceutil/trace.go:171","msg":"trace[35749074] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"547.767499ms","start":"2026-08-24T20:43:00.981069Z","end":"2026-08-24T20:43:01.528837Z","steps":["trace[35749074] 'process raft request' (duration: 547.568168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:01.529148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:00.981048Z","time spent":"547.99739ms","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:43:01.545622Z","caller":"traceutil/trace.go:171","msg":"trace[128640920] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1924; }","duration":"492.176251ms","start":"2026-08-24T20:43:01.053434Z","end":"2026-08-24T20:43:01.54561Z","steps":["trace[128640920] 'read index received' (duration: 475.226213ms)","trace[128640920] 'applied index is now lower than readState.Index' (duration: 16.949178ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:01.545657Z","caller":"traceutil/trace.go:171","msg":"trace[1026920213] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"534.149553ms","start":"2026-08-24T20:43:01.011482Z","end":"2026-08-24T20:43:01.545632Z","steps":["trace[1026920213] 'process raft request' (duration: 534.031352ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:01.5457Z","caller":"traceutil/trace.go:171","msg":"trace[1700697680] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"214.699607ms","start":"2026-08-24T20:43:01.330988Z","end":"2026-08-24T20:43:01.545687Z","steps":["trace[1700697680] 'process raft request' (duration: 214.591326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:01.545835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:01.011455Z","time spent":"534.283534ms","remote":"127.0.0.1:56050","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-24T20:43:01.546118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.036492ms","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-24T20:43:01.546224Z","caller":"traceutil/trace.go:171","msg":"trace[1001794409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1850; }","duration":"171.191753ms","start":"2026-08-24T20:43:01.37502Z","end":"2026-08-24T20:43:01.546212Z","steps":["trace[1001794409] 'agreement among raft nodes before linearized reading' (duration: 170.75605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:01.546238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.778885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:43:01.546329Z","caller":"traceutil/trace.go:171","msg":"trace[1846566303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"492.928966ms","start":"2026-08-24T20:43:01.05338Z","end":"2026-08-24T20:43:01.546309Z","steps":["trace[1846566303] 'agreement among raft nodes before linearized reading' (duration: 492.786185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:01.546389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:01.053361Z","time spent":"493.012826ms","remote":"127.0.0.1:48816","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-24T20:43:01.546334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.372952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T20:43:01.546408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.808955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:43:01.546465Z","caller":"traceutil/trace.go:171","msg":"trace[1237478958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"116.531913ms","start":"2026-08-24T20:43:01.429919Z","end":"2026-08-24T20:43:01.546451Z","steps":["trace[1237478958] 'agreement among raft nodes before linearized reading' (duration: 116.18565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:01.546515Z","caller":"traceutil/trace.go:171","msg":"trace[968369076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1850; }","duration":"149.948036ms","start":"2026-08-24T20:43:01.396542Z","end":"2026-08-24T20:43:01.54649Z","steps":["trace[968369076] 'agreement among raft nodes before linearized reading' (duration: 149.605954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:01.546419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.059685ms","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-24T20:43:01.54659Z","caller":"traceutil/trace.go:171","msg":"trace[435636010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"147.226736ms","start":"2026-08-24T20:43:01.39935Z","end":"2026-08-24T20:43:01.546577Z","steps":["trace[435636010] 'agreement among raft nodes before linearized reading' (duration: 146.774513ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:01.65975Z","caller":"traceutil/trace.go:171","msg":"trace[629694007] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"108.390595ms","start":"2026-08-24T20:43:01.551333Z","end":"2026-08-24T20:43:01.659724Z","steps":["trace[629694007] 'process raft request' (duration: 103.681343ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:01.660054Z","caller":"traceutil/trace.go:171","msg":"trace[76458164] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"108.635797ms","start":"2026-08-24T20:43:01.551384Z","end":"2026-08-24T20:43:01.66002Z","steps":["trace[76458164] 'process raft request' (duration: 108.288464ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:01.660403Z","caller":"traceutil/trace.go:171","msg":"trace[1458540234] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"108.681618ms","start":"2026-08-24T20:43:01.551688Z","end":"2026-08-24T20:43:01.66037Z","steps":["trace[1458540234] 'process raft request' (duration: 108.190074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:02.383481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.246527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T20:43:02.383569Z","caller":"traceutil/trace.go:171","msg":"trace[1119612139] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:1; response_revision:1854; }","duration":"444.358808ms","start":"2026-08-24T20:43:01.939186Z","end":"2026-08-24T20:43:02.383545Z","steps":["trace[1119612139] 'range keys from in-memory index tree' (duration: 444.060016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:02.38362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:01.939175Z","time spent":"444.434389ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.84\" "} {"level":"warn","ts":"2026-08-24T20:43:02.383872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.895819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:43:02.384005Z","caller":"traceutil/trace.go:171","msg":"trace[1083543443] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"154.102645ms","start":"2026-08-24T20:43:02.229882Z","end":"2026-08-24T20:43:02.383984Z","steps":["trace[1083543443] 'read index received' (duration: 36.957542ms)","trace[1083543443] 'applied index is now lower than readState.Index' (duration: 117.143683ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:43:02.384211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.322557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-24T20:43:02.38427Z","caller":"traceutil/trace.go:171","msg":"trace[2009565130] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1855; }","duration":"154.422769ms","start":"2026-08-24T20:43:02.229829Z","end":"2026-08-24T20:43:02.384251Z","steps":["trace[2009565130] 'agreement among raft nodes before linearized reading' (duration: 154.213827ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:02.384508Z","caller":"traceutil/trace.go:171","msg":"trace[1027094129] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"200.946928ms","start":"2026-08-24T20:43:02.183521Z","end":"2026-08-24T20:43:02.384468Z","steps":["trace[1027094129] 'process raft request' (duration: 83.341931ms)","trace[1027094129] 'compare' (duration: 116.431064ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:43:02.384552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.578807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-13df9f4e-8e73-4fc3-a1f4-f99ca67b997b\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-08-24T20:43:02.384646Z","caller":"traceutil/trace.go:171","msg":"trace[1312080447] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-13df9f4e-8e73-4fc3-a1f4-f99ca67b997b; range_end:; response_count:1; response_revision:1854; }","duration":"509.711058ms","start":"2026-08-24T20:43:01.874905Z","end":"2026-08-24T20:43:02.384616Z","steps":["trace[1312080447] 'range keys from in-memory index tree' (duration: 509.352065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:02.384712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:01.874886Z","time spent":"509.806699ms","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3765,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-13df9f4e-8e73-4fc3-a1f4-f99ca67b997b\" "} {"level":"info","ts":"2026-08-24T20:43:03.14626Z","caller":"traceutil/trace.go:171","msg":"trace[1064638520] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"154.088976ms","start":"2026-08-24T20:43:02.992131Z","end":"2026-08-24T20:43:03.14622Z","steps":["trace[1064638520] 'process raft request' (duration: 141.476139ms)","trace[1064638520] 'compare' (duration: 12.343444ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:15.278494Z","caller":"traceutil/trace.go:171","msg":"trace[718140089] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"225.644609ms","start":"2026-08-24T20:43:15.052826Z","end":"2026-08-24T20:43:15.278471Z","steps":["trace[718140089] 'read index received' (duration: 225.44633ms)","trace[718140089] 'applied index is now lower than readState.Index' (duration: 197.439µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:15.278611Z","caller":"traceutil/trace.go:171","msg":"trace[840853345] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"269.915744ms","start":"2026-08-24T20:43:15.008681Z","end":"2026-08-24T20:43:15.278597Z","steps":["trace[840853345] 'process raft request' (duration: 269.651242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:15.278694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.819009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:43:15.278737Z","caller":"traceutil/trace.go:171","msg":"trace[290859375] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1959; }","duration":"209.876351ms","start":"2026-08-24T20:43:15.068852Z","end":"2026-08-24T20:43:15.278729Z","steps":["trace[290859375] 'agreement among raft nodes before linearized reading' (duration: 209.797348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:15.278862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.040117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:43:15.278879Z","caller":"traceutil/trace.go:171","msg":"trace[1256627407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1959; }","duration":"226.086979ms","start":"2026-08-24T20:43:15.052787Z","end":"2026-08-24T20:43:15.278874Z","steps":["trace[1256627407] 'agreement among raft nodes before linearized reading' (duration: 226.057218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:32.040736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.284383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:43:32.04084Z","caller":"traceutil/trace.go:171","msg":"trace[452425758] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"251.920366ms","start":"2026-08-24T20:43:31.788906Z","end":"2026-08-24T20:43:32.040826Z","steps":["trace[452425758] 'read index received' (duration: 61.448175ms)","trace[452425758] 'applied index is now lower than readState.Index' (duration: 190.470941ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:32.040909Z","caller":"traceutil/trace.go:171","msg":"trace[1552581787] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"311.270294ms","start":"2026-08-24T20:43:31.729629Z","end":"2026-08-24T20:43:32.0409Z","steps":["trace[1552581787] 'process raft request' (duration: 120.741621ms)","trace[1552581787] 'compare' (duration: 190.097146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:43:32.040964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:31.729613Z","time spent":"311.327377ms","remote":"127.0.0.1:56050","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-24T20:43:32.041039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.03813ms","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-24T20:43:32.04115Z","caller":"traceutil/trace.go:171","msg":"trace[2001006674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2067; }","duration":"216.175796ms","start":"2026-08-24T20:43:31.824958Z","end":"2026-08-24T20:43:32.041134Z","steps":["trace[2001006674] 'agreement among raft nodes before linearized reading' (duration: 215.956997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:32.041272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.300817ms","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-24T20:43:32.041301Z","caller":"traceutil/trace.go:171","msg":"trace[1994726982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"172.36083ms","start":"2026-08-24T20:43:31.868934Z","end":"2026-08-24T20:43:32.041295Z","steps":["trace[1994726982] 'agreement among raft nodes before linearized reading' (duration: 172.288897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:32.04126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.010075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T20:43:32.041406Z","caller":"traceutil/trace.go:171","msg":"trace[1094682700] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:1; response_revision:2067; }","duration":"101.222843ms","start":"2026-08-24T20:43:31.940172Z","end":"2026-08-24T20:43:32.041395Z","steps":["trace[1094682700] 'agreement among raft nodes before linearized reading' (duration: 100.846198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:32.041627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.49596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:43:32.041664Z","caller":"traceutil/trace.go:171","msg":"trace[976004123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2067; }","duration":"175.554422ms","start":"2026-08-24T20:43:31.866102Z","end":"2026-08-24T20:43:32.041656Z","steps":["trace[976004123] 'agreement among raft nodes before linearized reading' (duration: 175.422007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:32.041873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.474349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-24T20:43:32.041949Z","caller":"traceutil/trace.go:171","msg":"trace[1560735127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2067; }","duration":"253.055133ms","start":"2026-08-24T20:43:31.788875Z","end":"2026-08-24T20:43:32.04193Z","steps":["trace[1560735127] 'agreement among raft nodes before linearized reading' (duration: 252.415536ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:32.178101Z","caller":"traceutil/trace.go:171","msg":"trace[1831671994] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"132.684944ms","start":"2026-08-24T20:43:32.045391Z","end":"2026-08-24T20:43:32.178076Z","steps":["trace[1831671994] 'process raft request' (duration: 132.58724ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:32.178281Z","caller":"traceutil/trace.go:171","msg":"trace[990862448] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"133.31472ms","start":"2026-08-24T20:43:32.044947Z","end":"2026-08-24T20:43:32.178262Z","steps":["trace[990862448] 'process raft request' (duration: 132.846411ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:32.17846Z","caller":"traceutil/trace.go:171","msg":"trace[253170373] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"132.798578ms","start":"2026-08-24T20:43:32.045635Z","end":"2026-08-24T20:43:32.178434Z","steps":["trace[253170373] 'process raft request' (duration: 132.403742ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:32.178334Z","caller":"traceutil/trace.go:171","msg":"trace[400582683] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2151; }","duration":"127.566823ms","start":"2026-08-24T20:43:32.050737Z","end":"2026-08-24T20:43:32.178303Z","steps":["trace[400582683] 'read index received' (duration: 61.988013ms)","trace[400582683] 'applied index is now lower than readState.Index' (duration: 65.57599ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:43:32.178584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.822173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:43:32.17863Z","caller":"traceutil/trace.go:171","msg":"trace[1248045625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2070; }","duration":"127.908707ms","start":"2026-08-24T20:43:32.05071Z","end":"2026-08-24T20:43:32.178619Z","steps":["trace[1248045625] 'agreement among raft nodes before linearized reading' (duration: 127.801363ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:32.919762Z","caller":"traceutil/trace.go:171","msg":"trace[756671873] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"241.966105ms","start":"2026-08-24T20:43:32.677769Z","end":"2026-08-24T20:43:32.919736Z","steps":["trace[756671873] 'read index received' (duration: 151.020129ms)","trace[756671873] 'applied index is now lower than readState.Index' (duration: 90.945016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:43:32.91997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.187094ms","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-24T20:43:32.92001Z","caller":"traceutil/trace.go:171","msg":"trace[228375329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2073; }","duration":"242.281818ms","start":"2026-08-24T20:43:32.677716Z","end":"2026-08-24T20:43:32.919998Z","steps":["trace[228375329] 'agreement among raft nodes before linearized reading' (duration: 242.114831ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:32.920154Z","caller":"traceutil/trace.go:171","msg":"trace[681914411] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"424.66144ms","start":"2026-08-24T20:43:32.495456Z","end":"2026-08-24T20:43:32.920117Z","steps":["trace[681914411] 'process raft request' (duration: 333.437784ms)","trace[681914411] 'compare' (duration: 90.693355ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:43:32.920333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:32.495441Z","time spent":"424.812996ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:43:33.062748Z","caller":"traceutil/trace.go:171","msg":"trace[1286352609] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"138.430087ms","start":"2026-08-24T20:43:32.924271Z","end":"2026-08-24T20:43:33.062702Z","steps":["trace[1286352609] 'process raft request' (duration: 130.602726ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:33.758104Z","caller":"traceutil/trace.go:171","msg":"trace[1691124192] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"293.966837ms","start":"2026-08-24T20:43:33.464034Z","end":"2026-08-24T20:43:33.758001Z","steps":["trace[1691124192] 'process raft request' (duration: 288.808026ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:34.508291Z","caller":"traceutil/trace.go:171","msg":"trace[2002387532] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2163; }","duration":"432.433325ms","start":"2026-08-24T20:43:34.075833Z","end":"2026-08-24T20:43:34.508266Z","steps":["trace[2002387532] 'read index received' (duration: 432.278509ms)","trace[2002387532] 'applied index is now lower than readState.Index' (duration: 153.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:34.508433Z","caller":"traceutil/trace.go:171","msg":"trace[1220911626] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"432.895454ms","start":"2026-08-24T20:43:34.075488Z","end":"2026-08-24T20:43:34.508383Z","steps":["trace[1220911626] 'process raft request' (duration: 432.615673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:34.508473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.649783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T20:43:34.508698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:34.075467Z","time spent":"433.141274ms","remote":"127.0.0.1:55878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:43:34.508722Z","caller":"traceutil/trace.go:171","msg":"trace[925623948] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2080; }","duration":"432.919094ms","start":"2026-08-24T20:43:34.075788Z","end":"2026-08-24T20:43:34.508707Z","steps":["trace[925623948] 'agreement among raft nodes before linearized reading' (duration: 432.595241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:34.508774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:34.075134Z","time spent":"433.626384ms","remote":"127.0.0.1:55894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-08-24T20:43:34.54936Z","caller":"traceutil/trace.go:171","msg":"trace[2139611905] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"471.762207ms","start":"2026-08-24T20:43:34.077558Z","end":"2026-08-24T20:43:34.549321Z","steps":["trace[2139611905] 'process raft request' (duration: 471.595311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:34.549392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.540874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:43:34.549456Z","caller":"traceutil/trace.go:171","msg":"trace[966378780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2081; }","duration":"366.642047ms","start":"2026-08-24T20:43:34.182799Z","end":"2026-08-24T20:43:34.549441Z","steps":["trace[966378780] 'agreement among raft nodes before linearized reading' (duration: 366.45457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:34.54945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.013341ms","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-24T20:43:34.549487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:34.182777Z","time spent":"366.70366ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T20:43:34.549504Z","caller":"traceutil/trace.go:171","msg":"trace[1917887040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2081; }","duration":"366.113326ms","start":"2026-08-24T20:43:34.183378Z","end":"2026-08-24T20:43:34.549491Z","steps":["trace[1917887040] 'agreement among raft nodes before linearized reading' (duration: 365.932079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:34.549455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.096905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T20:43:34.549532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:34.183359Z","time spent":"366.167959ms","remote":"127.0.0.1:56050","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-24T20:43:34.549542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:34.077535Z","time spent":"471.912593ms","remote":"127.0.0.1:56050","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-24T20:43:34.549546Z","caller":"traceutil/trace.go:171","msg":"trace[114480900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2081; }","duration":"366.227891ms","start":"2026-08-24T20:43:34.183303Z","end":"2026-08-24T20:43:34.549531Z","steps":["trace[114480900] 'agreement among raft nodes before linearized reading' (duration: 366.048334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:34.549794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:43:34.183281Z","time spent":"366.500142ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T20:43:34.839706Z","caller":"traceutil/trace.go:171","msg":"trace[289890945] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"286.236623ms","start":"2026-08-24T20:43:34.553443Z","end":"2026-08-24T20:43:34.83968Z","steps":["trace[289890945] 'process raft request' (duration: 285.722262ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:34.842023Z","caller":"traceutil/trace.go:171","msg":"trace[1445840867] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"288.299438ms","start":"2026-08-24T20:43:34.553706Z","end":"2026-08-24T20:43:34.842005Z","steps":["trace[1445840867] 'process raft request' (duration: 288.187263ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:34.842271Z","caller":"traceutil/trace.go:171","msg":"trace[2112446977] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"288.427972ms","start":"2026-08-24T20:43:34.553836Z","end":"2026-08-24T20:43:34.842264Z","steps":["trace[2112446977] 'process raft request' (duration: 288.146391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:27.882822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121179792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:44:28.261247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.559883ms","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-24T20:44:28.261341Z","caller":"traceutil/trace.go:171","msg":"trace[497649159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2370; }","duration":"919.696777ms","start":"2026-08-24T20:44:27.341604Z","end":"2026-08-24T20:44:28.261301Z","steps":["trace[497649159] 'range keys from in-memory index tree' (duration: 919.380848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.261378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.341558Z","time spent":"919.809181ms","remote":"127.0.0.1:56050","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-24T20:44:28.261575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024756168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:44:28.261635Z","caller":"traceutil/trace.go:171","msg":"trace[762543682] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2370; }","duration":"1.024827311s","start":"2026-08-24T20:44:27.236775Z","end":"2026-08-24T20:44:28.261602Z","steps":["trace[762543682] 'count revisions from in-memory index tree' (duration: 1.024676065s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.261663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.236726Z","time spent":"1.024928393s","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:44:28.261256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.747339Z","time spent":"513.912047ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-24T20:44:28.264747Z","caller":"traceutil/trace.go:171","msg":"trace[1575581893] linearizableReadLoop","detail":"{readStateIndex:2467; appliedIndex:2465; }","duration":"882.045597ms","start":"2026-08-24T20:44:27.38268Z","end":"2026-08-24T20:44:28.264725Z","steps":["trace[1575581893] 'read index received' (duration: 818.210265ms)","trace[1575581893] 'applied index is now lower than readState.Index' (duration: 63.834402ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:44:28.264845Z","caller":"traceutil/trace.go:171","msg":"trace[1822766724] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"299.06799ms","start":"2026-08-24T20:44:27.96576Z","end":"2026-08-24T20:44:28.264828Z","steps":["trace[1822766724] 'process raft request' (duration: 298.872124ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:44:28.264865Z","caller":"traceutil/trace.go:171","msg":"trace[1334220966] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"298.43826ms","start":"2026-08-24T20:44:27.966415Z","end":"2026-08-24T20:44:28.264854Z","steps":["trace[1334220966] 'process raft request' (duration: 298.267784ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:44:28.264971Z","caller":"traceutil/trace.go:171","msg":"trace[1024142551] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"610.344893ms","start":"2026-08-24T20:44:27.654618Z","end":"2026-08-24T20:44:28.264963Z","steps":["trace[1024142551] 'process raft request' (duration: 609.878409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.265037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.654567Z","time spent":"610.422536ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:44:28.26512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.776395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:44:28.265153Z","caller":"traceutil/trace.go:171","msg":"trace[118022222] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2373; }","duration":"877.842077ms","start":"2026-08-24T20:44:27.387301Z","end":"2026-08-24T20:44:28.265143Z","steps":["trace[118022222] 'agreement among raft nodes before linearized reading' (duration: 877.792336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.265178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.387283Z","time spent":"877.887079ms","remote":"127.0.0.1:47530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:44:28.265308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.627004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:44:28.265335Z","caller":"traceutil/trace.go:171","msg":"trace[1686141495] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2373; }","duration":"882.682716ms","start":"2026-08-24T20:44:27.382645Z","end":"2026-08-24T20:44:28.265328Z","steps":["trace[1686141495] 'agreement among raft nodes before linearized reading' (duration: 882.641885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.265357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.382627Z","time spent":"882.723216ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:44:28.26544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.451147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:44:28.265482Z","caller":"traceutil/trace.go:171","msg":"trace[958794627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2373; }","duration":"541.512089ms","start":"2026-08-24T20:44:27.723957Z","end":"2026-08-24T20:44:28.265469Z","steps":["trace[958794627] 'agreement among raft nodes before linearized reading' (duration: 541.430487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.265513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.018369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-08-24T20:44:28.265549Z","caller":"traceutil/trace.go:171","msg":"trace[1199703024] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2373; }","duration":"836.093491ms","start":"2026-08-24T20:44:27.429445Z","end":"2026-08-24T20:44:28.265539Z","steps":["trace[1199703024] 'agreement among raft nodes before linearized reading' (duration: 835.991368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.265578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.429424Z","time spent":"836.145873ms","remote":"127.0.0.1:52868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5450,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-24T20:44:28.265579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.606829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:44:28.265609Z","caller":"traceutil/trace.go:171","msg":"trace[2081058266] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2373; }","duration":"482.67112ms","start":"2026-08-24T20:44:27.78293Z","end":"2026-08-24T20:44:28.265601Z","steps":["trace[2081058266] 'agreement among raft nodes before linearized reading' (duration: 482.607688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:44:28.26565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.782924Z","time spent":"482.717782ms","remote":"127.0.0.1:55996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:44:28.265516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:44:27.723862Z","time spent":"541.644353ms","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:45:16.656047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.524471ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:45:16.656187Z","caller":"traceutil/trace.go:171","msg":"trace[553741542] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2732; }","duration":"126.686125ms","start":"2026-08-24T20:45:16.529482Z","end":"2026-08-24T20:45:16.656168Z","steps":["trace[553741542] 'range keys from in-memory index tree' (duration: 126.498131ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:16.751476Z","caller":"traceutil/trace.go:171","msg":"trace[4572484] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"293.948066ms","start":"2026-08-24T20:45:16.457505Z","end":"2026-08-24T20:45:16.751453Z","steps":["trace[4572484] 'process raft request' (duration: 293.767062ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:16.755577Z","caller":"traceutil/trace.go:171","msg":"trace[1732248112] linearizableReadLoop","detail":"{readStateIndex:2842; appliedIndex:2839; }","duration":"237.439984ms","start":"2026-08-24T20:45:16.518116Z","end":"2026-08-24T20:45:16.755556Z","steps":["trace[1732248112] 'read index received' (duration: 233.17992ms)","trace[1732248112] 'applied index is now lower than readState.Index' (duration: 4.258754ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:45:16.755607Z","caller":"traceutil/trace.go:171","msg":"trace[1618363923] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"298.041016ms","start":"2026-08-24T20:45:16.457538Z","end":"2026-08-24T20:45:16.755579Z","steps":["trace[1618363923] 'process raft request' (duration: 297.847321ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:16.755643Z","caller":"traceutil/trace.go:171","msg":"trace[1913311349] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"284.890386ms","start":"2026-08-24T20:45:16.470719Z","end":"2026-08-24T20:45:16.755609Z","steps":["trace[1913311349] 'process raft request' (duration: 284.769793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:45:16.755827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.946608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:45:16.755902Z","caller":"traceutil/trace.go:171","msg":"trace[1280340747] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2735; }","duration":"147.133752ms","start":"2026-08-24T20:45:16.608734Z","end":"2026-08-24T20:45:16.755868Z","steps":["trace[1280340747] 'agreement among raft nodes before linearized reading' (duration: 146.952508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:45:16.756161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.986917ms","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-24T20:45:16.756304Z","caller":"traceutil/trace.go:171","msg":"trace[680304278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2735; }","duration":"238.205802ms","start":"2026-08-24T20:45:16.518078Z","end":"2026-08-24T20:45:16.756284Z","steps":["trace[680304278] 'agreement among raft nodes before linearized reading' (duration: 237.655129ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:07.830471Z","caller":"traceutil/trace.go:171","msg":"trace[1495864464] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"111.848811ms","start":"2026-08-24T20:49:07.718601Z","end":"2026-08-24T20:49:07.830449Z","steps":["trace[1495864464] 'process raft request' (duration: 111.155452ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:07.837722Z","caller":"traceutil/trace.go:171","msg":"trace[1923625102] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"114.09943ms","start":"2026-08-24T20:49:07.723602Z","end":"2026-08-24T20:49:07.837702Z","steps":["trace[1923625102] 'process raft request' (duration: 113.961368ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:12.859338Z","caller":"traceutil/trace.go:171","msg":"trace[1024824765] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"176.576731ms","start":"2026-08-24T20:49:12.682596Z","end":"2026-08-24T20:49:12.859173Z","steps":["trace[1024824765] 'process raft request' (duration: 175.710239ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:12.859338Z","caller":"traceutil/trace.go:171","msg":"trace[137064316] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"176.608471ms","start":"2026-08-24T20:49:12.682702Z","end":"2026-08-24T20:49:12.85931Z","steps":["trace[137064316] 'process raft request' (duration: 176.266266ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:12.859769Z","caller":"traceutil/trace.go:171","msg":"trace[1834894760] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"176.861214ms","start":"2026-08-24T20:49:12.682894Z","end":"2026-08-24T20:49:12.859755Z","steps":["trace[1834894760] 'process raft request' (duration: 176.138104ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.102252Z","caller":"traceutil/trace.go:171","msg":"trace[1417313597] linearizableReadLoop","detail":"{readStateIndex:4937; appliedIndex:4936; }","duration":"238.381881ms","start":"2026-08-24T20:49:12.863839Z","end":"2026-08-24T20:49:13.102221Z","steps":["trace[1417313597] 'read index received' (duration: 173.016044ms)","trace[1417313597] 'applied index is now lower than readState.Index' (duration: 65.364487ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:49:13.102319Z","caller":"traceutil/trace.go:171","msg":"trace[697555166] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"238.921588ms","start":"2026-08-24T20:49:12.863372Z","end":"2026-08-24T20:49:13.102294Z","steps":["trace[697555166] 'process raft request' (duration: 173.46679ms)","trace[697555166] 'compare' (duration: 65.261755ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:49:13.102578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.717915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-24T20:49:13.102642Z","caller":"traceutil/trace.go:171","msg":"trace[1733226515] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4754; }","duration":"238.804806ms","start":"2026-08-24T20:49:12.863822Z","end":"2026-08-24T20:49:13.102626Z","steps":["trace[1733226515] 'agreement among raft nodes before linearized reading' (duration: 238.556473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:49:13.121102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.047202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-75afa676-5559-4c8f-b104-1f23223b4278\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-24T20:49:13.12119Z","caller":"traceutil/trace.go:171","msg":"trace[694342599] range","detail":"{range_begin:/registry/persistentvolumes/pvc-75afa676-5559-4c8f-b104-1f23223b4278; range_end:; response_count:1; response_revision:4757; }","duration":"257.184025ms","start":"2026-08-24T20:49:12.863983Z","end":"2026-08-24T20:49:13.121167Z","steps":["trace[694342599] 'agreement among raft nodes before linearized reading' (duration: 256.923011ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.121642Z","caller":"traceutil/trace.go:171","msg":"trace[1715759572] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"256.516565ms","start":"2026-08-24T20:49:12.865113Z","end":"2026-08-24T20:49:13.121629Z","steps":["trace[1715759572] 'process raft request' (duration: 255.512263ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.123034Z","caller":"traceutil/trace.go:171","msg":"trace[394570728] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"257.406657ms","start":"2026-08-24T20:49:12.865604Z","end":"2026-08-24T20:49:13.123011Z","steps":["trace[394570728] 'process raft request' (duration: 255.135067ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.123309Z","caller":"traceutil/trace.go:171","msg":"trace[873811812] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"252.368581ms","start":"2026-08-24T20:49:12.870926Z","end":"2026-08-24T20:49:13.123294Z","steps":["trace[873811812] 'process raft request' (duration: 249.866859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:49:13.124603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.456846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-24T20:49:13.124658Z","caller":"traceutil/trace.go:171","msg":"trace[807137656] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4757; }","duration":"260.542747ms","start":"2026-08-24T20:49:12.864102Z","end":"2026-08-24T20:49:13.124645Z","steps":["trace[807137656] 'agreement among raft nodes before linearized reading' (duration: 259.042738ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.40504Z","caller":"traceutil/trace.go:171","msg":"trace[1266056339] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4948; }","duration":"144.720494ms","start":"2026-08-24T20:49:13.260298Z","end":"2026-08-24T20:49:13.405019Z","steps":["trace[1266056339] 'read index received' (duration: 54.656057ms)","trace[1266056339] 'applied index is now lower than readState.Index' (duration: 90.063337ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:49:13.405125Z","caller":"traceutil/trace.go:171","msg":"trace[662954339] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"136.751071ms","start":"2026-08-24T20:49:13.268366Z","end":"2026-08-24T20:49:13.405117Z","steps":["trace[662954339] 'process raft request' (duration: 136.593439ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.405079Z","caller":"traceutil/trace.go:171","msg":"trace[1409627520] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"145.455034ms","start":"2026-08-24T20:49:13.259608Z","end":"2026-08-24T20:49:13.405063Z","steps":["trace[1409627520] 'process raft request' (duration: 142.143551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:49:13.405353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.022888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-24T20:49:13.405444Z","caller":"traceutil/trace.go:171","msg":"trace[1913561793] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4768; }","duration":"145.145631ms","start":"2026-08-24T20:49:13.260277Z","end":"2026-08-24T20:49:13.405422Z","steps":["trace[1913561793] 'agreement among raft nodes before linearized reading' (duration: 144.854386ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:13.556957Z","caller":"traceutil/trace.go:171","msg":"trace[1657228311] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4952; }","duration":"122.322724ms","start":"2026-08-24T20:49:13.434606Z","end":"2026-08-24T20:49:13.556928Z","steps":["trace[1657228311] 'read index received' (duration: 70.940808ms)","trace[1657228311] 'applied index is now lower than readState.Index' (duration: 51.380606ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:49:13.557026Z","caller":"traceutil/trace.go:171","msg":"trace[65132903] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"123.473029ms","start":"2026-08-24T20:49:13.43353Z","end":"2026-08-24T20:49:13.557003Z","steps":["trace[65132903] 'process raft request' (duration: 72.055913ms)","trace[65132903] 'compare' (duration: 51.215994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:49:13.557274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.477564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-24T20:49:13.557399Z","caller":"traceutil/trace.go:171","msg":"trace[1443342271] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4770; }","duration":"121.611316ms","start":"2026-08-24T20:49:13.435764Z","end":"2026-08-24T20:49:13.557375Z","steps":["trace[1443342271] 'agreement among raft nodes before linearized reading' (duration: 121.339083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:49:13.557315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.67697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-24T20:49:13.557648Z","caller":"traceutil/trace.go:171","msg":"trace[1007792968] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4770; }","duration":"123.030374ms","start":"2026-08-24T20:49:13.434591Z","end":"2026-08-24T20:49:13.557621Z","steps":["trace[1007792968] 'agreement among raft nodes before linearized reading' (duration: 122.481447ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:15.998149Z","caller":"traceutil/trace.go:171","msg":"trace[1256078470] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"147.350795ms","start":"2026-08-24T20:49:15.850769Z","end":"2026-08-24T20:49:15.99812Z","steps":["trace[1256078470] 'process raft request' (duration: 145.520801ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:25.959286Z","caller":"traceutil/trace.go:171","msg":"trace[273616123] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"115.206421ms","start":"2026-08-24T20:49:25.844034Z","end":"2026-08-24T20:49:25.959241Z","steps":["trace[273616123] 'process raft request' (duration: 84.62055ms)","trace[273616123] 'compare' (duration: 30.158635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:49:26.24264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.754827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:49:26.242912Z","caller":"traceutil/trace.go:171","msg":"trace[877617250] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"139.921784ms","start":"2026-08-24T20:49:26.102966Z","end":"2026-08-24T20:49:26.242888Z","steps":["trace[877617250] 'process raft request' (duration: 139.843843ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:26.243095Z","caller":"traceutil/trace.go:171","msg":"trace[801151624] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"193.189013ms","start":"2026-08-24T20:49:26.049754Z","end":"2026-08-24T20:49:26.242943Z","steps":["trace[801151624] 'process raft request' (duration: 73.017181ms)","trace[801151624] 'compare' (duration: 119.593095ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:49:26.243246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.514926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:49:26.243357Z","caller":"traceutil/trace.go:171","msg":"trace[1117463972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4981; }","duration":"189.676658ms","start":"2026-08-24T20:49:26.053666Z","end":"2026-08-24T20:49:26.243342Z","steps":["trace[1117463972] 'agreement among raft nodes before linearized reading' (duration: 189.518246ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:26.2431Z","caller":"traceutil/trace.go:171","msg":"trace[1473817765] linearizableReadLoop","detail":"{readStateIndex:5168; appliedIndex:5167; }","duration":"189.354624ms","start":"2026-08-24T20:49:26.053713Z","end":"2026-08-24T20:49:26.243068Z","steps":["trace[1473817765] 'read index received' (duration: 69.07002ms)","trace[1473817765] 'applied index is now lower than readState.Index' (duration: 120.282614ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:49:26.243582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.408583ms","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-24T20:49:26.243699Z","caller":"traceutil/trace.go:171","msg":"trace[1995992831] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4981; }","duration":"105.577786ms","start":"2026-08-24T20:49:26.138105Z","end":"2026-08-24T20:49:26.243683Z","steps":["trace[1995992831] 'agreement among raft nodes before linearized reading' (duration: 105.397093ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:50:38.988297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-08-24T20:50:39.089047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"99.268494ms","hash":357094071,"current-db-size-bytes":52944896,"current-db-size":"53 MB","current-db-size-in-use-bytes":29073408,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-24T20:50:39.089156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":357094071,"revision":2855,"compact-revision":-1} {"level":"warn","ts":"2026-08-24T20:51:11.892064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121188063,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:51:12.276493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088415305s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T20:51:12.276863Z","caller":"traceutil/trace.go:171","msg":"trace[1972306331] linearizableReadLoop","detail":"{readStateIndex:6003; appliedIndex:6002; }","duration":"885.650544ms","start":"2026-08-24T20:51:11.39119Z","end":"2026-08-24T20:51:12.27684Z","steps":["trace[1972306331] 'read index received' (duration: 885.590943ms)","trace[1972306331] 'applied index is now lower than readState.Index' (duration: 58.631µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:51:12.277046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.836046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-08-24T20:51:12.277115Z","caller":"traceutil/trace.go:171","msg":"trace[778831675] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5791; }","duration":"885.968558ms","start":"2026-08-24T20:51:11.391138Z","end":"2026-08-24T20:51:12.277107Z","steps":["trace[778831675] 'agreement among raft nodes before linearized reading' (duration: 885.798966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:51:12.277141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:51:11.391112Z","time spent":"886.022488ms","remote":"127.0.0.1:55950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"info","ts":"2026-08-24T20:51:12.277272Z","caller":"traceutil/trace.go:171","msg":"trace[1431745683] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"1.089020292s","start":"2026-08-24T20:51:11.187971Z","end":"2026-08-24T20:51:12.276992Z","steps":["trace[1431745683] 'process raft request' (duration: 1.088738789s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:51:12.277774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:51:11.187947Z","time spent":"1.08964048s","remote":"127.0.0.1:56050","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-24T20:51:12.280814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.608809ms","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":"warn","ts":"2026-08-24T20:51:12.280854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.860675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T20:51:12.280892Z","caller":"traceutil/trace.go:171","msg":"trace[2064349111] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5794; }","duration":"622.746981ms","start":"2026-08-24T20:51:11.658125Z","end":"2026-08-24T20:51:12.280872Z","steps":["trace[2064349111] 'agreement among raft nodes before linearized reading' (duration: 622.594829ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:51:12.280892Z","caller":"traceutil/trace.go:171","msg":"trace[641665411] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:1; response_revision:5794; }","duration":"311.939507ms","start":"2026-08-24T20:51:11.96894Z","end":"2026-08-24T20:51:12.28088Z","steps":["trace[641665411] 'agreement among raft nodes before linearized reading' (duration: 311.848306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:51:12.280914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.464988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-08-24T20:51:12.280943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:51:11.968915Z","time spent":"312.021757ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.84\" "} {"level":"warn","ts":"2026-08-24T20:51:12.280936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:51:11.658098Z","time spent":"622.824781ms","remote":"127.0.0.1:47482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-08-24T20:51:12.280979Z","caller":"traceutil/trace.go:171","msg":"trace[8706940] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5794; }","duration":"493.541869ms","start":"2026-08-24T20:51:11.787398Z","end":"2026-08-24T20:51:12.28094Z","steps":["trace[8706940] 'agreement among raft nodes before linearized reading' (duration: 493.412078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:51:12.281005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:51:11.787375Z","time spent":"493.62366ms","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-24T20:51:12.281171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.60499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:51:12.281222Z","caller":"traceutil/trace.go:171","msg":"trace[995321221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5794; }","duration":"227.68717ms","start":"2026-08-24T20:51:12.053503Z","end":"2026-08-24T20:51:12.281191Z","steps":["trace[995321221] 'agreement among raft nodes before linearized reading' (duration: 227.512468ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:28.830519Z","caller":"traceutil/trace.go:171","msg":"trace[1495858322] transaction","detail":"{read_only:false; response_revision:6217; number_of_response:1; }","duration":"246.829756ms","start":"2026-08-24T20:52:28.58364Z","end":"2026-08-24T20:52:28.83047Z","steps":["trace[1495858322] 'process raft request' (duration: 246.516812ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:28.830642Z","caller":"traceutil/trace.go:171","msg":"trace[1136607449] linearizableReadLoop","detail":"{readStateIndex:6447; appliedIndex:6444; }","duration":"206.45796ms","start":"2026-08-24T20:52:28.624157Z","end":"2026-08-24T20:52:28.830615Z","steps":["trace[1136607449] 'read index received' (duration: 50.555984ms)","trace[1136607449] 'applied index is now lower than readState.Index' (duration: 155.900956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:52:28.830839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.665179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T20:52:28.830867Z","caller":"traceutil/trace.go:171","msg":"trace[2488721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6218; }","duration":"206.755492ms","start":"2026-08-24T20:52:28.624104Z","end":"2026-08-24T20:52:28.83086Z","steps":["trace[2488721] 'agreement among raft nodes before linearized reading' (duration: 206.618257ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:28.830867Z","caller":"traceutil/trace.go:171","msg":"trace[823129118] transaction","detail":"{read_only:false; response_revision:6218; number_of_response:1; }","duration":"245.509789ms","start":"2026-08-24T20:52:28.585151Z","end":"2026-08-24T20:52:28.83066Z","steps":["trace[823129118] 'process raft request' (duration: 245.266769ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:28.834453Z","caller":"traceutil/trace.go:171","msg":"trace[420931054] transaction","detail":"{read_only:false; response_revision:6219; number_of_response:1; }","duration":"102.816201ms","start":"2026-08-24T20:52:28.731624Z","end":"2026-08-24T20:52:28.83444Z","steps":["trace[420931054] 'process raft request' (duration: 102.642373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:28.834478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.954677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:28.834548Z","caller":"traceutil/trace.go:171","msg":"trace[423437436] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:6219; }","duration":"196.048472ms","start":"2026-08-24T20:52:28.638483Z","end":"2026-08-24T20:52:28.834531Z","steps":["trace[423437436] 'agreement among raft nodes before linearized reading' (duration: 195.897604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:28.834561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.084605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:28.834585Z","caller":"traceutil/trace.go:171","msg":"trace[796324646] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6219; }","duration":"193.138547ms","start":"2026-08-24T20:52:28.64144Z","end":"2026-08-24T20:52:28.834579Z","steps":["trace[796324646] 'agreement among raft nodes before linearized reading' (duration: 193.092805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:51.065271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:51.566355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:52.066824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:53.13229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:53.132657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079849998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T20:52:53.132701Z","caller":"traceutil/trace.go:171","msg":"trace[878089340] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.079954072s","start":"2026-08-24T20:52:51.05273Z","end":"2026-08-24T20:52:53.132684Z","steps":["trace[878089340] 'agreement among raft nodes before linearized reading' (duration: 2.079902279s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:53.132743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.052707Z","time spent":"2.080027195s","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 20:52:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T20:52:53.63316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:54.13433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:54.635022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:55.12684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99405649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T20:52:55.126922Z","caller":"traceutil/trace.go:171","msg":"trace[1548333857] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.994172905s","start":"2026-08-24T20:52:53.13273Z","end":"2026-08-24T20:52:55.126903Z","steps":["trace[1548333857] 'agreement among raft nodes before linearized reading' (duration: 1.994077251s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.126974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:53.132718Z","time spent":"1.994240667s","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 20:52:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T20:52:55.135949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:55.637054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:55.656784Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.498726841s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T20:52:55.65715Z","caller":"traceutil/trace.go:171","msg":"trace[1315331876] transaction","detail":"{read_only:false; response_revision:6465; number_of_response:1; }","duration":"5.404199276s","start":"2026-08-24T20:52:50.252922Z","end":"2026-08-24T20:52:55.657121Z","steps":["trace[1315331876] 'process raft request' (duration: 5.40404344s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.657302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.252913Z","time spent":"5.40431313s","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6333,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:52:55.662303Z","caller":"traceutil/trace.go:171","msg":"trace[1035752531] transaction","detail":"{read_only:false; response_revision:6467; number_of_response:1; }","duration":"4.750495202s","start":"2026-08-24T20:52:50.91179Z","end":"2026-08-24T20:52:55.662285Z","steps":["trace[1035752531] 'process raft request' (duration: 4.75045279s)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:55.662354Z","caller":"traceutil/trace.go:171","msg":"trace[1087477518] transaction","detail":"{read_only:false; response_revision:6466; number_of_response:1; }","duration":"4.872996278s","start":"2026-08-24T20:52:50.78934Z","end":"2026-08-24T20:52:55.662337Z","steps":["trace[1087477518] 'process raft request' (duration: 4.872807391s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.662412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.911768Z","time spent":"4.750583015s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:52:55.662458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.789316Z","time spent":"4.873077091s","remote":"127.0.0.1:56050","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-24T20:52:55.662806Z","caller":"traceutil/trace.go:171","msg":"trace[1170607616] transaction","detail":"{read_only:false; response_revision:6468; number_of_response:1; }","duration":"4.748941497s","start":"2026-08-24T20:52:50.913854Z","end":"2026-08-24T20:52:55.662795Z","steps":["trace[1170607616] 'process raft request' (duration: 4.748409156s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.66285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.913813Z","time spent":"4.749015141s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:52:55.662847Z","caller":"traceutil/trace.go:171","msg":"trace[1896804884] linearizableReadLoop","detail":"{readStateIndex:6708; appliedIndex:6701; }","duration":"5.09804988s","start":"2026-08-24T20:52:50.564785Z","end":"2026-08-24T20:52:55.662835Z","steps":["trace[1896804884] 'read index received' (duration: 5.092140882s)","trace[1896804884] 'applied index is now lower than readState.Index' (duration: 5.908228ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:52:55.662921Z","caller":"traceutil/trace.go:171","msg":"trace[1292056342] transaction","detail":"{read_only:false; response_revision:6469; number_of_response:1; }","duration":"4.747727709s","start":"2026-08-24T20:52:50.915184Z","end":"2026-08-24T20:52:55.662912Z","steps":["trace[1292056342] 'process raft request' (duration: 4.74749065s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.662988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.915154Z","time spent":"4.747790801s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:52:55.66318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.864825465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:55.66321Z","caller":"traceutil/trace.go:171","msg":"trace[729646203] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6471; }","duration":"4.864885218s","start":"2026-08-24T20:52:50.798316Z","end":"2026-08-24T20:52:55.663202Z","steps":["trace[729646203] 'agreement among raft nodes before linearized reading' (duration: 4.864826905s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.663236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.798304Z","time spent":"4.864924939s","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-08-24T20:52:55.663351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.098573981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T20:52:55.663378Z","caller":"traceutil/trace.go:171","msg":"trace[560442042] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:6471; }","duration":"5.098648134s","start":"2026-08-24T20:52:50.564723Z","end":"2026-08-24T20:52:55.663371Z","steps":["trace[560442042] 'agreement among raft nodes before linearized reading' (duration: 5.098263649s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.663399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.5647Z","time spent":"5.098693846s","remote":"127.0.0.1:55878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":642,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-24T20:52:55.663496Z","caller":"traceutil/trace.go:171","msg":"trace[785323017] transaction","detail":"{read_only:false; response_revision:6470; number_of_response:1; }","duration":"533.332147ms","start":"2026-08-24T20:52:55.130158Z","end":"2026-08-24T20:52:55.66349Z","steps":["trace[785323017] 'process raft request' (duration: 532.592568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.663505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.259079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T20:52:55.66353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:55.130124Z","time spent":"533.38621ms","remote":"127.0.0.1:55878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:52:55.663536Z","caller":"traceutil/trace.go:171","msg":"trace[111339233] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:6471; }","duration":"276.327402ms","start":"2026-08-24T20:52:55.3872Z","end":"2026-08-24T20:52:55.663527Z","steps":["trace[111339233] 'agreement among raft nodes before linearized reading' (duration: 276.237808ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:55.66364Z","caller":"traceutil/trace.go:171","msg":"trace[643756622] transaction","detail":"{read_only:false; response_revision:6471; number_of_response:1; }","duration":"503.427595ms","start":"2026-08-24T20:52:55.160204Z","end":"2026-08-24T20:52:55.663631Z","steps":["trace[643756622] 'process raft request' (duration: 502.593842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.663674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:55.160184Z","time spent":"503.471317ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:52:55.663678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.663669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:55.663766Z","caller":"traceutil/trace.go:171","msg":"trace[874279086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6471; }","duration":"528.771715ms","start":"2026-08-24T20:52:55.134985Z","end":"2026-08-24T20:52:55.663756Z","steps":["trace[874279086] 'agreement among raft nodes before linearized reading' (duration: 528.662619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.663796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:55.134976Z","time spent":"528.811526ms","remote":"127.0.0.1:48816","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-24T20:52:55.663962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.444787038s","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-24T20:52:55.663989Z","caller":"traceutil/trace.go:171","msg":"trace[223579046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6471; }","duration":"4.44485436s","start":"2026-08-24T20:52:51.219128Z","end":"2026-08-24T20:52:55.663982Z","steps":["trace[223579046] 'agreement among raft nodes before linearized reading' (duration: 4.444763446s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.21911Z","time spent":"4.444893211s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11613277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-24T20:52:55.664147Z","caller":"traceutil/trace.go:171","msg":"trace[1637228157] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:6471; }","duration":"1.116159081s","start":"2026-08-24T20:52:54.547981Z","end":"2026-08-24T20:52:55.66414Z","steps":["trace[1637228157] 'agreement among raft nodes before linearized reading' (duration: 1.116097939s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:54.547958Z","time spent":"1.116202913s","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457742071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-24T20:52:55.664307Z","caller":"traceutil/trace.go:171","msg":"trace[1900246643] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6471; }","duration":"1.457795583s","start":"2026-08-24T20:52:54.206507Z","end":"2026-08-24T20:52:55.664302Z","steps":["trace[1900246643] 'agreement among raft nodes before linearized reading' (duration: 1.457737541s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:54.206486Z","time spent":"1.457833025s","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.741968003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-24T20:52:55.664432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.69111464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-24T20:52:55.664432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.448786919s","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-24T20:52:55.664452Z","caller":"traceutil/trace.go:171","msg":"trace[1214507861] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:1; response_revision:6471; }","duration":"3.691171062s","start":"2026-08-24T20:52:51.973275Z","end":"2026-08-24T20:52:55.664446Z","steps":["trace[1214507861] 'agreement among raft nodes before linearized reading' (duration: 3.69111977s)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:55.664452Z","caller":"traceutil/trace.go:171","msg":"trace[1064390274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6471; }","duration":"4.742082219s","start":"2026-08-24T20:52:50.92235Z","end":"2026-08-24T20:52:55.664432Z","steps":["trace[1064390274] 'agreement among raft nodes before linearized reading' (duration: 4.741850869s)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:55.664471Z","caller":"traceutil/trace.go:171","msg":"trace[293462678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6471; }","duration":"4.448874493s","start":"2026-08-24T20:52:51.215586Z","end":"2026-08-24T20:52:55.664461Z","steps":["trace[293462678] 'agreement among raft nodes before linearized reading' (duration: 4.448749158s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.922337Z","time spent":"4.742157781s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.150186954s","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-24T20:52:55.664534Z","caller":"traceutil/trace.go:171","msg":"trace[1916333639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6471; }","duration":"4.150224944s","start":"2026-08-24T20:52:51.514305Z","end":"2026-08-24T20:52:55.66453Z","steps":["trace[1916333639] 'agreement among raft nodes before linearized reading' (duration: 4.150178283s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.51429Z","time spent":"4.150257596s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.747891545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:55.664641Z","caller":"traceutil/trace.go:171","msg":"trace[1999074990] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6471; }","duration":"4.747938297s","start":"2026-08-24T20:52:50.916695Z","end":"2026-08-24T20:52:55.664633Z","steps":["trace[1999074990] 'agreement among raft nodes before linearized reading' (duration: 4.747892465s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.151125641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-24T20:52:55.664663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.916687Z","time spent":"4.747969728s","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"info","ts":"2026-08-24T20:52:55.66468Z","caller":"traceutil/trace.go:171","msg":"trace[1417932686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6471; }","duration":"4.151202344s","start":"2026-08-24T20:52:51.513473Z","end":"2026-08-24T20:52:55.664675Z","steps":["trace[1417932686] 'agreement among raft nodes before linearized reading' (duration: 4.151152172s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.513447Z","time spent":"4.151248566s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.237698877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:55.664799Z","caller":"traceutil/trace.go:171","msg":"trace[854461379] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:6471; }","duration":"4.237746219s","start":"2026-08-24T20:52:51.427048Z","end":"2026-08-24T20:52:55.664794Z","steps":["trace[854461379] 'agreement among raft nodes before linearized reading' (duration: 4.237716648s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.79544832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T20:52:55.664815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.427029Z","time spent":"4.237781821s","remote":"127.0.0.1:32886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-24T20:52:55.664822Z","caller":"traceutil/trace.go:171","msg":"trace[1490856033] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6471; }","duration":"4.795507133s","start":"2026-08-24T20:52:50.869308Z","end":"2026-08-24T20:52:55.664815Z","steps":["trace[1490856033] 'agreement among raft nodes before linearized reading' (duration: 4.795468721s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:50.869289Z","time spent":"4.795550305s","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.372313393s","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-24T20:52:55.664945Z","caller":"traceutil/trace.go:171","msg":"trace[558197643] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6471; }","duration":"4.372376976s","start":"2026-08-24T20:52:51.292563Z","end":"2026-08-24T20:52:55.66494Z","steps":["trace[558197643] 'agreement among raft nodes before linearized reading' (duration: 4.372328044s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:55.664963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.29254Z","time spent":"4.372419658s","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-24T20:52:55.664504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.215564Z","time spent":"4.448930295s","remote":"127.0.0.1:56050","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-24T20:52:55.664472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:51.973258Z","time spent":"3.691208674s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.84\" "} {"level":"warn","ts":"2026-08-24T20:52:57.539219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965516227121189793,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T20:52:57.575898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277143209s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T20:52:57.576327Z","caller":"traceutil/trace.go:171","msg":"trace[1424305365] transaction","detail":"{read_only:false; response_revision:6480; number_of_response:1; }","duration":"1.277647459s","start":"2026-08-24T20:52:56.298644Z","end":"2026-08-24T20:52:57.576292Z","steps":["trace[1424305365] 'process raft request' (duration: 1.277469712s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:57.576475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:56.298622Z","time spent":"1.277781514s","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:52:57.583114Z","caller":"traceutil/trace.go:171","msg":"trace[656905681] transaction","detail":"{read_only:false; response_revision:6481; number_of_response:1; }","duration":"540.223569ms","start":"2026-08-24T20:52:57.042862Z","end":"2026-08-24T20:52:57.583086Z","steps":["trace[656905681] 'process raft request' (duration: 539.99202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:57.583236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:57.042851Z","time spent":"540.325434ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4601,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:52:57.592988Z","caller":"traceutil/trace.go:171","msg":"trace[1271770125] linearizableReadLoop","detail":"{readStateIndex:6720; appliedIndex:6717; }","duration":"554.723599ms","start":"2026-08-24T20:52:57.03825Z","end":"2026-08-24T20:52:57.592974Z","steps":["trace[1271770125] 'read index received' (duration: 537.886396ms)","trace[1271770125] 'applied index is now lower than readState.Index' (duration: 16.836253ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:52:57.593226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.852354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:52:57.593258Z","caller":"traceutil/trace.go:171","msg":"trace[1881056611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6481; }","duration":"540.923997ms","start":"2026-08-24T20:52:57.052325Z","end":"2026-08-24T20:52:57.593249Z","steps":["trace[1881056611] 'agreement among raft nodes before linearized reading' (duration: 540.869525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:57.593227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.950708ms","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-24T20:52:57.59328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:57.052304Z","time spent":"540.971399ms","remote":"127.0.0.1:48816","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-24T20:52:57.593279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.878342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T20:52:57.593287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.513081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T20:52:57.593319Z","caller":"traceutil/trace.go:171","msg":"trace[1400956842] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:6481; }","duration":"555.079963ms","start":"2026-08-24T20:52:57.038217Z","end":"2026-08-24T20:52:57.593297Z","steps":["trace[1400956842] 'agreement among raft nodes before linearized reading' (duration: 554.916417ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:57.59334Z","caller":"traceutil/trace.go:171","msg":"trace[394272970] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:6481; }","duration":"374.994987ms","start":"2026-08-24T20:52:57.21833Z","end":"2026-08-24T20:52:57.593325Z","steps":["trace[394272970] 'agreement among raft nodes before linearized reading' (duration: 374.860732ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:52:57.593346Z","caller":"traceutil/trace.go:171","msg":"trace[543562091] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:6481; }","duration":"554.600395ms","start":"2026-08-24T20:52:57.038732Z","end":"2026-08-24T20:52:57.593333Z","steps":["trace[543562091] 'agreement among raft nodes before linearized reading' (duration: 554.467719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:52:57.593365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:57.038202Z","time spent":"555.151576ms","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:52:57.593375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:57.218312Z","time spent":"375.05531ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-08-24T20:52:57.593378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:52:57.038727Z","time spent":"554.644266ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-08-24T20:53:32.074811Z","caller":"traceutil/trace.go:171","msg":"trace[759131347] transaction","detail":"{read_only:false; response_revision:6734; number_of_response:1; }","duration":"166.479281ms","start":"2026-08-24T20:53:31.908311Z","end":"2026-08-24T20:53:32.07479Z","steps":["trace[759131347] 'process raft request' (duration: 166.312175ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:53:32.092194Z","caller":"traceutil/trace.go:171","msg":"trace[710400647] transaction","detail":"{read_only:false; response_revision:6736; number_of_response:1; }","duration":"132.761264ms","start":"2026-08-24T20:53:31.959407Z","end":"2026-08-24T20:53:32.092168Z","steps":["trace[710400647] 'process raft request' (duration: 132.6524ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:53:32.092317Z","caller":"traceutil/trace.go:171","msg":"trace[204224606] linearizableReadLoop","detail":"{readStateIndex:6986; appliedIndex:6982; }","duration":"126.182392ms","start":"2026-08-24T20:53:31.966119Z","end":"2026-08-24T20:53:32.092301Z","steps":["trace[204224606] 'read index received' (duration: 108.643386ms)","trace[204224606] 'applied index is now lower than readState.Index' (duration: 17.538046ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:53:32.092482Z","caller":"traceutil/trace.go:171","msg":"trace[1064241510] transaction","detail":"{read_only:false; response_revision:6735; number_of_response:1; }","duration":"150.977567ms","start":"2026-08-24T20:53:31.941491Z","end":"2026-08-24T20:53:32.092469Z","steps":["trace[1064241510] 'process raft request' (duration: 150.442548ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:53:32.092704Z","caller":"traceutil/trace.go:171","msg":"trace[778103318] transaction","detail":"{read_only:false; response_revision:6737; number_of_response:1; }","duration":"130.75819ms","start":"2026-08-24T20:53:31.961936Z","end":"2026-08-24T20:53:32.092694Z","steps":["trace[778103318] 'process raft request' (duration: 130.19491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:53:32.092882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.742922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T20:53:32.092919Z","caller":"traceutil/trace.go:171","msg":"trace[653850045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6737; }","duration":"126.813035ms","start":"2026-08-24T20:53:31.966096Z","end":"2026-08-24T20:53:32.092909Z","steps":["trace[653850045] 'agreement among raft nodes before linearized reading' (duration: 126.67871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:53:32.092967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.365773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T20:53:32.093014Z","caller":"traceutil/trace.go:171","msg":"trace[695434357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6737; }","duration":"121.432256ms","start":"2026-08-24T20:53:31.971571Z","end":"2026-08-24T20:53:32.093003Z","steps":["trace[695434357] 'agreement among raft nodes before linearized reading' (duration: 121.26721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:53:32.093537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.455057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T20:53:32.093577Z","caller":"traceutil/trace.go:171","msg":"trace[1344306072] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:1; response_revision:6737; }","duration":"118.520069ms","start":"2026-08-24T20:53:31.975047Z","end":"2026-08-24T20:53:32.093567Z","steps":["trace[1344306072] 'agreement among raft nodes before linearized reading' (duration: 118.435426ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:55:38.994839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5576} {"level":"info","ts":"2026-08-24T20:55:39.085923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5576,"took":"89.26256ms","hash":1191279424,"current-db-size-bytes":52944896,"current-db-size":"53 MB","current-db-size-in-use-bytes":18329600,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-24T20:55:39.085979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1191279424,"revision":5576,"compact-revision":2855} {"level":"warn","ts":"2026-08-24T20:58:27.863392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.662333ms","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":"warn","ts":"2026-08-24T20:58:27.863496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.65474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T20:58:27.863534Z","caller":"traceutil/trace.go:171","msg":"trace[1630997034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8256; }","duration":"129.701891ms","start":"2026-08-24T20:58:27.733817Z","end":"2026-08-24T20:58:27.863519Z","steps":["trace[1630997034] 'range keys from in-memory index tree' (duration: 129.565488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:27.863529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.849973ms","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-24T20:58:27.86359Z","caller":"traceutil/trace.go:171","msg":"trace[1963018721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8256; }","duration":"128.930335ms","start":"2026-08-24T20:58:27.734644Z","end":"2026-08-24T20:58:27.863575Z","steps":["trace[1963018721] 'range keys from in-memory index tree' (duration: 128.691269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:27.863637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.489146ms","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-24T20:58:27.863701Z","caller":"traceutil/trace.go:171","msg":"trace[1362947097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8256; }","duration":"134.595749ms","start":"2026-08-24T20:58:27.72909Z","end":"2026-08-24T20:58:27.863686Z","steps":["trace[1362947097] 'range keys from in-memory index tree' (duration: 134.350524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:27.86343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.580969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T20:58:27.86388Z","caller":"traceutil/trace.go:171","msg":"trace[1430287378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8256; }","duration":"130.10454ms","start":"2026-08-24T20:58:27.733768Z","end":"2026-08-24T20:58:27.863873Z","steps":["trace[1430287378] 'range keys from in-memory index tree' (duration: 129.400085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:27.864253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.450009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-24T20:58:27.864659Z","caller":"traceutil/trace.go:171","msg":"trace[835562607] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8256; }","duration":"256.585171ms","start":"2026-08-24T20:58:27.607752Z","end":"2026-08-24T20:58:27.864338Z","steps":["trace[835562607] 'range keys from in-memory index tree' (duration: 255.679721ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:58:27.863532Z","caller":"traceutil/trace.go:171","msg":"trace[506937685] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8256; }","duration":"116.01839ms","start":"2026-08-24T20:58:27.747487Z","end":"2026-08-24T20:58:27.863505Z","steps":["trace[506937685] 'count revisions from in-memory index tree' (duration: 115.564411ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:58:30.095502Z","caller":"traceutil/trace.go:171","msg":"trace[655772891] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"764.722069ms","start":"2026-08-24T20:58:29.330761Z","end":"2026-08-24T20:58:30.095483Z","steps":["trace[655772891] 'process raft request' (duration: 764.603467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.095694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:58:29.330734Z","time spent":"764.896544ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:58:30.097433Z","caller":"traceutil/trace.go:171","msg":"trace[1396629538] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"722.181397ms","start":"2026-08-24T20:58:29.375235Z","end":"2026-08-24T20:58:30.097416Z","steps":["trace[1396629538] 'process raft request' (duration: 721.85474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.097532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:58:29.375212Z","time spent":"722.284239ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:58:30.09849Z","caller":"traceutil/trace.go:171","msg":"trace[819370363] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"712.3117ms","start":"2026-08-24T20:58:29.386165Z","end":"2026-08-24T20:58:30.098476Z","steps":["trace[819370363] 'process raft request' (duration: 711.773308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.098569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:58:29.38614Z","time spent":"712.402771ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:58:30.100077Z","caller":"traceutil/trace.go:171","msg":"trace[1866482576] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"707.951705ms","start":"2026-08-24T20:58:29.392104Z","end":"2026-08-24T20:58:30.100056Z","steps":["trace[1866482576] 'process raft request' (duration: 706.38015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.10029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.877484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-24T20:58:30.100289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:58:29.392079Z","time spent":"708.080897ms","remote":"127.0.0.1:56050","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-24T20:58:30.100366Z","caller":"traceutil/trace.go:171","msg":"trace[1864991876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8265; }","duration":"226.984975ms","start":"2026-08-24T20:58:29.873368Z","end":"2026-08-24T20:58:30.100353Z","steps":["trace[1864991876] 'agreement among raft nodes before linearized reading' (duration: 226.774341ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:58:30.101245Z","caller":"traceutil/trace.go:171","msg":"trace[239878454] linearizableReadLoop","detail":"{readStateIndex:8575; appliedIndex:8571; }","duration":"226.580237ms","start":"2026-08-24T20:58:29.873407Z","end":"2026-08-24T20:58:30.099987Z","steps":["trace[239878454] 'read index received' (duration: 221.986615ms)","trace[239878454] 'applied index is now lower than readState.Index' (duration: 4.591621ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:58:30.101657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.183731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-24T20:58:30.101672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.851301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T20:58:30.101771Z","caller":"traceutil/trace.go:171","msg":"trace[350599736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8265; }","duration":"164.002154ms","start":"2026-08-24T20:58:29.937758Z","end":"2026-08-24T20:58:30.10176Z","steps":["trace[350599736] 'agreement among raft nodes before linearized reading' (duration: 163.83787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.101827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.794643ms","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-24T20:58:30.101827Z","caller":"traceutil/trace.go:171","msg":"trace[1761674021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8265; }","duration":"228.255924ms","start":"2026-08-24T20:58:29.873448Z","end":"2026-08-24T20:58:30.101703Z","steps":["trace[1761674021] 'agreement among raft nodes before linearized reading' (duration: 228.134361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.101823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.711598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T20:58:30.101869Z","caller":"traceutil/trace.go:171","msg":"trace[1935501627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8265; }","duration":"227.837074ms","start":"2026-08-24T20:58:29.87402Z","end":"2026-08-24T20:58:30.101857Z","steps":["trace[1935501627] 'agreement among raft nodes before linearized reading' (duration: 227.696491ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:58:30.101895Z","caller":"traceutil/trace.go:171","msg":"trace[493365634] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8265; }","duration":"137.849481ms","start":"2026-08-24T20:58:29.964031Z","end":"2026-08-24T20:58:30.10188Z","steps":["trace[493365634] 'agreement among raft nodes before linearized reading' (duration: 137.637647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:58:30.102048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.524079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T20:58:30.102137Z","caller":"traceutil/trace.go:171","msg":"trace[836857145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8265; }","duration":"228.633991ms","start":"2026-08-24T20:58:29.873479Z","end":"2026-08-24T20:58:30.102113Z","steps":["trace[836857145] 'agreement among raft nodes before linearized reading' (duration: 228.360615ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:59:56.099378Z","caller":"traceutil/trace.go:171","msg":"trace[24286056] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"141.199372ms","start":"2026-08-24T20:59:55.958138Z","end":"2026-08-24T20:59:56.099337Z","steps":["trace[24286056] 'process raft request' (duration: 140.903466ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:59:56.824969Z","caller":"traceutil/trace.go:171","msg":"trace[697294859] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"205.230729ms","start":"2026-08-24T20:59:56.61972Z","end":"2026-08-24T20:59:56.824951Z","steps":["trace[697294859] 'process raft request' (duration: 204.144897ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:34.649567Z","caller":"traceutil/trace.go:171","msg":"trace[1511345935] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"121.702959ms","start":"2026-08-24T21:00:34.527825Z","end":"2026-08-24T21:00:34.649528Z","steps":["trace[1511345935] 'process raft request' (duration: 121.525906ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:34.650325Z","caller":"traceutil/trace.go:171","msg":"trace[2001093783] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"118.736512ms","start":"2026-08-24T21:00:34.531572Z","end":"2026-08-24T21:00:34.650308Z","steps":["trace[2001093783] 'process raft request' (duration: 118.573629ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:34.650316Z","caller":"traceutil/trace.go:171","msg":"trace[737023739] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"118.841014ms","start":"2026-08-24T21:00:34.531449Z","end":"2026-08-24T21:00:34.65029Z","steps":["trace[737023739] 'process raft request' (duration: 118.579649ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:34.650405Z","caller":"traceutil/trace.go:171","msg":"trace[478509927] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"118.813013ms","start":"2026-08-24T21:00:34.531571Z","end":"2026-08-24T21:00:34.650384Z","steps":["trace[478509927] 'process raft request' (duration: 118.6094ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:39.00101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7407} {"level":"info","ts":"2026-08-24T21:00:39.058555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7407,"took":"56.043257ms","hash":3319251361,"current-db-size-bytes":52944896,"current-db-size":"53 MB","current-db-size-in-use-bytes":14843904,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-08-24T21:00:39.058616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3319251361,"revision":7407,"compact-revision":5576} {"level":"info","ts":"2026-08-24T21:00:56.377802Z","caller":"traceutil/trace.go:171","msg":"trace[728565237] transaction","detail":"{read_only:false; response_revision:9002; number_of_response:1; }","duration":"237.893732ms","start":"2026-08-24T21:00:56.139878Z","end":"2026-08-24T21:00:56.377772Z","steps":["trace[728565237] 'process raft request' (duration: 237.734879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.081976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.940726ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T21:00:57.082077Z","caller":"traceutil/trace.go:171","msg":"trace[1961389097] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:9002; }","duration":"553.055988ms","start":"2026-08-24T21:00:56.529008Z","end":"2026-08-24T21:00:57.082064Z","steps":["trace[1961389097] 'range keys from in-memory index tree' (duration: 552.929485ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:57.082613Z","caller":"traceutil/trace.go:171","msg":"trace[144091793] transaction","detail":"{read_only:false; response_revision:9003; number_of_response:1; }","duration":"356.594801ms","start":"2026-08-24T21:00:56.725976Z","end":"2026-08-24T21:00:57.082571Z","steps":["trace[144091793] 'process raft request' (duration: 354.251987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.082997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.725949Z","time spent":"356.750853ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T21:00:57.120051Z","caller":"traceutil/trace.go:171","msg":"trace[1126102474] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"391.456191ms","start":"2026-08-24T21:00:56.728546Z","end":"2026-08-24T21:00:57.120002Z","steps":["trace[1126102474] 'process raft request' (duration: 391.269828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.12022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.728521Z","time spent":"391.614084ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T21:00:57.120421Z","caller":"traceutil/trace.go:171","msg":"trace[1317908966] transaction","detail":"{read_only:false; response_revision:9005; number_of_response:1; }","duration":"391.755327ms","start":"2026-08-24T21:00:56.728592Z","end":"2026-08-24T21:00:57.120348Z","steps":["trace[1317908966] 'process raft request' (duration: 391.365129ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:57.120676Z","caller":"traceutil/trace.go:171","msg":"trace[645314280] transaction","detail":"{read_only:false; response_revision:9006; number_of_response:1; }","duration":"389.105626ms","start":"2026-08-24T21:00:56.731543Z","end":"2026-08-24T21:00:57.120648Z","steps":["trace[645314280] 'process raft request' (duration: 388.894142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.120844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.731534Z","time spent":"389.223839ms","remote":"127.0.0.1:56050","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-24T21:00:57.121046Z","caller":"traceutil/trace.go:171","msg":"trace[1000175465] linearizableReadLoop","detail":"{readStateIndex:9346; appliedIndex:9342; }","duration":"381.923122ms","start":"2026-08-24T21:00:56.739095Z","end":"2026-08-24T21:00:57.121018Z","steps":["trace[1000175465] 'read index received' (duration: 341.21641ms)","trace[1000175465] 'applied index is now lower than readState.Index' (duration: 40.704812ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T21:00:57.121145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.728569Z","time spent":"391.96135ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T21:00:57.121174Z","caller":"traceutil/trace.go:171","msg":"trace[241846885] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"245.442183ms","start":"2026-08-24T21:00:56.875721Z","end":"2026-08-24T21:00:57.121163Z","steps":["trace[241846885] 'process raft request' (duration: 244.861141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.121485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.738265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T21:00:57.121555Z","caller":"traceutil/trace.go:171","msg":"trace[1205694556] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:9007; }","duration":"345.808447ms","start":"2026-08-24T21:00:56.775728Z","end":"2026-08-24T21:00:57.121536Z","steps":["trace[1205694556] 'agreement among raft nodes before linearized reading' (duration: 345.685954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.121601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.775684Z","time spent":"345.902439ms","remote":"127.0.0.1:56258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":19,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-24T21:00:57.12188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.761458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-24T21:00:57.121972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.893689ms","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-24T21:00:57.122028Z","caller":"traceutil/trace.go:171","msg":"trace[1101393958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9007; }","duration":"315.971431ms","start":"2026-08-24T21:00:56.806042Z","end":"2026-08-24T21:00:57.122013Z","steps":["trace[1101393958] 'agreement among raft nodes before linearized reading' (duration: 315.827848ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:00:57.121938Z","caller":"traceutil/trace.go:171","msg":"trace[1564091584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9007; }","duration":"382.858029ms","start":"2026-08-24T21:00:56.739066Z","end":"2026-08-24T21:00:57.121924Z","steps":["trace[1564091584] 'agreement among raft nodes before linearized reading' (duration: 382.455561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.122067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.26939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T21:00:57.122129Z","caller":"traceutil/trace.go:171","msg":"trace[440782154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9007; }","duration":"302.356493ms","start":"2026-08-24T21:00:56.819763Z","end":"2026-08-24T21:00:57.122119Z","steps":["trace[440782154] 'agreement among raft nodes before linearized reading' (duration: 301.740031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.122163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.819756Z","time spent":"302.398803ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T21:00:57.122165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.207194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T21:00:57.122242Z","caller":"traceutil/trace.go:171","msg":"trace[1440687091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9007; }","duration":"307.304546ms","start":"2026-08-24T21:00:56.81491Z","end":"2026-08-24T21:00:57.122215Z","steps":["trace[1440687091] 'agreement among raft nodes before linearized reading' (duration: 307.121113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.122259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.739059Z","time spent":"383.067123ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T21:00:57.122293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.8149Z","time spent":"307.382708ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T21:00:57.122091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.806032Z","time spent":"316.030761ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T21:00:57.121972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.461256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T21:00:57.12339Z","caller":"traceutil/trace.go:171","msg":"trace[1758327518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9007; }","duration":"311.902033ms","start":"2026-08-24T21:00:56.81146Z","end":"2026-08-24T21:00:57.123362Z","steps":["trace[1758327518] 'agreement among raft nodes before linearized reading' (duration: 310.373114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:00:57.123452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:00:56.811441Z","time spent":"311.992164ms","remote":"127.0.0.1:56050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}