{"level":"warn","ts":"2026-05-21T03:02:09.372236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:02:09.37241Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.100:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.100:2380","--initial-cluster=controller=https://162.253.55.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.100:2380","--name=controller","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T03:02:09.372521Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:02:09.372541Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-21T03:02:09.372575Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T03:02:09.373781Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"]} {"level":"info","ts":"2026-05-21T03:02:09.373916Z","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":"controller","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.100:2380"],"listen-peer-urls":["https://162.253.55.100:2380"],"advertise-client-urls":["https://162.253.55.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://162.253.55.100:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T03:02:09.379175Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.79765ms"} {"level":"info","ts":"2026-05-21T03:02:09.388533Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d716dd02ccafa30","cluster-id":"c093465db09ae4fe"} {"level":"info","ts":"2026-05-21T03:02:09.388625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T03:02:09.388645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 0"} {"level":"info","ts":"2026-05-21T03:02:09.388654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d716dd02ccafa30 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T03:02:09.388665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 1"} {"level":"info","ts":"2026-05-21T03:02:09.3887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"warn","ts":"2026-05-21T03:02:09.394209Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T03:02:09.395961Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T03:02:09.399192Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T03:02:09.403488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d716dd02ccafa30","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T03:02:09.403754Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d716dd02ccafa30","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T03:02:09.40384Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T03:02:09.403864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T03:02:09.403872Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T03:02:09.406715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"info","ts":"2026-05-21T03:02:09.406849Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c093465db09ae4fe","local-member-id":"d716dd02ccafa30","added-peer-id":"d716dd02ccafa30","added-peer-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-21T03:02:09.408Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T03:02:09.408188Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-21T03:02:09.408231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-21T03:02:09.408485Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d716dd02ccafa30","initial-advertise-peer-urls":["https://162.253.55.100:2380"],"listen-peer-urls":["https://162.253.55.100:2380"],"advertise-client-urls":["https://162.253.55.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T03:02:09.408539Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T03:02:09.589652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T03:02:09.5897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T03:02:09.589731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgPreVoteResp from d716dd02ccafa30 at term 1"} {"level":"info","ts":"2026-05-21T03:02:09.589893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became candidate at term 2"} {"level":"info","ts":"2026-05-21T03:02:09.589911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgVoteResp from d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-21T03:02:09.589997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became leader at term 2"} {"level":"info","ts":"2026-05-21T03:02:09.590076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d716dd02ccafa30 elected leader d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-21T03:02:09.590926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:02:09.593702Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d716dd02ccafa30","local-member-attributes":"{Name:controller ClientURLs:[https://162.253.55.100:2379]}","request-path":"/0/members/d716dd02ccafa30/attributes","cluster-id":"c093465db09ae4fe","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T03:02:09.593929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:02:09.593948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:02:09.596578Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T03:02:09.596641Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T03:02:09.597952Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c093465db09ae4fe","local-member-id":"d716dd02ccafa30","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:02:09.598128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:02:09.59824Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:02:09.598237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T03:02:09.597877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.100:2379"} {"level":"info","ts":"2026-05-21T03:02:46.120378Z","caller":"traceutil/trace.go:171","msg":"trace[1561084832] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"159.141704ms","start":"2026-05-21T03:02:45.961172Z","end":"2026-05-21T03:02:46.120314Z","steps":["trace[1561084832] 'process raft request' (duration: 154.002871ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:08.149805Z","caller":"traceutil/trace.go:171","msg":"trace[349544626] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"119.133477ms","start":"2026-05-21T03:03:08.030638Z","end":"2026-05-21T03:03:08.149771Z","steps":["trace[349544626] 'process raft request' (duration: 118.930972ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:15.856216Z","caller":"traceutil/trace.go:171","msg":"trace[404619714] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"197.804946ms","start":"2026-05-21T03:03:15.658386Z","end":"2026-05-21T03:03:15.856191Z","steps":["trace[404619714] 'process raft request' (duration: 197.670093ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:15.8568Z","caller":"traceutil/trace.go:171","msg":"trace[829782130] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:906; }","duration":"180.328453ms","start":"2026-05-21T03:03:15.676432Z","end":"2026-05-21T03:03:15.856761Z","steps":["trace[829782130] 'read index received' (duration: 180.312833ms)","trace[829782130] 'applied index is now lower than readState.Index' (duration: 13.33µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:15.857097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.643441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/controller\" ","response":"range_response_count:1 size:4493"} {"level":"info","ts":"2026-05-21T03:03:15.85719Z","caller":"traceutil/trace.go:171","msg":"trace[1489602809] range","detail":"{range_begin:/registry/minions/controller; range_end:; response_count:1; response_revision:876; }","duration":"180.799894ms","start":"2026-05-21T03:03:15.676379Z","end":"2026-05-21T03:03:15.857178Z","steps":["trace[1489602809] 'agreement among raft nodes before linearized reading' (duration: 180.552959ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:15.857581Z","caller":"traceutil/trace.go:171","msg":"trace[104482133] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"124.679287ms","start":"2026-05-21T03:03:15.732886Z","end":"2026-05-21T03:03:15.857565Z","steps":["trace[104482133] 'process raft request' (duration: 124.411412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:15.857881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.9577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:15.857935Z","caller":"traceutil/trace.go:171","msg":"trace[740339149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"146.063943ms","start":"2026-05-21T03:03:15.711857Z","end":"2026-05-21T03:03:15.857921Z","steps":["trace[740339149] 'agreement among raft nodes before linearized reading' (duration: 145.660644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:21.922874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342514910190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:03:22.424146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342514910190,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:03:22.855737Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.593049688s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T03:03:22.856173Z","caller":"traceutil/trace.go:171","msg":"trace[213898228] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.59362346s","start":"2026-05-21T03:03:21.262524Z","end":"2026-05-21T03:03:22.856148Z","steps":["trace[213898228] 'process raft request' (duration: 1.593469117s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:22.856825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:21.262485Z","time spent":"1.593765173s","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:23.065058Z","caller":"traceutil/trace.go:171","msg":"trace[609880880] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"905.032871ms","start":"2026-05-21T03:03:22.159977Z","end":"2026-05-21T03:03:23.06501Z","steps":["trace[609880880] 'process raft request' (duration: 837.558074ms)","trace[609880880] 'compare' (duration: 66.978807ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:23.065142Z","caller":"traceutil/trace.go:171","msg":"trace[1299467955] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"1.643348185s","start":"2026-05-21T03:03:21.421756Z","end":"2026-05-21T03:03:23.065104Z","steps":["trace[1299467955] 'read index received' (duration: 1.434222253s)","trace[1299467955] 'applied index is now lower than readState.Index' (duration: 209.122662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:23.065361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:22.159948Z","time spent":"905.275656ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:03:23.065416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643627281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-h5rs5\" ","response":"range_response_count:1 size:5685"} {"level":"info","ts":"2026-05-21T03:03:23.065473Z","caller":"traceutil/trace.go:171","msg":"trace[554825570] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-h5rs5; range_end:; response_count:1; response_revision:964; }","duration":"1.643765793s","start":"2026-05-21T03:03:21.421694Z","end":"2026-05-21T03:03:23.06546Z","steps":["trace[554825570] 'agreement among raft nodes before linearized reading' (duration: 1.643505638s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.065518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:21.421672Z","time spent":"1.643836735s","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5708,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-h5rs5\" "} {"level":"info","ts":"2026-05-21T03:03:23.065846Z","caller":"traceutil/trace.go:171","msg":"trace[1671939895] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"166.278702ms","start":"2026-05-21T03:03:22.89954Z","end":"2026-05-21T03:03:23.065819Z","steps":["trace[1671939895] 'process raft request' (duration: 165.504697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.066468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.212465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:03:23.066531Z","caller":"traceutil/trace.go:171","msg":"trace[1416281950] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:966; }","duration":"271.334767ms","start":"2026-05-21T03:03:22.795181Z","end":"2026-05-21T03:03:23.066516Z","steps":["trace[1416281950] 'agreement among raft nodes before linearized reading' (duration: 271.127033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.066569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.675536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T03:03:23.066629Z","caller":"traceutil/trace.go:171","msg":"trace[2116539621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"849.803158ms","start":"2026-05-21T03:03:22.216812Z","end":"2026-05-21T03:03:23.066615Z","steps":["trace[2116539621] 'agreement among raft nodes before linearized reading' (duration: 849.578324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.066668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:22.216789Z","time spent":"849.870119ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:03:23.066786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.62268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-21T03:03:23.06684Z","caller":"traceutil/trace.go:171","msg":"trace[575713998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"661.839384ms","start":"2026-05-21T03:03:22.404976Z","end":"2026-05-21T03:03:23.066816Z","steps":["trace[575713998] 'agreement among raft nodes before linearized reading' (duration: 661.513548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.066882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:22.404957Z","time spent":"661.905955ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:03:23.066891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609380643s","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-05-21T03:03:23.066967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.822674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.100\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T03:03:23.067082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355365426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:23.067404Z","caller":"traceutil/trace.go:171","msg":"trace[1961095882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"1.355761226s","start":"2026-05-21T03:03:21.71163Z","end":"2026-05-21T03:03:23.067391Z","steps":["trace[1961095882] 'agreement among raft nodes before linearized reading' (duration: 1.355420358s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.067478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:21.711599Z","time spent":"1.355856447s","remote":"127.0.0.1:33366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:03:23.067494Z","caller":"traceutil/trace.go:171","msg":"trace[1729285893] range","detail":"{range_begin:/registry/masterleases/162.253.55.100; range_end:; response_count:1; response_revision:966; }","duration":"208.283104ms","start":"2026-05-21T03:03:22.859093Z","end":"2026-05-21T03:03:23.067376Z","steps":["trace[1729285893] 'agreement among raft nodes before linearized reading' (duration: 207.773793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.067099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188296983s","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-05-21T03:03:23.067402Z","caller":"traceutil/trace.go:171","msg":"trace[1621948637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"1.6097158s","start":"2026-05-21T03:03:21.45746Z","end":"2026-05-21T03:03:23.067175Z","steps":["trace[1621948637] 'agreement among raft nodes before linearized reading' (duration: 1.608665249s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:23.067653Z","caller":"traceutil/trace.go:171","msg":"trace[140824608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"1.188929277s","start":"2026-05-21T03:03:21.878711Z","end":"2026-05-21T03:03:23.06764Z","steps":["trace[140824608] 'agreement among raft nodes before linearized reading' (duration: 1.18816341s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.067666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:21.457437Z","time spent":"1.610215361s","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:03:23.067689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:21.87867Z","time spent":"1.189009138s","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T03:03:23.615433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342514910205,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:03:23.740218Z","caller":"traceutil/trace.go:171","msg":"trace[1410533691] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"665.604697ms","start":"2026-05-21T03:03:23.074576Z","end":"2026-05-21T03:03:23.74018Z","steps":["trace[1410533691] 'process raft request' (duration: 572.093909ms)","trace[1410533691] 'compare' (duration: 93.270623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:23.74047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.074554Z","time spent":"665.794971ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":478,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:23.740572Z","caller":"traceutil/trace.go:171","msg":"trace[1723674801] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"665.904493ms","start":"2026-05-21T03:03:23.074633Z","end":"2026-05-21T03:03:23.740538Z","steps":["trace[1723674801] 'process raft request' (duration: 665.486854ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:23.740645Z","caller":"traceutil/trace.go:171","msg":"trace[1796482035] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"665.916273ms","start":"2026-05-21T03:03:23.074699Z","end":"2026-05-21T03:03:23.740615Z","steps":["trace[1796482035] 'process raft request' (duration: 665.74049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.740744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.07462Z","time spent":"666.026505ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:03:23.74083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.074674Z","time spent":"666.050765ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:23.740966Z","caller":"traceutil/trace.go:171","msg":"trace[410760550] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"665.294091ms","start":"2026-05-21T03:03:23.075656Z","end":"2026-05-21T03:03:23.74095Z","steps":["trace[410760550] 'process raft request' (duration: 664.917113ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:23.741027Z","caller":"traceutil/trace.go:171","msg":"trace[2144135536] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"664.35341ms","start":"2026-05-21T03:03:23.076665Z","end":"2026-05-21T03:03:23.741019Z","steps":["trace[2144135536] 'process raft request' (duration: 664.033374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.741038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.075628Z","time spent":"665.374272ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:03:23.741066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.076658Z","time spent":"664.386552ms","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:23.741174Z","caller":"traceutil/trace.go:171","msg":"trace[995885747] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"647.204571ms","start":"2026-05-21T03:03:23.093955Z","end":"2026-05-21T03:03:23.74116Z","steps":["trace[995885747] 'process raft request' (duration: 647.024478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.741273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.093938Z","time spent":"647.276562ms","remote":"127.0.0.1:33432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:03:23.78287Z","caller":"traceutil/trace.go:171","msg":"trace[414197518] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:999; }","duration":"668.19412ms","start":"2026-05-21T03:03:23.114648Z","end":"2026-05-21T03:03:23.782842Z","steps":["trace[414197518] 'read index received' (duration: 532.001881ms)","trace[414197518] 'applied index is now lower than readState.Index' (duration: 136.191229ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:23.78292Z","caller":"traceutil/trace.go:171","msg":"trace[1672103427] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"674.742404ms","start":"2026-05-21T03:03:23.108147Z","end":"2026-05-21T03:03:23.78289Z","steps":["trace[1672103427] 'process raft request' (duration: 674.535439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.783065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.399693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:03:23.7831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.108112Z","time spent":"674.871135ms","remote":"127.0.0.1:33390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:23.783133Z","caller":"traceutil/trace.go:171","msg":"trace[72485031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"668.519076ms","start":"2026-05-21T03:03:23.114599Z","end":"2026-05-21T03:03:23.783118Z","steps":["trace[72485031] 'agreement among raft nodes before linearized reading' (duration: 668.355863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:23.78318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.11458Z","time spent":"668.592828ms","remote":"127.0.0.1:33366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:03:23.985023Z","caller":"traceutil/trace.go:171","msg":"trace[1378862293] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"235.484373ms","start":"2026-05-21T03:03:23.749513Z","end":"2026-05-21T03:03:23.984998Z","steps":["trace[1378862293] 'process raft request' (duration: 235.018213ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:23.985614Z","caller":"traceutil/trace.go:171","msg":"trace[1502135234] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"234.732347ms","start":"2026-05-21T03:03:23.750872Z","end":"2026-05-21T03:03:23.985604Z","steps":["trace[1502135234] 'process raft request' (duration: 233.987032ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:23.985756Z","caller":"traceutil/trace.go:171","msg":"trace[1832019355] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"232.744398ms","start":"2026-05-21T03:03:23.752987Z","end":"2026-05-21T03:03:23.985731Z","steps":["trace[1832019355] 'process raft request' (duration: 231.963872ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:23.985071Z","caller":"traceutil/trace.go:171","msg":"trace[1309761575] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1006; }","duration":"199.172453ms","start":"2026-05-21T03:03:23.785864Z","end":"2026-05-21T03:03:23.985036Z","steps":["trace[1309761575] 'read index received' (duration: 198.780145ms)","trace[1309761575] 'applied index is now lower than readState.Index' (duration: 390.738µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:23.987557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.897678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-21T03:03:23.987719Z","caller":"traceutil/trace.go:171","msg":"trace[1996321965] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:976; }","duration":"201.850487ms","start":"2026-05-21T03:03:23.785856Z","end":"2026-05-21T03:03:23.987706Z","steps":["trace[1996321965] 'agreement among raft nodes before linearized reading' (duration: 200.849337ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:24.387428Z","caller":"traceutil/trace.go:171","msg":"trace[794277205] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"390.333092ms","start":"2026-05-21T03:03:23.997058Z","end":"2026-05-21T03:03:24.387391Z","steps":["trace[794277205] 'process raft request' (duration: 389.889223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:24.387703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:23.997044Z","time spent":"390.475544ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:03:24.766712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.912815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:24.766923Z","caller":"traceutil/trace.go:171","msg":"trace[948812177] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:977; }","duration":"195.194691ms","start":"2026-05-21T03:03:24.571686Z","end":"2026-05-21T03:03:24.766881Z","steps":["trace[948812177] 'range keys from in-memory index tree' (duration: 194.806072ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:25.066917Z","caller":"traceutil/trace.go:171","msg":"trace[1537221444] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"198.339398ms","start":"2026-05-21T03:03:24.868545Z","end":"2026-05-21T03:03:25.066884Z","steps":["trace[1537221444] 'process raft request' (duration: 198.098203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:25.46059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.552548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:03:25.460731Z","caller":"traceutil/trace.go:171","msg":"trace[1631147490] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"301.610409ms","start":"2026-05-21T03:03:25.159099Z","end":"2026-05-21T03:03:25.460709Z","steps":["trace[1631147490] 'read index received' (duration: 49.532821ms)","trace[1631147490] 'applied index is now lower than readState.Index' (duration: 252.075768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:25.460993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.612865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zj92m\" ","response":"range_response_count:1 size:4889"} {"level":"info","ts":"2026-05-21T03:03:25.46103Z","caller":"traceutil/trace.go:171","msg":"trace[1819048349] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-zj92m; range_end:; response_count:1; response_revision:979; }","duration":"281.710207ms","start":"2026-05-21T03:03:25.179311Z","end":"2026-05-21T03:03:25.461021Z","steps":["trace[1819048349] 'agreement among raft nodes before linearized reading' (duration: 281.520633ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:25.461071Z","caller":"traceutil/trace.go:171","msg":"trace[23546120] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"389.255408ms","start":"2026-05-21T03:03:25.07178Z","end":"2026-05-21T03:03:25.461036Z","steps":["trace[23546120] 'process raft request' (duration: 136.937104ms)","trace[23546120] 'compare' (duration: 251.059459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:25.461205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.080348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-zj92m\" ","response":"range_response_count:1 size:2277"} {"level":"info","ts":"2026-05-21T03:03:25.461299Z","caller":"traceutil/trace.go:171","msg":"trace[277610637] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-zj92m; range_end:; response_count:1; response_revision:979; }","duration":"302.236111ms","start":"2026-05-21T03:03:25.159036Z","end":"2026-05-21T03:03:25.461272Z","steps":["trace[277610637] 'agreement among raft nodes before linearized reading' (duration: 301.787432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:25.46129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:25.071754Z","time spent":"389.40647ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:03:25.461362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:25.158961Z","time spent":"302.392964ms","remote":"127.0.0.1:51588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":2300,"request content":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-zj92m\" "} {"level":"info","ts":"2026-05-21T03:03:25.679899Z","caller":"traceutil/trace.go:171","msg":"trace[1297113375] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"168.236196ms","start":"2026-05-21T03:03:25.511639Z","end":"2026-05-21T03:03:25.679876Z","steps":["trace[1297113375] 'read index received' (duration: 168.099753ms)","trace[1297113375] 'applied index is now lower than readState.Index' (duration: 134.683µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:25.679964Z","caller":"traceutil/trace.go:171","msg":"trace[1597646523] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"208.117621ms","start":"2026-05-21T03:03:25.471818Z","end":"2026-05-21T03:03:25.679936Z","steps":["trace[1597646523] 'process raft request' (duration: 207.920677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:25.680082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.409799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:25.680172Z","caller":"traceutil/trace.go:171","msg":"trace[294672320] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:981; }","duration":"168.617113ms","start":"2026-05-21T03:03:25.511536Z","end":"2026-05-21T03:03:25.680153Z","steps":["trace[294672320] 'agreement among raft nodes before linearized reading' (duration: 168.42988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:25.9008Z","caller":"traceutil/trace.go:171","msg":"trace[1472524529] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"117.968922ms","start":"2026-05-21T03:03:25.782797Z","end":"2026-05-21T03:03:25.900766Z","steps":["trace[1472524529] 'process raft request' (duration: 97.644552ms)","trace[1472524529] 'compare' (duration: 20.117655ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:25.901011Z","caller":"traceutil/trace.go:171","msg":"trace[1190809269] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"118.140424ms","start":"2026-05-21T03:03:25.782859Z","end":"2026-05-21T03:03:25.901Z","steps":["trace[1190809269] 'process raft request' (duration: 117.846778ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:25.901122Z","caller":"traceutil/trace.go:171","msg":"trace[1758979809] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"117.831638ms","start":"2026-05-21T03:03:25.783267Z","end":"2026-05-21T03:03:25.901099Z","steps":["trace[1758979809] 'process raft request' (duration: 117.680765ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:26.264404Z","caller":"traceutil/trace.go:171","msg":"trace[1277663485] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1023; }","duration":"137.003701ms","start":"2026-05-21T03:03:26.127361Z","end":"2026-05-21T03:03:26.264365Z","steps":["trace[1277663485] 'read index received' (duration: 136.988361ms)","trace[1277663485] 'applied index is now lower than readState.Index' (duration: 11.78µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:26.26435Z","caller":"traceutil/trace.go:171","msg":"trace[1970908080] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"149.606185ms","start":"2026-05-21T03:03:26.114675Z","end":"2026-05-21T03:03:26.264281Z","steps":["trace[1970908080] 'process raft request' (duration: 149.440412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:26.264644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.263067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zj92m\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-21T03:03:26.264706Z","caller":"traceutil/trace.go:171","msg":"trace[1552005349] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-zj92m; range_end:; response_count:1; response_revision:990; }","duration":"137.392649ms","start":"2026-05-21T03:03:26.1273Z","end":"2026-05-21T03:03:26.264692Z","steps":["trace[1552005349] 'agreement among raft nodes before linearized reading' (duration: 137.187875ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:03:26.47639Z","caller":"traceutil/trace.go:171","msg":"trace[1280531601] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"206.478496ms","start":"2026-05-21T03:03:26.269888Z","end":"2026-05-21T03:03:26.476367Z","steps":["trace[1280531601] 'read index received' (duration: 206.243372ms)","trace[1280531601] 'applied index is now lower than readState.Index' (duration: 233.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:03:26.476528Z","caller":"traceutil/trace.go:171","msg":"trace[46124269] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"335.389806ms","start":"2026-05-21T03:03:26.141091Z","end":"2026-05-21T03:03:26.476481Z","steps":["trace[46124269] 'process raft request' (duration: 335.057689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:26.476601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.693981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zj92m\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-21T03:03:26.476642Z","caller":"traceutil/trace.go:171","msg":"trace[628033295] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-zj92m; range_end:; response_count:1; response_revision:991; }","duration":"206.774814ms","start":"2026-05-21T03:03:26.269856Z","end":"2026-05-21T03:03:26.476631Z","steps":["trace[628033295] 'agreement among raft nodes before linearized reading' (duration: 206.602159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:26.476666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:26.141072Z","time spent":"335.532399ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211512,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:03:26.594938Z","caller":"traceutil/trace.go:171","msg":"trace[1742016089] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"116.453859ms","start":"2026-05-21T03:03:26.47842Z","end":"2026-05-21T03:03:26.594874Z","steps":["trace[1742016089] 'process raft request' (duration: 115.731084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.212018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342514910906,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:03:58.431054Z","caller":"traceutil/trace.go:171","msg":"trace[1612904281] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"838.955099ms","start":"2026-05-21T03:03:57.592061Z","end":"2026-05-21T03:03:58.431017Z","steps":["trace[1612904281] 'process raft request' (duration: 838.759775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.431246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:57.592031Z","time spent":"839.117711ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:03:58.459872Z","caller":"traceutil/trace.go:171","msg":"trace[805627310] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1375; }","duration":"747.853357ms","start":"2026-05-21T03:03:57.711963Z","end":"2026-05-21T03:03:58.459817Z","steps":["trace[805627310] 'read index received' (duration: 718.869699ms)","trace[805627310] 'applied index is now lower than readState.Index' (duration: 28.981498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:03:58.46014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.130612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:03:58.460197Z","caller":"traceutil/trace.go:171","msg":"trace[503420612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"748.273294ms","start":"2026-05-21T03:03:57.711907Z","end":"2026-05-21T03:03:58.46018Z","steps":["trace[503420612] 'agreement among raft nodes before linearized reading' (duration: 748.123522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.460466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.380308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:03:58.460519Z","caller":"traceutil/trace.go:171","msg":"trace[302544386] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1331; }","duration":"747.521151ms","start":"2026-05-21T03:03:57.712985Z","end":"2026-05-21T03:03:58.460506Z","steps":["trace[302544386] 'agreement among raft nodes before linearized reading' (duration: 747.317608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.460513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.966412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T03:03:58.460568Z","caller":"traceutil/trace.go:171","msg":"trace[1850684148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"339.070294ms","start":"2026-05-21T03:03:58.121485Z","end":"2026-05-21T03:03:58.460556Z","steps":["trace[1850684148] 'agreement among raft nodes before linearized reading' (duration: 338.86713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.460568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.602961ms","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":"warn","ts":"2026-05-21T03:03:58.460817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:57.711885Z","time spent":"748.919035ms","remote":"127.0.0.1:33366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:03:58.460962Z","caller":"traceutil/trace.go:171","msg":"trace[1195069655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1331; }","duration":"337.029798ms","start":"2026-05-21T03:03:58.123916Z","end":"2026-05-21T03:03:58.460946Z","steps":["trace[1195069655] 'agreement among raft nodes before linearized reading' (duration: 336.538389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.461014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:58.123906Z","time spent":"337.09535ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:03:58.460566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:57.712955Z","time spent":"747.596353ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:03:58.46061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:58.121458Z","time spent":"339.143876ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:03:58.460696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.123138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T03:03:58.461634Z","caller":"traceutil/trace.go:171","msg":"trace[1217367254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"347.145415ms","start":"2026-05-21T03:03:58.114476Z","end":"2026-05-21T03:03:58.461622Z","steps":["trace[1217367254] 'agreement among raft nodes before linearized reading' (duration: 345.991135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.461674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:58.114436Z","time spent":"347.227117ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:03:58.460726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.080928ms","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-05-21T03:03:58.46257Z","caller":"traceutil/trace.go:171","msg":"trace[1607304059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1331; }","duration":"471.908009ms","start":"2026-05-21T03:03:57.990589Z","end":"2026-05-21T03:03:58.462497Z","steps":["trace[1607304059] 'agreement among raft nodes before linearized reading' (duration: 470.003676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:03:58.463231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:03:57.990564Z","time spent":"472.642742ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T03:04:12.450525Z","caller":"traceutil/trace.go:171","msg":"trace[1849014143] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"115.645374ms","start":"2026-05-21T03:04:12.334835Z","end":"2026-05-21T03:04:12.45048Z","steps":["trace[1849014143] 'process raft request' (duration: 115.345609ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:39.830531Z","caller":"traceutil/trace.go:171","msg":"trace[1704850391] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2008; }","duration":"262.242809ms","start":"2026-05-21T03:04:39.568261Z","end":"2026-05-21T03:04:39.830504Z","steps":["trace[1704850391] 'read index received' (duration: 26.100677ms)","trace[1704850391] 'applied index is now lower than readState.Index' (duration: 236.140712ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:39.830918Z","caller":"traceutil/trace.go:171","msg":"trace[642448261] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"267.42351ms","start":"2026-05-21T03:04:39.563477Z","end":"2026-05-21T03:04:39.8309Z","steps":["trace[642448261] 'process raft request' (duration: 266.795811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:39.831195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.91653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e78acdb-a598-42e7-9fd8-2a85126e63dd\" ","response":"range_response_count:1 size:3629"} {"level":"info","ts":"2026-05-21T03:04:39.831251Z","caller":"traceutil/trace.go:171","msg":"trace[63552440] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e78acdb-a598-42e7-9fd8-2a85126e63dd; range_end:; response_count:1; response_revision:1933; }","duration":"263.028371ms","start":"2026-05-21T03:04:39.56821Z","end":"2026-05-21T03:04:39.831238Z","steps":["trace[63552440] 'agreement among raft nodes before linearized reading' (duration: 262.820438ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:39.999609Z","caller":"traceutil/trace.go:171","msg":"trace[565706682] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2010; }","duration":"168.93476ms","start":"2026-05-21T03:04:39.830644Z","end":"2026-05-21T03:04:39.999579Z","steps":["trace[565706682] 'read index received' (duration: 117.842652ms)","trace[565706682] 'applied index is now lower than readState.Index' (duration: 51.090298ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:04:39.999764Z","caller":"traceutil/trace.go:171","msg":"trace[1621664512] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"243.635218ms","start":"2026-05-21T03:04:39.756114Z","end":"2026-05-21T03:04:39.99975Z","steps":["trace[1621664512] 'process raft request' (duration: 192.014002ms)","trace[1621664512] 'compare' (duration: 51.279281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:39.999927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.122497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:04:40.000027Z","caller":"traceutil/trace.go:171","msg":"trace[224838213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1934; }","duration":"287.334721ms","start":"2026-05-21T03:04:39.712665Z","end":"2026-05-21T03:04:40Z","steps":["trace[224838213] 'agreement among raft nodes before linearized reading' (duration: 287.173988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:40.18296Z","caller":"traceutil/trace.go:171","msg":"trace[1726747261] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"340.338063ms","start":"2026-05-21T03:04:39.842586Z","end":"2026-05-21T03:04:40.182924Z","steps":["trace[1726747261] 'process raft request' (duration: 340.14079ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:40.183115Z","caller":"traceutil/trace.go:171","msg":"trace[1842359359] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"339.332348ms","start":"2026-05-21T03:04:39.843747Z","end":"2026-05-21T03:04:40.18308Z","steps":["trace[1842359359] 'process raft request' (duration: 339.088674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:40.183172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.842578Z","time spent":"340.475875ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1478,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:04:40.183351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.357335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-05-21T03:04:40.183371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.843738Z","time spent":"339.467311ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":982,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:04:40.183397Z","caller":"traceutil/trace.go:171","msg":"trace[1540706266] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"340.405005ms","start":"2026-05-21T03:04:39.842973Z","end":"2026-05-21T03:04:40.183378Z","steps":["trace[1540706266] 'process raft request' (duration: 339.821515ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:40.183425Z","caller":"traceutil/trace.go:171","msg":"trace[1375537560] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1938; }","duration":"214.509547ms","start":"2026-05-21T03:04:39.968897Z","end":"2026-05-21T03:04:40.183407Z","steps":["trace[1375537560] 'agreement among raft nodes before linearized reading' (duration: 214.234543ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:40.183441Z","caller":"traceutil/trace.go:171","msg":"trace[262918371] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"341.690314ms","start":"2026-05-21T03:04:39.841738Z","end":"2026-05-21T03:04:40.183428Z","steps":["trace[262918371] 'process raft request' (duration: 260.537948ms)","trace[262918371] 'compare' (duration: 80.222691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:40.184176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.841721Z","time spent":"342.403956ms","remote":"127.0.0.1:33520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:04:40.183513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.842939Z","time spent":"340.509917ms","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1536,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:04:40.18294Z","caller":"traceutil/trace.go:171","msg":"trace[1961452460] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2011; }","duration":"183.209218ms","start":"2026-05-21T03:04:39.9997Z","end":"2026-05-21T03:04:40.182909Z","steps":["trace[1961452460] 'read index received' (duration: 102.55965ms)","trace[1961452460] 'applied index is now lower than readState.Index' (duration: 80.648137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:04:40.183785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.090664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e78acdb-a598-42e7-9fd8-2a85126e63dd\" ","response":"range_response_count:1 size:3629"} {"level":"info","ts":"2026-05-21T03:04:40.184664Z","caller":"traceutil/trace.go:171","msg":"trace[1691335630] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e78acdb-a598-42e7-9fd8-2a85126e63dd; range_end:; response_count:1; response_revision:1938; }","duration":"347.021938ms","start":"2026-05-21T03:04:39.837627Z","end":"2026-05-21T03:04:40.184649Z","steps":["trace[1691335630] 'agreement among raft nodes before linearized reading' (duration: 346.017913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:04:40.184711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:04:39.837579Z","time spent":"347.1183ms","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3652,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e78acdb-a598-42e7-9fd8-2a85126e63dd\" "} {"level":"warn","ts":"2026-05-21T03:04:40.184804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.40611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:04:40.184863Z","caller":"traceutil/trace.go:171","msg":"trace[1866257536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1938; }","duration":"183.485811ms","start":"2026-05-21T03:04:40.001362Z","end":"2026-05-21T03:04:40.184848Z","steps":["trace[1866257536] 'agreement among raft nodes before linearized reading' (duration: 183.38965ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:04:49.682358Z","caller":"traceutil/trace.go:171","msg":"trace[1118720540] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"102.817906ms","start":"2026-05-21T03:04:49.579465Z","end":"2026-05-21T03:04:49.682283Z","steps":["trace[1118720540] 'process raft request' (duration: 102.564442ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:11.361708Z","caller":"traceutil/trace.go:171","msg":"trace[123114428] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"216.249304ms","start":"2026-05-21T03:05:11.145413Z","end":"2026-05-21T03:05:11.361663Z","steps":["trace[123114428] 'process raft request' (duration: 216.151702ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:11.36184Z","caller":"traceutil/trace.go:171","msg":"trace[866031833] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2242; }","duration":"186.297559ms","start":"2026-05-21T03:05:11.175525Z","end":"2026-05-21T03:05:11.361822Z","steps":["trace[866031833] 'read index received' (duration: 150.442678ms)","trace[866031833] 'applied index is now lower than readState.Index' (duration: 35.853851ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:05:11.361995Z","caller":"traceutil/trace.go:171","msg":"trace[1386891752] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"216.232273ms","start":"2026-05-21T03:05:11.145752Z","end":"2026-05-21T03:05:11.361984Z","steps":["trace[1386891752] 'process raft request' (duration: 215.873908ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:11.362112Z","caller":"traceutil/trace.go:171","msg":"trace[2061678728] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"216.948804ms","start":"2026-05-21T03:05:11.145144Z","end":"2026-05-21T03:05:11.362093Z","steps":["trace[2061678728] 'process raft request' (duration: 180.89119ms)","trace[2061678728] 'compare' (duration: 35.168041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:05:11.362247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.694596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-21T03:05:11.362302Z","caller":"traceutil/trace.go:171","msg":"trace[682578283] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2161; }","duration":"186.791737ms","start":"2026-05-21T03:05:11.175482Z","end":"2026-05-21T03:05:11.362274Z","steps":["trace[682578283] 'agreement among raft nodes before linearized reading' (duration: 186.689676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:11.36266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.907974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123787"} {"level":"info","ts":"2026-05-21T03:05:11.362755Z","caller":"traceutil/trace.go:171","msg":"trace[1491033419] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2161; }","duration":"186.060507ms","start":"2026-05-21T03:05:11.176675Z","end":"2026-05-21T03:05:11.362736Z","steps":["trace[1491033419] 'agreement among raft nodes before linearized reading' (duration: 185.689942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:13.476824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.826453ms","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-05-21T03:05:13.476892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.401432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T03:05:13.476957Z","caller":"traceutil/trace.go:171","msg":"trace[1083546357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2169; }","duration":"108.021126ms","start":"2026-05-21T03:05:13.368904Z","end":"2026-05-21T03:05:13.476925Z","steps":["trace[1083546357] 'range keys from in-memory index tree' (duration: 107.62613ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:13.476989Z","caller":"traceutil/trace.go:171","msg":"trace[1512957721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2169; }","duration":"106.528323ms","start":"2026-05-21T03:05:13.370438Z","end":"2026-05-21T03:05:13.476966Z","steps":["trace[1512957721] 'range keys from in-memory index tree' (duration: 106.240989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:13.476948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.057936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T03:05:13.47706Z","caller":"traceutil/trace.go:171","msg":"trace[336846363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2169; }","duration":"110.275729ms","start":"2026-05-21T03:05:13.366761Z","end":"2026-05-21T03:05:13.477037Z","steps":["trace[336846363] 'range keys from in-memory index tree' (duration: 109.776932ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:13.75128Z","caller":"traceutil/trace.go:171","msg":"trace[2082535609] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2254; }","duration":"262.716351ms","start":"2026-05-21T03:05:13.488527Z","end":"2026-05-21T03:05:13.751243Z","steps":["trace[2082535609] 'read index received' (duration: 73.719039ms)","trace[2082535609] 'applied index is now lower than readState.Index' (duration: 188.995742ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:05:13.7514Z","caller":"traceutil/trace.go:171","msg":"trace[1672033121] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"265.713405ms","start":"2026-05-21T03:05:13.485633Z","end":"2026-05-21T03:05:13.751346Z","steps":["trace[1672033121] 'process raft request' (duration: 265.258159ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:05:13.75153Z","caller":"traceutil/trace.go:171","msg":"trace[1074390217] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"264.720681ms","start":"2026-05-21T03:05:13.486792Z","end":"2026-05-21T03:05:13.751512Z","steps":["trace[1074390217] 'process raft request' (duration: 264.394456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:05:13.751805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.2636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:05:13.751889Z","caller":"traceutil/trace.go:171","msg":"trace[699584296] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2172; }","duration":"263.372571ms","start":"2026-05-21T03:05:13.488498Z","end":"2026-05-21T03:05:13.751871Z","steps":["trace[699584296] 'agreement among raft nodes before linearized reading' (duration: 263.241049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:07:06.438902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.050467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:07:06.439563Z","caller":"traceutil/trace.go:171","msg":"trace[1183307624] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"150.018205ms","start":"2026-05-21T03:07:06.289485Z","end":"2026-05-21T03:07:06.439503Z","steps":["trace[1183307624] 'process raft request' (duration: 34.918967ms)","trace[1183307624] 'compare' (duration: 113.760587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:08:53.191177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:08:52.879409Z","time spent":"311.758871ms","remote":"127.0.0.1:33390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T03:08:53.191526Z","caller":"traceutil/trace.go:171","msg":"trace[78468505] linearizableReadLoop","detail":"{readStateIndex:3567; appliedIndex:3566; }","duration":"301.676062ms","start":"2026-05-21T03:08:52.889817Z","end":"2026-05-21T03:08:53.191493Z","steps":["trace[78468505] 'read index received' (duration: 297.353965ms)","trace[78468505] 'applied index is now lower than readState.Index' (duration: 4.314837ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:08:53.191572Z","caller":"traceutil/trace.go:171","msg":"trace[980685238] transaction","detail":"{read_only:false; response_revision:3435; number_of_response:1; }","duration":"192.345169ms","start":"2026-05-21T03:08:52.999198Z","end":"2026-05-21T03:08:53.191543Z","steps":["trace[980685238] 'process raft request' (duration: 191.875809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:08:53.191732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.893236ms","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-05-21T03:08:53.191759Z","caller":"traceutil/trace.go:171","msg":"trace[511304356] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:3435; }","duration":"302.000339ms","start":"2026-05-21T03:08:52.889751Z","end":"2026-05-21T03:08:53.191751Z","steps":["trace[511304356] 'agreement among raft nodes before linearized reading' (duration: 301.901227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:08:53.191785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:08:52.88973Z","time spent":"302.04968ms","remote":"127.0.0.1:51474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:08:53.191886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.585243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-21T03:08:53.191984Z","caller":"traceutil/trace.go:171","msg":"trace[1692607300] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3435; }","duration":"199.747486ms","start":"2026-05-21T03:08:52.992214Z","end":"2026-05-21T03:08:53.191962Z","steps":["trace[1692607300] 'agreement among raft nodes before linearized reading' (duration: 199.506671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:08:53.192023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.925056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:08:53.192112Z","caller":"traceutil/trace.go:171","msg":"trace[462035918] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:3435; }","duration":"127.094219ms","start":"2026-05-21T03:08:53.064995Z","end":"2026-05-21T03:08:53.19209Z","steps":["trace[462035918] 'agreement among raft nodes before linearized reading' (duration: 126.897975ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:12:09.977494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2900} {"level":"info","ts":"2026-05-21T03:12:10.068244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2900,"took":"89.479304ms","hash":4228411768,"current-db-size-bytes":36982784,"current-db-size":"37 MB","current-db-size-in-use-bytes":12406784,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-05-21T03:12:10.068335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4228411768,"revision":2900,"compact-revision":-1} {"level":"info","ts":"2026-05-21T03:13:47.224791Z","caller":"traceutil/trace.go:171","msg":"trace[1645613530] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"105.167554ms","start":"2026-05-21T03:13:47.11959Z","end":"2026-05-21T03:13:47.224757Z","steps":["trace[1645613530] 'process raft request' (duration: 104.991622ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:17:09.984497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4823} {"level":"info","ts":"2026-05-21T03:17:10.053199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4823,"took":"67.478205ms","hash":2686076180,"current-db-size-bytes":36982784,"current-db-size":"37 MB","current-db-size-in-use-bytes":13869056,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-05-21T03:17:10.053261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2686076180,"revision":4823,"compact-revision":2900}