{"level":"warn","ts":"2026-04-01T23:25:08.311537Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:25:08.311676Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-04-01T23:25:08.311801Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:25:08.311822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-04-01T23:25:08.311841Z","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-04-01T23:25:08.312475Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-04-01T23:25:08.312569Z","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://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-04-01T23:25:08.316701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.905785ms"} {"level":"info","ts":"2026-04-01T23:25:08.326422Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-04-01T23:25:08.32682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T23:25:08.326882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-04-01T23:25:08.326971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T23:25:08.327086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-04-01T23:25:08.327191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-04-01T23:25:08.334156Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T23:25:08.3358Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T23:25:08.337398Z","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-04-01T23:25:08.339699Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T23:25:08.339855Z","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-04-01T23:25:08.339979Z","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-04-01T23:25:08.339992Z","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-04-01T23:25:08.340339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T23:25:08.34732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-04-01T23:25:08.347959Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-04-01T23:25:08.349479Z","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-04-01T23:25:08.349841Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T23:25:08.349947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T23:25:08.350135Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-04-01T23:25:08.350175Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-04-01T23:25:08.82836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T23:25:08.828785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T23:25:08.828881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-04-01T23:25:08.82894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-04-01T23:25:08.829013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-04-01T23:25:08.829178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-04-01T23:25:08.829301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-04-01T23:25:08.84468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:25:08.8468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T23:25:08.846942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:25:08.846831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:25:08.847346Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T23:25:08.847438Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T23:25:08.84859Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:25:08.849025Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:25:08.850501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:25:08.851122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-04-01T23:25:08.853048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T23:25:35.786095Z","caller":"traceutil/trace.go:171","msg":"trace[1952340543] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"770.328209ms","start":"2026-04-01T23:25:35.015723Z","end":"2026-04-01T23:25:35.786051Z","steps":["trace[1952340543] 'process raft request' (duration: 770.084281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:35.787407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:35.015701Z","time spent":"770.77026ms","remote":"127.0.0.1:55420","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-04-01T23:25:43.052304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742197,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:25:43.496411Z","caller":"traceutil/trace.go:171","msg":"trace[1469815565] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"944.860295ms","start":"2026-04-01T23:25:42.551525Z","end":"2026-04-01T23:25:43.496385Z","steps":["trace[1469815565] 'read index received' (duration: 944.642479ms)","trace[1469815565] 'applied index is now lower than readState.Index' (duration: 216.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:25:43.496483Z","caller":"traceutil/trace.go:171","msg":"trace[907329965] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"948.68832ms","start":"2026-04-01T23:25:42.547752Z","end":"2026-04-01T23:25:43.496441Z","steps":["trace[907329965] 'process raft request' (duration: 948.490015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:43.496594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.013041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:25:43.496686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:42.547734Z","time spent":"948.849585ms","remote":"127.0.0.1:55420","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-04-01T23:25:43.496721Z","caller":"traceutil/trace.go:171","msg":"trace[787505426] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:475; }","duration":"945.226396ms","start":"2026-04-01T23:25:42.551473Z","end":"2026-04-01T23:25:43.4967Z","steps":["trace[787505426] 'agreement among raft nodes before linearized reading' (duration: 945.00701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:43.49678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:42.551454Z","time spent":"945.316189ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-01T23:25:44.324554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742201,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:25:44.417174Z","caller":"traceutil/trace.go:171","msg":"trace[606172220] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"737.88153ms","start":"2026-04-01T23:25:43.679265Z","end":"2026-04-01T23:25:44.417146Z","steps":["trace[606172220] 'process raft request' (duration: 737.757167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.417516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.679246Z","time spent":"738.116236ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.448909Z","caller":"traceutil/trace.go:171","msg":"trace[944918007] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"761.55396ms","start":"2026-04-01T23:25:43.687321Z","end":"2026-04-01T23:25:44.448875Z","steps":["trace[944918007] 'process raft request' (duration: 761.136149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.449098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.687297Z","time spent":"761.699694ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.449181Z","caller":"traceutil/trace.go:171","msg":"trace[579407979] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"753.973596ms","start":"2026-04-01T23:25:43.695174Z","end":"2026-04-01T23:25:44.449148Z","steps":["trace[579407979] 'process raft request' (duration: 753.559664ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:44.449317Z","caller":"traceutil/trace.go:171","msg":"trace[73244302] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"747.568291ms","start":"2026-04-01T23:25:43.701729Z","end":"2026-04-01T23:25:44.449297Z","steps":["trace[73244302] 'process raft request' (duration: 747.169211ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:44.449477Z","caller":"traceutil/trace.go:171","msg":"trace[1605036059] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"747.666734ms","start":"2026-04-01T23:25:43.701797Z","end":"2026-04-01T23:25:44.449463Z","steps":["trace[1605036059] 'process raft request' (duration: 747.170001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.45127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.70179Z","time spent":"749.306059ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:25:44.450792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.695167Z","time spent":"754.129479ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:25:44.452545Z","caller":"traceutil/trace.go:171","msg":"trace[1879583609] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"740.796308ms","start":"2026-04-01T23:25:43.711586Z","end":"2026-04-01T23:25:44.452382Z","steps":["trace[1879583609] 'process raft request' (duration: 740.169021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.452716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.711581Z","time spent":"741.049375ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.450845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.701706Z","time spent":"747.667314ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.452869Z","caller":"traceutil/trace.go:171","msg":"trace[1092226789] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"740.823779ms","start":"2026-04-01T23:25:43.711645Z","end":"2026-04-01T23:25:44.452469Z","steps":["trace[1092226789] 'process raft request' (duration: 740.318875ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:44.448957Z","caller":"traceutil/trace.go:171","msg":"trace[1351027517] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"755.364033ms","start":"2026-04-01T23:25:43.693572Z","end":"2026-04-01T23:25:44.448936Z","steps":["trace[1351027517] 'process raft request' (duration: 755.076625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.453073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.711639Z","time spent":"741.345743ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:25:44.453514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.693561Z","time spent":"759.750862ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.454046Z","caller":"traceutil/trace.go:171","msg":"trace[1974169728] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"719.773228ms","start":"2026-04-01T23:25:43.734165Z","end":"2026-04-01T23:25:44.453938Z","steps":["trace[1974169728] 'process raft request' (duration: 717.927268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.454231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.734145Z","time spent":"719.951393ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.454292Z","caller":"traceutil/trace.go:171","msg":"trace[1002300011] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"742.044991ms","start":"2026-04-01T23:25:43.711523Z","end":"2026-04-01T23:25:44.453568Z","steps":["trace[1002300011] 'process raft request' (duration: 737.824047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.454371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.711507Z","time spent":"742.822533ms","remote":"127.0.0.1:55226","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-04-01T23:25:44.454411Z","caller":"traceutil/trace.go:171","msg":"trace[1604036076] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"698.254074ms","start":"2026-04-01T23:25:43.756105Z","end":"2026-04-01T23:25:44.454359Z","steps":["trace[1604036076] 'process raft request' (duration: 696.293652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.454595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.75608Z","time spent":"698.41027ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:25:44.455678Z","caller":"traceutil/trace.go:171","msg":"trace[1397243896] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:497; }","duration":"631.457467ms","start":"2026-04-01T23:25:43.824142Z","end":"2026-04-01T23:25:44.4556Z","steps":["trace[1397243896] 'read index received' (duration: 593.048086ms)","trace[1397243896] 'applied index is now lower than readState.Index' (duration: 38.170604ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:25:44.455818Z","caller":"traceutil/trace.go:171","msg":"trace[1263431046] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"561.626914ms","start":"2026-04-01T23:25:43.894172Z","end":"2026-04-01T23:25:44.455799Z","steps":["trace[1263431046] 'process raft request' (duration: 559.138367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.455927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.894143Z","time spent":"561.735587ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:25:44.456295Z","caller":"traceutil/trace.go:171","msg":"trace[2065015691] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"375.813601ms","start":"2026-04-01T23:25:44.080456Z","end":"2026-04-01T23:25:44.45627Z","steps":["trace[2065015691] 'process raft request' (duration: 374.779093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.45643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:44.080431Z","time spent":"375.916514ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:25:44.456876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.73243ms","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-04-01T23:25:44.456959Z","caller":"traceutil/trace.go:171","msg":"trace[2039770478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"632.846193ms","start":"2026-04-01T23:25:43.824092Z","end":"2026-04-01T23:25:44.456938Z","steps":["trace[2039770478] 'agreement among raft nodes before linearized reading' (duration: 632.626738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.457024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.824072Z","time spent":"632.928246ms","remote":"127.0.0.1:55420","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":"warn","ts":"2026-04-01T23:25:44.461894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.297041ms","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-04-01T23:25:44.461771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.987326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T23:25:44.46214Z","caller":"traceutil/trace.go:171","msg":"trace[1302088822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"525.404723ms","start":"2026-04-01T23:25:43.936529Z","end":"2026-04-01T23:25:44.461933Z","steps":["trace[1302088822] 'agreement among raft nodes before linearized reading' (duration: 524.963332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:44.462177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:43.936511Z","time spent":"525.656341ms","remote":"127.0.0.1:55420","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-04-01T23:25:44.462176Z","caller":"traceutil/trace.go:171","msg":"trace[927911035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"259.421337ms","start":"2026-04-01T23:25:44.202732Z","end":"2026-04-01T23:25:44.462154Z","steps":["trace[927911035] 'agreement among raft nodes before linearized reading' (duration: 258.77951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.008873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742432,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:25:51.307819Z","caller":"traceutil/trace.go:171","msg":"trace[2029752799] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"799.291473ms","start":"2026-04-01T23:25:50.508502Z","end":"2026-04-01T23:25:51.307793Z","steps":["trace[2029752799] 'read index received' (duration: 798.44869ms)","trace[2029752799] 'applied index is now lower than readState.Index' (duration: 841.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:25:51.307953Z","caller":"traceutil/trace.go:171","msg":"trace[1080327167] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"845.479483ms","start":"2026-04-01T23:25:50.462463Z","end":"2026-04-01T23:25:51.307943Z","steps":["trace[1080327167] 'process raft request' (duration: 844.749953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.308066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:50.46244Z","time spent":"845.543156ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:25:51.308453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.197391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:25:51.308491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.535267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k78nh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-01T23:25:51.308613Z","caller":"traceutil/trace.go:171","msg":"trace[1705932702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"785.330345ms","start":"2026-04-01T23:25:50.52321Z","end":"2026-04-01T23:25:51.30854Z","steps":["trace[1705932702] 'agreement among raft nodes before linearized reading' (duration: 784.985526ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:51.308713Z","caller":"traceutil/trace.go:171","msg":"trace[102559534] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k78nh; range_end:; response_count:1; response_revision:581; }","duration":"463.716061ms","start":"2026-04-01T23:25:50.844895Z","end":"2026-04-01T23:25:51.308611Z","steps":["trace[102559534] 'agreement among raft nodes before linearized reading' (duration: 463.334782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.308781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:50.523193Z","time spent":"785.51643ms","remote":"127.0.0.1:55420","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":"warn","ts":"2026-04-01T23:25:51.308814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:50.844875Z","time spent":"463.925588ms","remote":"127.0.0.1:55342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-k78nh\" "} {"level":"warn","ts":"2026-04-01T23:25:51.308463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.93215ms","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-04-01T23:25:51.309306Z","caller":"traceutil/trace.go:171","msg":"trace[1685120062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"800.823114ms","start":"2026-04-01T23:25:50.508459Z","end":"2026-04-01T23:25:51.309282Z","steps":["trace[1685120062] 'agreement among raft nodes before linearized reading' (duration: 799.608001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.309609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.861432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:25:51.309629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.96548ms","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-04-01T23:25:51.309694Z","caller":"traceutil/trace.go:171","msg":"trace[1504540159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"673.183447ms","start":"2026-04-01T23:25:50.636464Z","end":"2026-04-01T23:25:51.309647Z","steps":["trace[1504540159] 'agreement among raft nodes before linearized reading' (duration: 671.685006ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:51.309715Z","caller":"traceutil/trace.go:171","msg":"trace[2006478497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"787.093463ms","start":"2026-04-01T23:25:50.522593Z","end":"2026-04-01T23:25:51.309687Z","steps":["trace[2006478497] 'agreement among raft nodes before linearized reading' (duration: 785.51213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.309759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:50.522571Z","time spent":"787.179275ms","remote":"127.0.0.1:55420","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-04-01T23:25:51.309741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:50.636452Z","time spent":"673.279189ms","remote":"127.0.0.1:55186","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-04-01T23:25:51.309431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:50.508439Z","time spent":"800.976559ms","remote":"127.0.0.1:55420","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-04-01T23:25:51.731234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.421533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:25:51.731456Z","caller":"traceutil/trace.go:171","msg":"trace[856252784] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"416.323348ms","start":"2026-04-01T23:25:51.315108Z","end":"2026-04-01T23:25:51.731431Z","steps":["trace[856252784] 'process raft request' (duration: 96.340599ms)","trace[856252784] 'compare' (duration: 319.28146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:25:51.731619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:51.315087Z","time spent":"416.474441ms","remote":"127.0.0.1:55420","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-04-01T23:25:51.810031Z","caller":"traceutil/trace.go:171","msg":"trace[452622995] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"449.347332ms","start":"2026-04-01T23:25:51.360666Z","end":"2026-04-01T23:25:51.810014Z","steps":["trace[452622995] 'process raft request' (duration: 449.311772ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:51.81006Z","caller":"traceutil/trace.go:171","msg":"trace[878627605] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"494.68725ms","start":"2026-04-01T23:25:51.315339Z","end":"2026-04-01T23:25:51.810026Z","steps":["trace[878627605] 'process raft request' (duration: 494.489635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.810134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:51.360638Z","time spent":"449.445404ms","remote":"127.0.0.1:55652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:25:51.810163Z","caller":"traceutil/trace.go:171","msg":"trace[367883838] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"494.840344ms","start":"2026-04-01T23:25:51.315299Z","end":"2026-04-01T23:25:51.810139Z","steps":["trace[367883838] 'process raft request' (duration: 494.622719ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:25:51.810139Z","caller":"traceutil/trace.go:171","msg":"trace[1083119729] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"486.462968ms","start":"2026-04-01T23:25:51.323639Z","end":"2026-04-01T23:25:51.810102Z","steps":["trace[1083119729] 'process raft request' (duration: 486.306784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.81024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:51.31533Z","time spent":"494.815464ms","remote":"127.0.0.1:55420","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-04-01T23:25:51.81031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:51.315282Z","time spent":"494.934078ms","remote":"127.0.0.1:55420","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-04-01T23:25:51.810334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:51.323627Z","time spent":"486.606211ms","remote":"127.0.0.1:55342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:25:51.820578Z","caller":"traceutil/trace.go:171","msg":"trace[1234804379] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:607; }","duration":"262.887631ms","start":"2026-04-01T23:25:51.557666Z","end":"2026-04-01T23:25:51.820553Z","steps":["trace[1234804379] 'read index received' (duration: 252.196642ms)","trace[1234804379] 'applied index is now lower than readState.Index' (duration: 10.690449ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:25:51.820693Z","caller":"traceutil/trace.go:171","msg":"trace[1658646480] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"335.769856ms","start":"2026-04-01T23:25:51.4849Z","end":"2026-04-01T23:25:51.82067Z","steps":["trace[1658646480] 'process raft request' (duration: 335.582281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.820819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.479517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:25:51.820879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:51.484872Z","time spent":"335.884468ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:25:51.820896Z","caller":"traceutil/trace.go:171","msg":"trace[1448595791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"184.57948ms","start":"2026-04-01T23:25:51.63631Z","end":"2026-04-01T23:25:51.820889Z","steps":["trace[1448595791] 'agreement among raft nodes before linearized reading' (duration: 184.426086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:51.821027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.351974ms","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-04-01T23:25:51.821145Z","caller":"traceutil/trace.go:171","msg":"trace[1553516968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"263.486727ms","start":"2026-04-01T23:25:51.557616Z","end":"2026-04-01T23:25:51.821103Z","steps":["trace[1553516968] 'agreement among raft nodes before linearized reading' (duration: 263.124257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:53.138744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:25:53.15354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.021621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T23:25:53.153615Z","caller":"traceutil/trace.go:171","msg":"trace[399447916] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:588; }","duration":"986.226977ms","start":"2026-04-01T23:25:52.167364Z","end":"2026-04-01T23:25:53.153591Z","steps":["trace[399447916] 'range keys from in-memory index tree' (duration: 985.891478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:53.153682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:52.167051Z","time spent":"986.616239ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-04-01T23:25:53.639543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:25:54.140705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:25:54.343606Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.136354264s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:25:54.344752Z","caller":"traceutil/trace.go:171","msg":"trace[1973709540] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.139305524s","start":"2026-04-01T23:25:52.205422Z","end":"2026-04-01T23:25:54.344728Z","steps":["trace[1973709540] 'process raft request' (duration: 2.138666926s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:54.344897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:52.20539Z","time spent":"2.139432207s","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:25:54.347926Z","caller":"traceutil/trace.go:171","msg":"trace[624487082] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"1.710186569s","start":"2026-04-01T23:25:52.637713Z","end":"2026-04-01T23:25:54.3479Z","steps":["trace[624487082] 'read index received' (duration: 1.7061637s)","trace[624487082] 'applied index is now lower than readState.Index' (duration: 4.021169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:25:54.347975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:53.155478Z","time spent":"1.192492513s","remote":"127.0.0.1:55208","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-01T23:25:54.348115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710387215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:25:54.348171Z","caller":"traceutil/trace.go:171","msg":"trace[1206436951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.710485068s","start":"2026-04-01T23:25:52.637671Z","end":"2026-04-01T23:25:54.348156Z","steps":["trace[1206436951] 'agreement among raft nodes before linearized reading' (duration: 1.710384375s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:54.34822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:52.637651Z","time spent":"1.71055753s","remote":"127.0.0.1:55176","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-04-01T23:25:54.348298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.356459ms","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-04-01T23:25:54.348329Z","caller":"traceutil/trace.go:171","msg":"trace[833697716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"612.388471ms","start":"2026-04-01T23:25:53.735931Z","end":"2026-04-01T23:25:54.34832Z","steps":["trace[833697716] 'agreement among raft nodes before linearized reading' (duration: 612.287488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:54.348396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:53.73586Z","time spent":"612.487582ms","remote":"127.0.0.1:55420","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-04-01T23:25:54.348523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.868298ms","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-04-01T23:25:54.348548Z","caller":"traceutil/trace.go:171","msg":"trace[1472564784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"517.91078ms","start":"2026-04-01T23:25:53.830629Z","end":"2026-04-01T23:25:54.34854Z","steps":["trace[1472564784] 'agreement among raft nodes before linearized reading' (duration: 517.852978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:54.348566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:53.830619Z","time spent":"517.943341ms","remote":"127.0.0.1:55420","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-04-01T23:25:54.348679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.099839ms","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-04-01T23:25:54.348839Z","caller":"traceutil/trace.go:171","msg":"trace[1620695419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"534.306814ms","start":"2026-04-01T23:25:53.814515Z","end":"2026-04-01T23:25:54.348822Z","steps":["trace[1620695419] 'agreement among raft nodes before linearized reading' (duration: 534.059387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:54.348899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:53.814493Z","time spent":"534.391426ms","remote":"127.0.0.1:55420","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-04-01T23:25:54.349145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.032467ms","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-04-01T23:25:54.349189Z","caller":"traceutil/trace.go:171","msg":"trace[348012377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"534.093589ms","start":"2026-04-01T23:25:53.815084Z","end":"2026-04-01T23:25:54.349178Z","steps":["trace[348012377] 'agreement among raft nodes before linearized reading' (duration: 533.977086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:54.349236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:53.815075Z","time spent":"534.15042ms","remote":"127.0.0.1:55420","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-04-01T23:25:56.326497Z","caller":"traceutil/trace.go:171","msg":"trace[1223030582] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"210.747369ms","start":"2026-04-01T23:25:56.115732Z","end":"2026-04-01T23:25:56.32648Z","steps":["trace[1223030582] 'process raft request' (duration: 210.663496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:58.18382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:25:58.684685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:25:59.18486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:25:59.637626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000215416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-01T23:25:59.637732Z","caller":"traceutil/trace.go:171","msg":"trace[1781184376] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00041008s","start":"2026-04-01T23:25:57.637304Z","end":"2026-04-01T23:25:59.637714Z","steps":["trace[1781184376] 'agreement among raft nodes before linearized reading' (duration: 2.000271086s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:25:59.637782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:57.637274Z","time spent":"2.000497734s","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:25:59.685982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104742536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:26:00.157016Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.861249272s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:26:00.157452Z","caller":"traceutil/trace.go:171","msg":"trace[2099357409] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"2.861808146s","start":"2026-04-01T23:25:57.295623Z","end":"2026-04-01T23:26:00.157431Z","steps":["trace[2099357409] 'process raft request' (duration: 2.861689693s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.157587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:57.295595Z","time spent":"2.86191978s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:26:00.788344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.897055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-01T23:26:00.788701Z","caller":"traceutil/trace.go:171","msg":"trace[334642577] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"622.505594ms","start":"2026-04-01T23:26:00.166179Z","end":"2026-04-01T23:26:00.788684Z","steps":["trace[334642577] 'process raft request' (duration: 622.449742ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:00.78872Z","caller":"traceutil/trace.go:171","msg":"trace[1014118031] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"2.60462936s","start":"2026-04-01T23:25:58.184056Z","end":"2026-04-01T23:26:00.788685Z","steps":["trace[1014118031] 'process raft request' (duration: 2.604391223s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:00.788728Z","caller":"traceutil/trace.go:171","msg":"trace[363121084] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:675; }","duration":"3.459635713s","start":"2026-04-01T23:25:57.329061Z","end":"2026-04-01T23:26:00.788697Z","steps":["trace[363121084] 'read index received' (duration: 2.828229988s)","trace[363121084] 'applied index is now lower than readState.Index' (duration: 631.402505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:00.788887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.184033Z","time spent":"2.604770613s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:26:00.788788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:00.166153Z","time spent":"622.585675ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:26:00.788937Z","caller":"traceutil/trace.go:171","msg":"trace[1248721431] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.595711968s","start":"2026-04-01T23:25:58.193186Z","end":"2026-04-01T23:26:00.788897Z","steps":["trace[1248721431] 'process raft request' (duration: 2.595362078s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.274933218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:00.789077Z","caller":"traceutil/trace.go:171","msg":"trace[1765309725] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:653; }","duration":"3.27502889s","start":"2026-04-01T23:25:57.514035Z","end":"2026-04-01T23:26:00.789064Z","steps":["trace[1765309725] 'agreement among raft nodes before linearized reading' (duration: 3.274941518s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.193174Z","time spent":"2.595845622s","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:26:00.789151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.921532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-04-01T23:26:00.789158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.306974806s","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-04-01T23:26:00.789198Z","caller":"traceutil/trace.go:171","msg":"trace[1919909009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"2.307042728s","start":"2026-04-01T23:25:58.482142Z","end":"2026-04-01T23:26:00.789185Z","steps":["trace[1919909009] 'agreement among raft nodes before linearized reading' (duration: 2.306887405s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.475714767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-04-01T23:26:00.789243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.596021817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k78nh\" ","response":"range_response_count:1 size:17786"} {"level":"warn","ts":"2026-04-01T23:26:00.789235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.482125Z","time spent":"2.30710389s","remote":"127.0.0.1:55420","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-04-01T23:26:00.789274Z","caller":"traceutil/trace.go:171","msg":"trace[492618496] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k78nh; range_end:; response_count:1; response_revision:653; }","duration":"2.596075838s","start":"2026-04-01T23:25:58.19319Z","end":"2026-04-01T23:26:00.789265Z","steps":["trace[492618496] 'agreement among raft nodes before linearized reading' (duration: 2.595944194s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:00.789275Z","caller":"traceutil/trace.go:171","msg":"trace[653119410] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"2.47581157s","start":"2026-04-01T23:25:58.31345Z","end":"2026-04-01T23:26:00.789262Z","steps":["trace[653119410] 'agreement among raft nodes before linearized reading' (duration: 2.475653406s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.193176Z","time spent":"2.596118628s","remote":"127.0.0.1:55342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17808,"request content":"key:\"/registry/pods/kube-system/cilium-k78nh\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.313429Z","time spent":"2.47587433s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.967826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-01T23:26:00.789343Z","caller":"traceutil/trace.go:171","msg":"trace[440408573] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"715.992247ms","start":"2026-04-01T23:26:00.073342Z","end":"2026-04-01T23:26:00.789335Z","steps":["trace[440408573] 'agreement among raft nodes before linearized reading' (duration: 715.922745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:57.51402Z","time spent":"3.275084642s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:00.073309Z","time spent":"716.049998ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.975777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-04-01T23:26:00.789497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395067932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:26:00.789527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.604911167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-01T23:26:00.789533Z","caller":"traceutil/trace.go:171","msg":"trace[2069458596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"2.395114583s","start":"2026-04-01T23:25:58.394409Z","end":"2026-04-01T23:26:00.789524Z","steps":["trace[2069458596] 'agreement among raft nodes before linearized reading' (duration: 2.395026402s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.394404Z","time spent":"2.395154475s","remote":"127.0.0.1:55420","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-04-01T23:26:00.78957Z","caller":"traceutil/trace.go:171","msg":"trace[2113117759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"2.604961969s","start":"2026-04-01T23:25:58.184587Z","end":"2026-04-01T23:26:00.789549Z","steps":["trace[2113117759] 'agreement among raft nodes before linearized reading' (duration: 2.604868706s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.974873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13220"} {"level":"warn","ts":"2026-04-01T23:26:00.7896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.184573Z","time spent":"2.605020081s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.397995231s","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-04-01T23:26:00.789625Z","caller":"traceutil/trace.go:171","msg":"trace[1006565574] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"126.064535ms","start":"2026-04-01T23:26:00.663546Z","end":"2026-04-01T23:26:00.78961Z","steps":["trace[1006565574] 'agreement among raft nodes before linearized reading' (duration: 125.977912ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:00.789653Z","caller":"traceutil/trace.go:171","msg":"trace[1199385483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"2.398074923s","start":"2026-04-01T23:25:58.39156Z","end":"2026-04-01T23:26:00.789635Z","steps":["trace[1199385483] 'agreement among raft nodes before linearized reading' (duration: 2.397997642s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.018181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-04-01T23:26:00.789675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.391536Z","time spent":"2.398134906s","remote":"127.0.0.1:55420","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-04-01T23:26:00.789688Z","caller":"traceutil/trace.go:171","msg":"trace[957302199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"718.220147ms","start":"2026-04-01T23:26:00.071459Z","end":"2026-04-01T23:26:00.789679Z","steps":["trace[957302199] 'agreement among raft nodes before linearized reading' (duration: 717.986801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150464996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:26:00.789717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:00.071439Z","time spent":"718.270348ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.78974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395678599s","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-04-01T23:26:00.789737Z","caller":"traceutil/trace.go:171","msg":"trace[1921457267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"1.150647131s","start":"2026-04-01T23:25:59.639072Z","end":"2026-04-01T23:26:00.789719Z","steps":["trace[1921457267] 'agreement among raft nodes before linearized reading' (duration: 1.150480917s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:00.789489Z","caller":"traceutil/trace.go:171","msg":"trace[76201212] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"716.14505ms","start":"2026-04-01T23:26:00.073314Z","end":"2026-04-01T23:26:00.789459Z","steps":["trace[76201212] 'agreement among raft nodes before linearized reading' (duration: 715.759089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.78978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.605795141s","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-04-01T23:26:00.789787Z","caller":"traceutil/trace.go:171","msg":"trace[1204665650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"2.395758362s","start":"2026-04-01T23:25:58.394016Z","end":"2026-04-01T23:26:00.789774Z","steps":["trace[1204665650] 'agreement among raft nodes before linearized reading' (duration: 2.395643789s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:00.789807Z","caller":"traceutil/trace.go:171","msg":"trace[2145712449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"2.605858213s","start":"2026-04-01T23:25:58.183941Z","end":"2026-04-01T23:26:00.7898Z","steps":["trace[2145712449] 'agreement among raft nodes before linearized reading' (duration: 2.605782461s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:00.073298Z","time spent":"716.50093ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:59.639053Z","time spent":"1.150736443s","remote":"127.0.0.1:55186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:26:00.789243Z","caller":"traceutil/trace.go:171","msg":"trace[1103291579] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"716.996493ms","start":"2026-04-01T23:26:00.072175Z","end":"2026-04-01T23:26:00.789171Z","steps":["trace[1103291579] 'agreement among raft nodes before linearized reading' (duration: 716.89012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.394Z","time spent":"2.395811383s","remote":"127.0.0.1:55420","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-04-01T23:26:00.78985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:00.07216Z","time spent":"717.684162ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.789821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.605947705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-04-01T23:26:00.789832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.183918Z","time spent":"2.605908264s","remote":"127.0.0.1:55420","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-04-01T23:26:00.789881Z","caller":"traceutil/trace.go:171","msg":"trace[1950531682] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"2.606052139s","start":"2026-04-01T23:25:58.18382Z","end":"2026-04-01T23:26:00.789872Z","steps":["trace[1950531682] 'agreement among raft nodes before linearized reading' (duration: 2.605955436s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.789908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:58.183782Z","time spent":"2.60611986s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:00.79041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.461346299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:00.790494Z","caller":"traceutil/trace.go:171","msg":"trace[314793572] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:653; }","duration":"3.46145022s","start":"2026-04-01T23:25:57.32902Z","end":"2026-04-01T23:26:00.790471Z","steps":["trace[314793572] 'agreement among raft nodes before linearized reading' (duration: 3.461265046s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:00.790534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:25:57.329004Z","time spent":"3.461520573s","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:26:01.55804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.871947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"warn","ts":"2026-04-01T23:26:01.558143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.393176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-01T23:26:01.558161Z","caller":"traceutil/trace.go:171","msg":"trace[775576841] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"134.03139ms","start":"2026-04-01T23:26:01.424093Z","end":"2026-04-01T23:26:01.558125Z","steps":["trace[775576841] 'range keys from in-memory index tree' (duration: 133.63399ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:01.55822Z","caller":"traceutil/trace.go:171","msg":"trace[2146854576] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"149.52966ms","start":"2026-04-01T23:26:01.408669Z","end":"2026-04-01T23:26:01.558199Z","steps":["trace[2146854576] 'range keys from in-memory index tree' (duration: 149.13881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.19245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553925025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:15.192623Z","caller":"traceutil/trace.go:171","msg":"trace[2117248787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"1.554346267s","start":"2026-04-01T23:26:13.638256Z","end":"2026-04-01T23:26:15.192602Z","steps":["trace[2117248787] 'range keys from in-memory index tree' (duration: 1.553788102s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.192699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:13.638238Z","time spent":"1.55444748s","remote":"127.0.0.1:55176","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-04-01T23:26:15.192848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.346807Z","time spent":"846.037109ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-01T23:26:15.193701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.760269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:26:15.193808Z","caller":"traceutil/trace.go:171","msg":"trace[2073206328] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"287.119098ms","start":"2026-04-01T23:26:14.90667Z","end":"2026-04-01T23:26:15.193789Z","steps":["trace[2073206328] 'read index received' (duration: 57.962µs)","trace[2073206328] 'applied index is now lower than readState.Index' (duration: 287.059596ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:26:15.193837Z","caller":"traceutil/trace.go:171","msg":"trace[583061341] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"859.37948ms","start":"2026-04-01T23:26:14.334425Z","end":"2026-04-01T23:26:15.193805Z","steps":["trace[583061341] 'process raft request' (duration: 215.411615ms)","trace[583061341] 'compare' (duration: 643.537473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:15.193992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.334399Z","time spent":"859.511224ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:26:15.194202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.517359ms","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-04-01T23:26:15.194287Z","caller":"traceutil/trace.go:171","msg":"trace[1467733922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"287.637173ms","start":"2026-04-01T23:26:14.906631Z","end":"2026-04-01T23:26:15.194268Z","steps":["trace[1467733922] 'agreement among raft nodes before linearized reading' (duration: 287.248722ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:15.383867Z","caller":"traceutil/trace.go:171","msg":"trace[1376825913] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"453.405712ms","start":"2026-04-01T23:26:14.930438Z","end":"2026-04-01T23:26:15.383844Z","steps":["trace[1376825913] 'process raft request' (duration: 376.610372ms)","trace[1376825913] 'compare' (duration: 76.539113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:15.384058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.930422Z","time spent":"453.562557ms","remote":"127.0.0.1:55326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:26:15.383907Z","caller":"traceutil/trace.go:171","msg":"trace[613405767] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"190.038868ms","start":"2026-04-01T23:26:15.19385Z","end":"2026-04-01T23:26:15.383889Z","steps":["trace[613405767] 'read index received' (duration: 113.172126ms)","trace[613405767] 'applied index is now lower than readState.Index' (duration: 76.865862ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:26:15.384196Z","caller":"traceutil/trace.go:171","msg":"trace[2076447764] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"184.849357ms","start":"2026-04-01T23:26:15.199321Z","end":"2026-04-01T23:26:15.38417Z","steps":["trace[2076447764] 'process raft request' (duration: 184.497438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.384267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.71406ms","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-04-01T23:26:15.384334Z","caller":"traceutil/trace.go:171","msg":"trace[557630082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"443.826882ms","start":"2026-04-01T23:26:14.940489Z","end":"2026-04-01T23:26:15.384316Z","steps":["trace[557630082] 'agreement among raft nodes before linearized reading' (duration: 443.597336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.384384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.940469Z","time spent":"443.906245ms","remote":"127.0.0.1:55420","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-04-01T23:26:15.384424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.545535ms","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-04-01T23:26:15.384507Z","caller":"traceutil/trace.go:171","msg":"trace[1665335867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"443.656498ms","start":"2026-04-01T23:26:14.940829Z","end":"2026-04-01T23:26:15.384486Z","steps":["trace[1665335867] 'agreement among raft nodes before linearized reading' (duration: 443.387761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.384555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.94082Z","time spent":"443.726992ms","remote":"127.0.0.1:55420","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-04-01T23:26:15.384556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.99695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:15.384599Z","caller":"traceutil/trace.go:171","msg":"trace[1504424988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"189.070872ms","start":"2026-04-01T23:26:15.195516Z","end":"2026-04-01T23:26:15.384587Z","steps":["trace[1504424988] 'agreement among raft nodes before linearized reading' (duration: 188.99399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.384615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.86324ms","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-04-01T23:26:15.384689Z","caller":"traceutil/trace.go:171","msg":"trace[1005997732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"451.970084ms","start":"2026-04-01T23:26:14.932707Z","end":"2026-04-01T23:26:15.384677Z","steps":["trace[1005997732] 'agreement among raft nodes before linearized reading' (duration: 451.642995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.384737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.883153ms","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-04-01T23:26:15.384756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.93269Z","time spent":"452.055105ms","remote":"127.0.0.1:55420","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-04-01T23:26:15.384771Z","caller":"traceutil/trace.go:171","msg":"trace[926208973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"464.952255ms","start":"2026-04-01T23:26:14.919809Z","end":"2026-04-01T23:26:15.384761Z","steps":["trace[926208973] 'agreement among raft nodes before linearized reading' (duration: 464.841922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:15.384801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:14.919785Z","time spent":"465.009937ms","remote":"127.0.0.1:55420","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-04-01T23:26:15.384768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.083012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:26:15.384907Z","caller":"traceutil/trace.go:171","msg":"trace[1822061625] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:765; }","duration":"175.237017ms","start":"2026-04-01T23:26:15.209633Z","end":"2026-04-01T23:26:15.38487Z","steps":["trace[1822061625] 'agreement among raft nodes before linearized reading' (duration: 175.093803ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:15.529269Z","caller":"traceutil/trace.go:171","msg":"trace[1283064848] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:797; }","duration":"136.804416ms","start":"2026-04-01T23:26:15.392443Z","end":"2026-04-01T23:26:15.529248Z","steps":["trace[1283064848] 'read index received' (duration: 55.659608ms)","trace[1283064848] 'applied index is now lower than readState.Index' (duration: 81.144058ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:15.529666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.198107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:15.529661Z","caller":"traceutil/trace.go:171","msg":"trace[1150087743] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"139.408496ms","start":"2026-04-01T23:26:15.390214Z","end":"2026-04-01T23:26:15.529623Z","steps":["trace[1150087743] 'process raft request' (duration: 139.006995ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:15.529705Z","caller":"traceutil/trace.go:171","msg":"trace[1641169381] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"139.518268ms","start":"2026-04-01T23:26:15.390158Z","end":"2026-04-01T23:26:15.529677Z","steps":["trace[1641169381] 'process raft request' (duration: 139.003974ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:15.529884Z","caller":"traceutil/trace.go:171","msg":"trace[1696561091] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"139.709354ms","start":"2026-04-01T23:26:15.390167Z","end":"2026-04-01T23:26:15.529876Z","steps":["trace[1696561091] 'process raft request' (duration: 139.032626ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:15.529698Z","caller":"traceutil/trace.go:171","msg":"trace[394568989] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:770; }","duration":"137.267808ms","start":"2026-04-01T23:26:15.392422Z","end":"2026-04-01T23:26:15.529689Z","steps":["trace[394568989] 'agreement among raft nodes before linearized reading' (duration: 137.195696ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:15.529893Z","caller":"traceutil/trace.go:171","msg":"trace[1192049972] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"139.720264ms","start":"2026-04-01T23:26:15.390068Z","end":"2026-04-01T23:26:15.529789Z","steps":["trace[1192049972] 'process raft request' (duration: 108.307984ms)","trace[1192049972] 'compare' (duration: 30.657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:15.529735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.119745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:15.53025Z","caller":"traceutil/trace.go:171","msg":"trace[941457208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"137.648508ms","start":"2026-04-01T23:26:15.392587Z","end":"2026-04-01T23:26:15.530235Z","steps":["trace[941457208] 'agreement among raft nodes before linearized reading' (duration: 137.131405ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:17.489083Z","caller":"traceutil/trace.go:171","msg":"trace[127650850] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"141.470632ms","start":"2026-04-01T23:26:17.347583Z","end":"2026-04-01T23:26:17.489053Z","steps":["trace[127650850] 'process raft request' (duration: 141.204005ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:19.163444Z","caller":"traceutil/trace.go:171","msg":"trace[2072429104] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"145.875432ms","start":"2026-04-01T23:26:19.01754Z","end":"2026-04-01T23:26:19.163415Z","steps":["trace[2072429104] 'process raft request' (duration: 144.959697ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:19.163833Z","caller":"traceutil/trace.go:171","msg":"trace[1930066178] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"138.977794ms","start":"2026-04-01T23:26:19.024677Z","end":"2026-04-01T23:26:19.163655Z","steps":["trace[1930066178] 'process raft request' (duration: 138.83068ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:21.745345Z","caller":"traceutil/trace.go:171","msg":"trace[874918437] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:863; }","duration":"107.924454ms","start":"2026-04-01T23:26:21.637403Z","end":"2026-04-01T23:26:21.745327Z","steps":["trace[874918437] 'read index received' (duration: 99.019593ms)","trace[874918437] 'applied index is now lower than readState.Index' (duration: 8.904151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:21.745468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.055457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:21.745491Z","caller":"traceutil/trace.go:171","msg":"trace[1796427229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"108.147939ms","start":"2026-04-01T23:26:21.637337Z","end":"2026-04-01T23:26:21.745485Z","steps":["trace[1796427229] 'agreement among raft nodes before linearized reading' (duration: 108.072447ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:21.745619Z","caller":"traceutil/trace.go:171","msg":"trace[403663815] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"151.312909ms","start":"2026-04-01T23:26:21.594292Z","end":"2026-04-01T23:26:21.745605Z","steps":["trace[403663815] 'process raft request' (duration: 142.09972ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:21.745802Z","caller":"traceutil/trace.go:171","msg":"trace[2018670939] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"151.436442ms","start":"2026-04-01T23:26:21.594314Z","end":"2026-04-01T23:26:21.74575Z","steps":["trace[2018670939] 'process raft request' (duration: 150.98121ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:43.829273Z","caller":"traceutil/trace.go:171","msg":"trace[357104075] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"136.429346ms","start":"2026-04-01T23:26:43.692809Z","end":"2026-04-01T23:26:43.829239Z","steps":["trace[357104075] 'read index received' (duration: 136.116598ms)","trace[357104075] 'applied index is now lower than readState.Index' (duration: 311.058µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:26:43.829396Z","caller":"traceutil/trace.go:171","msg":"trace[2140797907] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"159.266514ms","start":"2026-04-01T23:26:43.670086Z","end":"2026-04-01T23:26:43.829353Z","steps":["trace[2140797907] 'process raft request' (duration: 158.943116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:43.829518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.692103ms","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-04-01T23:26:43.829718Z","caller":"traceutil/trace.go:171","msg":"trace[2006888964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"136.95229ms","start":"2026-04-01T23:26:43.692745Z","end":"2026-04-01T23:26:43.829697Z","steps":["trace[2006888964] 'agreement among raft nodes before linearized reading' (duration: 136.605361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:43.829518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.373311ms","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-04-01T23:26:43.829831Z","caller":"traceutil/trace.go:171","msg":"trace[1373069142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"103.73078ms","start":"2026-04-01T23:26:43.726085Z","end":"2026-04-01T23:26:43.829816Z","steps":["trace[1373069142] 'agreement among raft nodes before linearized reading' (duration: 103.332958ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:44.110733Z","caller":"traceutil/trace.go:171","msg":"trace[583875395] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"165.081733ms","start":"2026-04-01T23:26:43.945623Z","end":"2026-04-01T23:26:44.110705Z","steps":["trace[583875395] 'process raft request' (duration: 164.499997ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:50.029774Z","caller":"traceutil/trace.go:171","msg":"trace[2025617899] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"148.306688ms","start":"2026-04-01T23:26:49.881442Z","end":"2026-04-01T23:26:50.029749Z","steps":["trace[2025617899] 'process raft request' (duration: 148.171674ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:50.559903Z","caller":"traceutil/trace.go:171","msg":"trace[210974438] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"571.549993ms","start":"2026-04-01T23:26:49.98833Z","end":"2026-04-01T23:26:50.55988Z","steps":["trace[210974438] 'read index received' (duration: 41.36617ms)","trace[210974438] 'applied index is now lower than readState.Index' (duration: 530.183053ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:26:50.559991Z","caller":"traceutil/trace.go:171","msg":"trace[27119142] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"577.410213ms","start":"2026-04-01T23:26:49.982556Z","end":"2026-04-01T23:26:50.559966Z","steps":["trace[27119142] 'process raft request' (duration: 500.079898ms)","trace[27119142] 'compare' (duration: 77.15893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:50.560114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:49.982532Z","time spent":"577.493114ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:26:50.560118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.993493ms","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-04-01T23:26:50.560135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.792499ms","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-04-01T23:26:50.560156Z","caller":"traceutil/trace.go:171","msg":"trace[1911157019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:981; }","duration":"569.088007ms","start":"2026-04-01T23:26:49.991056Z","end":"2026-04-01T23:26:50.560145Z","steps":["trace[1911157019] 'agreement among raft nodes before linearized reading' (duration: 568.970483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:50.560194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:49.991029Z","time spent":"569.157629ms","remote":"127.0.0.1:55420","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-04-01T23:26:50.560185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.003542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T23:26:50.560202Z","caller":"traceutil/trace.go:171","msg":"trace[173561065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"571.882662ms","start":"2026-04-01T23:26:49.988298Z","end":"2026-04-01T23:26:50.56018Z","steps":["trace[173561065] 'agreement among raft nodes before linearized reading' (duration: 571.703056ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:50.56023Z","caller":"traceutil/trace.go:171","msg":"trace[576086936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"525.237338ms","start":"2026-04-01T23:26:50.034976Z","end":"2026-04-01T23:26:50.560214Z","steps":["trace[576086936] 'agreement among raft nodes before linearized reading' (duration: 525.124495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:50.560232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:49.988288Z","time spent":"571.938553ms","remote":"127.0.0.1:55420","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-04-01T23:26:50.560219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.748719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-01T23:26:50.560261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.034949Z","time spent":"525.305171ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:26:50.560302Z","caller":"traceutil/trace.go:171","msg":"trace[656712020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"420.871381ms","start":"2026-04-01T23:26:50.139401Z","end":"2026-04-01T23:26:50.560272Z","steps":["trace[656712020] 'agreement among raft nodes before linearized reading' (duration: 420.667996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:50.560335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.139374Z","time spent":"420.954424ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:26:51.466063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.635798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:26:51.466397Z","caller":"traceutil/trace.go:171","msg":"trace[816531425] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"902.038496ms","start":"2026-04-01T23:26:50.564309Z","end":"2026-04-01T23:26:51.466348Z","steps":["trace[816531425] 'process raft request' (duration: 134.586976ms)","trace[816531425] 'compare' (duration: 766.185466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:51.466526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.564294Z","time spent":"902.179699ms","remote":"127.0.0.1:55420","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-04-01T23:26:51.467213Z","caller":"traceutil/trace.go:171","msg":"trace[560871079] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"902.838307ms","start":"2026-04-01T23:26:50.564358Z","end":"2026-04-01T23:26:51.467197Z","steps":["trace[560871079] 'process raft request' (duration: 902.583251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:51.467343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.461878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:51.467389Z","caller":"traceutil/trace.go:171","msg":"trace[1686690265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"737.55598ms","start":"2026-04-01T23:26:50.729822Z","end":"2026-04-01T23:26:51.467378Z","steps":["trace[1686690265] 'agreement among raft nodes before linearized reading' (duration: 737.470268ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:51.467265Z","caller":"traceutil/trace.go:171","msg":"trace[1901654971] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"902.669573ms","start":"2026-04-01T23:26:50.564584Z","end":"2026-04-01T23:26:51.467254Z","steps":["trace[1901654971] 'process raft request' (duration: 902.503648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:51.467415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.729804Z","time spent":"737.605232ms","remote":"127.0.0.1:55176","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-04-01T23:26:51.467357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.564351Z","time spent":"902.9596ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:26:51.467458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.564574Z","time spent":"902.859387ms","remote":"127.0.0.1:55420","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-04-01T23:26:51.467239Z","caller":"traceutil/trace.go:171","msg":"trace[607525413] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1022; }","duration":"737.342844ms","start":"2026-04-01T23:26:50.729868Z","end":"2026-04-01T23:26:51.467211Z","steps":["trace[607525413] 'read index received' (duration: 28.45µs)","trace[607525413] 'applied index is now lower than readState.Index' (duration: 737.313023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:51.467749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.54379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:51.467778Z","caller":"traceutil/trace.go:171","msg":"trace[794203758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:985; }","duration":"689.618341ms","start":"2026-04-01T23:26:50.778152Z","end":"2026-04-01T23:26:51.467771Z","steps":["trace[794203758] 'agreement among raft nodes before linearized reading' (duration: 689.570311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:51.467802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.778128Z","time spent":"689.667203ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:26:51.467921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.184827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:26:51.46801Z","caller":"traceutil/trace.go:171","msg":"trace[1213300973] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:985; }","duration":"587.300669ms","start":"2026-04-01T23:26:50.880676Z","end":"2026-04-01T23:26:51.467977Z","steps":["trace[1213300973] 'agreement among raft nodes before linearized reading' (duration: 587.146475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:51.468054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:50.880659Z","time spent":"587.383832ms","remote":"127.0.0.1:55518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:26:52.757997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119819347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:52.758266Z","caller":"traceutil/trace.go:171","msg":"trace[1473374264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"1.120261969s","start":"2026-04-01T23:26:51.637955Z","end":"2026-04-01T23:26:52.758217Z","steps":["trace[1473374264] 'range keys from in-memory index tree' (duration: 1.119596921s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:52.758371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:51.637927Z","time spent":"1.120421003s","remote":"127.0.0.1:55186","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-04-01T23:26:52.758408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.442088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:52.75859Z","caller":"traceutil/trace.go:171","msg":"trace[1842908595] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:985; }","duration":"895.739977ms","start":"2026-04-01T23:26:51.862814Z","end":"2026-04-01T23:26:52.758554Z","steps":["trace[1842908595] 'count revisions from in-memory index tree' (duration: 895.218242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:52.759264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.789905ms","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-04-01T23:26:52.759293Z","caller":"traceutil/trace.go:171","msg":"trace[621260888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"724.848217ms","start":"2026-04-01T23:26:52.034437Z","end":"2026-04-01T23:26:52.759286Z","steps":["trace[621260888] 'range keys from in-memory index tree' (duration: 724.658482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:52.759317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.034417Z","time spent":"724.895128ms","remote":"127.0.0.1:55420","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":"warn","ts":"2026-04-01T23:26:52.759438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.729901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T23:26:52.759493Z","caller":"traceutil/trace.go:171","msg":"trace[1666922055] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:985; }","duration":"591.832372ms","start":"2026-04-01T23:26:52.167644Z","end":"2026-04-01T23:26:52.759477Z","steps":["trace[1666922055] 'range keys from in-memory index tree' (duration: 591.527225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:52.759536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.16761Z","time spent":"591.913535ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-04-01T23:26:52.758711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:51.862781Z","time spent":"895.890791ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:26:53.078198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104743241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:26:53.183067Z","caller":"traceutil/trace.go:171","msg":"trace[1216144379] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"616.139871ms","start":"2026-04-01T23:26:52.566901Z","end":"2026-04-01T23:26:53.183041Z","steps":["trace[1216144379] 'process raft request' (duration: 615.951447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:53.183242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.566879Z","time spent":"616.272715ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:26:53.202034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.760683Z","time spent":"441.346386ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-01T23:26:53.202199Z","caller":"traceutil/trace.go:171","msg":"trace[700415353] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1025; }","duration":"624.550449ms","start":"2026-04-01T23:26:52.577624Z","end":"2026-04-01T23:26:53.202175Z","steps":["trace[700415353] 'read index received' (duration: 605.376719ms)","trace[700415353] 'applied index is now lower than readState.Index' (duration: 19.17299ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:26:53.202295Z","caller":"traceutil/trace.go:171","msg":"trace[925302659] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"437.988725ms","start":"2026-04-01T23:26:52.764296Z","end":"2026-04-01T23:26:53.202285Z","steps":["trace[925302659] 'process raft request' (duration: 437.691036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:53.202418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.764265Z","time spent":"438.069288ms","remote":"127.0.0.1:55420","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-04-01T23:26:53.202514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.890778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:26:53.20257Z","caller":"traceutil/trace.go:171","msg":"trace[364483047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:988; }","duration":"624.997051ms","start":"2026-04-01T23:26:52.577556Z","end":"2026-04-01T23:26:53.202553Z","steps":["trace[364483047] 'agreement among raft nodes before linearized reading' (duration: 624.910449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:53.202617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.520682ms","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-04-01T23:26:53.202634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.577517Z","time spent":"625.091573ms","remote":"127.0.0.1:45446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:26:53.202653Z","caller":"traceutil/trace.go:171","msg":"trace[1734501236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"578.593513ms","start":"2026-04-01T23:26:52.624049Z","end":"2026-04-01T23:26:53.202643Z","steps":["trace[1734501236] 'agreement among raft nodes before linearized reading' (duration: 578.47863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:53.202685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.624027Z","time spent":"578.650956ms","remote":"127.0.0.1:55420","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-04-01T23:26:53.202732Z","caller":"traceutil/trace.go:171","msg":"trace[368085670] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"185.068224ms","start":"2026-04-01T23:26:53.017625Z","end":"2026-04-01T23:26:53.202693Z","steps":["trace[368085670] 'process raft request' (duration: 184.486409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:53.202897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.503821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:26:53.202966Z","caller":"traceutil/trace.go:171","msg":"trace[643871670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"434.570523ms","start":"2026-04-01T23:26:52.768385Z","end":"2026-04-01T23:26:53.202955Z","steps":["trace[643871670] 'agreement among raft nodes before linearized reading' (duration: 434.480561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:26:53.202992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:26:52.768322Z","time spent":"434.663525ms","remote":"127.0.0.1:55176","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-04-01T23:26:53.20296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.095595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:26:53.203118Z","caller":"traceutil/trace.go:171","msg":"trace[1866126187] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:988; }","duration":"116.263959ms","start":"2026-04-01T23:26:53.086779Z","end":"2026-04-01T23:26:53.203043Z","steps":["trace[1866126187] 'agreement among raft nodes before linearized reading' (duration: 116.038393ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:57.334769Z","caller":"traceutil/trace.go:171","msg":"trace[1644390829] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"115.245891ms","start":"2026-04-01T23:26:57.219506Z","end":"2026-04-01T23:26:57.334752Z","steps":["trace[1644390829] 'read index received' (duration: 115.141899ms)","trace[1644390829] 'applied index is now lower than readState.Index' (duration: 101.252µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:26:57.334926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.396386ms","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-04-01T23:26:57.334972Z","caller":"traceutil/trace.go:171","msg":"trace[72738743] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1001; }","duration":"115.477498ms","start":"2026-04-01T23:26:57.219488Z","end":"2026-04-01T23:26:57.334966Z","steps":["trace[72738743] 'agreement among raft nodes before linearized reading' (duration: 115.353565ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:26:57.335081Z","caller":"traceutil/trace.go:171","msg":"trace[1258609572] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"115.765226ms","start":"2026-04-01T23:26:57.219033Z","end":"2026-04-01T23:26:57.334799Z","steps":["trace[1258609572] 'process raft request' (duration: 115.597831ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:03.581495Z","caller":"traceutil/trace.go:171","msg":"trace[2038791200] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"146.059266ms","start":"2026-04-01T23:27:03.435411Z","end":"2026-04-01T23:27:03.581471Z","steps":["trace[2038791200] 'process raft request' (duration: 103.660147ms)","trace[2038791200] 'compare' (duration: 42.331867ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:03.581969Z","caller":"traceutil/trace.go:171","msg":"trace[421882710] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"145.306927ms","start":"2026-04-01T23:27:03.436654Z","end":"2026-04-01T23:27:03.581961Z","steps":["trace[421882710] 'process raft request' (duration: 145.177733ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:03.582019Z","caller":"traceutil/trace.go:171","msg":"trace[439566625] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"131.234665ms","start":"2026-04-01T23:27:03.450746Z","end":"2026-04-01T23:27:03.581981Z","steps":["trace[439566625] 'process raft request' (duration: 131.164333ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:03.783426Z","caller":"traceutil/trace.go:171","msg":"trace[1480351032] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"196.524755ms","start":"2026-04-01T23:27:03.586875Z","end":"2026-04-01T23:27:03.7834Z","steps":["trace[1480351032] 'process raft request' (duration: 194.206272ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:03.786654Z","caller":"traceutil/trace.go:171","msg":"trace[655945666] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"149.251413ms","start":"2026-04-01T23:27:03.637379Z","end":"2026-04-01T23:27:03.78663Z","steps":["trace[655945666] 'read index received' (duration: 143.716433ms)","trace[655945666] 'applied index is now lower than readState.Index' (duration: 5.53385ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:03.786757Z","caller":"traceutil/trace.go:171","msg":"trace[1270138263] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"199.400513ms","start":"2026-04-01T23:27:03.58733Z","end":"2026-04-01T23:27:03.78673Z","steps":["trace[1270138263] 'process raft request' (duration: 199.260149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:03.78677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.371377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:03.786828Z","caller":"traceutil/trace.go:171","msg":"trace[1487293505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"149.435489ms","start":"2026-04-01T23:27:03.637368Z","end":"2026-04-01T23:27:03.786804Z","steps":["trace[1487293505] 'agreement among raft nodes before linearized reading' (duration: 149.350197ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:03.787217Z","caller":"traceutil/trace.go:171","msg":"trace[370123466] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"200.219384ms","start":"2026-04-01T23:27:03.586975Z","end":"2026-04-01T23:27:03.787195Z","steps":["trace[370123466] 'process raft request' (duration: 199.469644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:05.8057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.566252ms","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-04-01T23:27:05.805769Z","caller":"traceutil/trace.go:171","msg":"trace[533169233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"220.709957ms","start":"2026-04-01T23:27:05.585044Z","end":"2026-04-01T23:27:05.805754Z","steps":["trace[533169233] 'range keys from in-memory index tree' (duration: 220.330126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:05.805902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.022242ms","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-04-01T23:27:05.805926Z","caller":"traceutil/trace.go:171","msg":"trace[454957076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"219.094363ms","start":"2026-04-01T23:27:05.586825Z","end":"2026-04-01T23:27:05.80592Z","steps":["trace[454957076] 'range keys from in-memory index tree' (duration: 218.883189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:05.805969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.911608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:05.80598Z","caller":"traceutil/trace.go:171","msg":"trace[248787340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"169.95169ms","start":"2026-04-01T23:27:05.636025Z","end":"2026-04-01T23:27:05.805976Z","steps":["trace[248787340] 'range keys from in-memory index tree' (duration: 169.857008ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:06.277527Z","caller":"traceutil/trace.go:171","msg":"trace[139242040] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"466.112608ms","start":"2026-04-01T23:27:05.811382Z","end":"2026-04-01T23:27:06.277495Z","steps":["trace[139242040] 'process raft request' (duration: 465.895342ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:06.277559Z","caller":"traceutil/trace.go:171","msg":"trace[1334652280] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"466.037285ms","start":"2026-04-01T23:27:05.811475Z","end":"2026-04-01T23:27:06.277512Z","steps":["trace[1334652280] 'process raft request' (duration: 465.959033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:06.27767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:05.811372Z","time spent":"466.220251ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:27:06.277711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:05.811466Z","time spent":"466.167049ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:27:06.277795Z","caller":"traceutil/trace.go:171","msg":"trace[175100404] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"466.18738ms","start":"2026-04-01T23:27:05.811594Z","end":"2026-04-01T23:27:06.277782Z","steps":["trace[175100404] 'process raft request' (duration: 465.882102ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:06.277806Z","caller":"traceutil/trace.go:171","msg":"trace[567537569] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"465.301357ms","start":"2026-04-01T23:27:05.812485Z","end":"2026-04-01T23:27:06.277786Z","steps":["trace[567537569] 'process raft request' (duration: 465.206224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:06.277848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:05.811557Z","time spent":"466.262912ms","remote":"127.0.0.1:55420","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-04-01T23:27:06.27792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:05.81247Z","time spent":"465.389498ms","remote":"127.0.0.1:55420","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-04-01T23:27:07.739702Z","caller":"traceutil/trace.go:171","msg":"trace[1537141345] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"102.231792ms","start":"2026-04-01T23:27:07.63745Z","end":"2026-04-01T23:27:07.739682Z","steps":["trace[1537141345] 'read index received' (duration: 102.138319ms)","trace[1537141345] 'applied index is now lower than readState.Index' (duration: 92.153µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:07.739803Z","caller":"traceutil/trace.go:171","msg":"trace[839533434] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"297.185285ms","start":"2026-04-01T23:27:07.442582Z","end":"2026-04-01T23:27:07.739768Z","steps":["trace[839533434] 'process raft request' (duration: 296.96874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:07.739854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.394478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:07.739876Z","caller":"traceutil/trace.go:171","msg":"trace[328812597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"102.468719ms","start":"2026-04-01T23:27:07.6374Z","end":"2026-04-01T23:27:07.739869Z","steps":["trace[328812597] 'agreement among raft nodes before linearized reading' (duration: 102.354696ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:08.081779Z","caller":"traceutil/trace.go:171","msg":"trace[1683372459] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"124.955216ms","start":"2026-04-01T23:27:07.956798Z","end":"2026-04-01T23:27:08.081753Z","steps":["trace[1683372459] 'process raft request' (duration: 124.788952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:16.296613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104743438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:27:16.470359Z","caller":"traceutil/trace.go:171","msg":"trace[776582802] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"674.283622ms","start":"2026-04-01T23:27:15.796059Z","end":"2026-04-01T23:27:16.470343Z","steps":["trace[776582802] 'read index received' (duration: 674.160129ms)","trace[776582802] 'applied index is now lower than readState.Index' (duration: 122.563µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:16.470659Z","caller":"traceutil/trace.go:171","msg":"trace[662336223] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"708.49892ms","start":"2026-04-01T23:27:15.762149Z","end":"2026-04-01T23:27:16.470648Z","steps":["trace[662336223] 'process raft request' (duration: 708.078409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:16.470771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:15.762127Z","time spent":"708.584331ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:27:16.470999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.947429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:16.471061Z","caller":"traceutil/trace.go:171","msg":"trace[870963490] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1060; }","duration":"675.049852ms","start":"2026-04-01T23:27:15.796Z","end":"2026-04-01T23:27:16.47105Z","steps":["trace[870963490] 'agreement among raft nodes before linearized reading' (duration: 674.96587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:16.471103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:15.79597Z","time spent":"675.125404ms","remote":"127.0.0.1:55574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:27:16.471353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.665438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:27:16.471468Z","caller":"traceutil/trace.go:171","msg":"trace[787285847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"326.849213ms","start":"2026-04-01T23:27:16.144593Z","end":"2026-04-01T23:27:16.471442Z","steps":["trace[787285847] 'agreement among raft nodes before linearized reading' (duration: 326.511784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:16.471532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:16.144565Z","time spent":"326.957286ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:27:16.685999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.635073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:27:16.686139Z","caller":"traceutil/trace.go:171","msg":"trace[746988448] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"209.669054ms","start":"2026-04-01T23:27:16.476442Z","end":"2026-04-01T23:27:16.686111Z","steps":["trace[746988448] 'process raft request' (duration: 86.841065ms)","trace[746988448] 'compare' (duration: 122.50914ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:16.77388Z","caller":"traceutil/trace.go:171","msg":"trace[191280697] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1105; }","duration":"274.176585ms","start":"2026-04-01T23:27:16.499674Z","end":"2026-04-01T23:27:16.77385Z","steps":["trace[191280697] 'read index received' (duration: 63.788142ms)","trace[191280697] 'applied index is now lower than readState.Index' (duration: 210.387533ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:16.773935Z","caller":"traceutil/trace.go:171","msg":"trace[203641676] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"297.004901ms","start":"2026-04-01T23:27:16.476874Z","end":"2026-04-01T23:27:16.773879Z","steps":["trace[203641676] 'process raft request' (duration: 296.771725ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:16.773952Z","caller":"traceutil/trace.go:171","msg":"trace[1598544772] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"297.023971ms","start":"2026-04-01T23:27:16.476885Z","end":"2026-04-01T23:27:16.773909Z","steps":["trace[1598544772] 'process raft request' (duration: 296.913918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:16.774161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.472894ms","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-04-01T23:27:16.7742Z","caller":"traceutil/trace.go:171","msg":"trace[1563360540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"274.569075ms","start":"2026-04-01T23:27:16.499619Z","end":"2026-04-01T23:27:16.774188Z","steps":["trace[1563360540] 'agreement among raft nodes before linearized reading' (duration: 274.364099ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:16.882128Z","caller":"traceutil/trace.go:171","msg":"trace[1521219365] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"108.128919ms","start":"2026-04-01T23:27:16.773949Z","end":"2026-04-01T23:27:16.882078Z","steps":["trace[1521219365] 'read index received' (duration: 33.240002ms)","trace[1521219365] 'applied index is now lower than readState.Index' (duration: 74.880097ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:16.882165Z","caller":"traceutil/trace.go:171","msg":"trace[1023485986] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"186.955971ms","start":"2026-04-01T23:27:16.695165Z","end":"2026-04-01T23:27:16.882121Z","steps":["trace[1023485986] 'process raft request' (duration: 112.002732ms)","trace[1023485986] 'compare' (duration: 74.168868ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:16.882367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.29515ms","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-04-01T23:27:16.882427Z","caller":"traceutil/trace.go:171","msg":"trace[1907556740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"357.437205ms","start":"2026-04-01T23:27:16.524977Z","end":"2026-04-01T23:27:16.882414Z","steps":["trace[1907556740] 'agreement among raft nodes before linearized reading' (duration: 357.216209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:16.882461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:16.52491Z","time spent":"357.540847ms","remote":"127.0.0.1:55420","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-04-01T23:27:17.104235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.613978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:27:17.104553Z","caller":"traceutil/trace.go:171","msg":"trace[1687252993] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"325.879267ms","start":"2026-04-01T23:27:16.778621Z","end":"2026-04-01T23:27:17.1045Z","steps":["trace[1687252993] 'process raft request' (duration: 209.561931ms)","trace[1687252993] 'compare' (duration: 115.462483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:17.10477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:16.778599Z","time spent":"326.043552ms","remote":"127.0.0.1:55420","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-04-01T23:27:17.104825Z","caller":"traceutil/trace.go:171","msg":"trace[187710237] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"217.970123ms","start":"2026-04-01T23:27:16.886827Z","end":"2026-04-01T23:27:17.104797Z","steps":["trace[187710237] 'process raft request' (duration: 217.872621ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:18.651681Z","caller":"traceutil/trace.go:171","msg":"trace[1045974801] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"173.242746ms","start":"2026-04-01T23:27:18.478406Z","end":"2026-04-01T23:27:18.651649Z","steps":["trace[1045974801] 'process raft request' (duration: 173.0223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:19.163329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.579238ms","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-04-01T23:27:19.163366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.284897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:27:19.163484Z","caller":"traceutil/trace.go:171","msg":"trace[758805057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1068; }","duration":"384.476262ms","start":"2026-04-01T23:27:18.778982Z","end":"2026-04-01T23:27:19.163458Z","steps":["trace[758805057] 'range keys from in-memory index tree' (duration: 384.054671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:19.163552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:18.77894Z","time spent":"384.600495ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-01T23:27:19.16342Z","caller":"traceutil/trace.go:171","msg":"trace[1708374507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1068; }","duration":"383.719202ms","start":"2026-04-01T23:27:18.779682Z","end":"2026-04-01T23:27:19.163401Z","steps":["trace[1708374507] 'range keys from in-memory index tree' (duration: 383.390463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:19.163781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:18.779659Z","time spent":"384.080272ms","remote":"127.0.0.1:55420","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-04-01T23:27:19.322556Z","caller":"traceutil/trace.go:171","msg":"trace[128798468] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"153.999036ms","start":"2026-04-01T23:27:19.168517Z","end":"2026-04-01T23:27:19.322516Z","steps":["trace[128798468] 'process raft request' (duration: 153.623736ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:19.322788Z","caller":"traceutil/trace.go:171","msg":"trace[2060090932] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"154.181271ms","start":"2026-04-01T23:27:19.168525Z","end":"2026-04-01T23:27:19.322707Z","steps":["trace[2060090932] 'process raft request' (duration: 153.901164ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:19.334355Z","caller":"traceutil/trace.go:171","msg":"trace[2132702976] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"164.653579ms","start":"2026-04-01T23:27:19.169671Z","end":"2026-04-01T23:27:19.334324Z","steps":["trace[2132702976] 'process raft request' (duration: 164.588808ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:19.334434Z","caller":"traceutil/trace.go:171","msg":"trace[564333989] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"165.648426ms","start":"2026-04-01T23:27:19.168761Z","end":"2026-04-01T23:27:19.33441Z","steps":["trace[564333989] 'process raft request' (duration: 165.303437ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:21.013366Z","caller":"traceutil/trace.go:171","msg":"trace[2061759064] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"299.419264ms","start":"2026-04-01T23:27:20.713919Z","end":"2026-04-01T23:27:21.013338Z","steps":["trace[2061759064] 'read index received' (duration: 299.237069ms)","trace[2061759064] 'applied index is now lower than readState.Index' (duration: 180.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:21.013454Z","caller":"traceutil/trace.go:171","msg":"trace[725433332] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"356.262833ms","start":"2026-04-01T23:27:20.657151Z","end":"2026-04-01T23:27:21.013414Z","steps":["trace[725433332] 'process raft request' (duration: 355.997776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:21.013729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.803575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:27:21.013734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:20.657126Z","time spent":"356.515141ms","remote":"127.0.0.1:55420","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-04-01T23:27:21.013789Z","caller":"traceutil/trace.go:171","msg":"trace[1649233874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"299.917608ms","start":"2026-04-01T23:27:20.713859Z","end":"2026-04-01T23:27:21.013776Z","steps":["trace[1649233874] 'agreement among raft nodes before linearized reading' (duration: 299.668002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:21.013767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.684716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T23:27:21.013934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.932982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:21.01397Z","caller":"traceutil/trace.go:171","msg":"trace[556588886] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1073; }","duration":"117.014045ms","start":"2026-04-01T23:27:20.896947Z","end":"2026-04-01T23:27:21.013961Z","steps":["trace[556588886] 'agreement among raft nodes before linearized reading' (duration: 116.959863ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:21.013945Z","caller":"traceutil/trace.go:171","msg":"trace[1582716381] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1073; }","duration":"145.919232ms","start":"2026-04-01T23:27:20.867988Z","end":"2026-04-01T23:27:21.013907Z","steps":["trace[1582716381] 'agreement among raft nodes before linearized reading' (duration: 145.690596ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:21.200473Z","caller":"traceutil/trace.go:171","msg":"trace[1294614860] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"182.03411ms","start":"2026-04-01T23:27:21.018415Z","end":"2026-04-01T23:27:21.200449Z","steps":["trace[1294614860] 'process raft request' (duration: 144.852174ms)","trace[1294614860] 'compare' (duration: 37.092453ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:21.458424Z","caller":"traceutil/trace.go:171","msg":"trace[140588806] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"120.466696ms","start":"2026-04-01T23:27:21.337926Z","end":"2026-04-01T23:27:21.458393Z","steps":["trace[140588806] 'read index received' (duration: 21.372357ms)","trace[140588806] 'applied index is now lower than readState.Index' (duration: 99.092969ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:21.458463Z","caller":"traceutil/trace.go:171","msg":"trace[2024460262] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"126.020144ms","start":"2026-04-01T23:27:21.3324Z","end":"2026-04-01T23:27:21.45842Z","steps":["trace[2024460262] 'process raft request' (duration: 115.537556ms)","trace[2024460262] 'compare' (duration: 10.331024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:21.458689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.889321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T23:27:21.458722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.774724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T23:27:21.458821Z","caller":"traceutil/trace.go:171","msg":"trace[23563569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"120.948129ms","start":"2026-04-01T23:27:21.337857Z","end":"2026-04-01T23:27:21.458805Z","steps":["trace[23563569] 'agreement among raft nodes before linearized reading' (duration: 120.686083ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:21.458858Z","caller":"traceutil/trace.go:171","msg":"trace[649089870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1076; }","duration":"120.030316ms","start":"2026-04-01T23:27:21.338753Z","end":"2026-04-01T23:27:21.458784Z","steps":["trace[649089870] 'agreement among raft nodes before linearized reading' (duration: 119.83876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:23.319828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.109531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:27:23.319908Z","caller":"traceutil/trace.go:171","msg":"trace[61337521] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1080; }","duration":"159.256715ms","start":"2026-04-01T23:27:23.160636Z","end":"2026-04-01T23:27:23.319893Z","steps":["trace[61337521] 'count revisions from in-memory index tree' (duration: 159.008779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:23.320189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.808722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:23.320303Z","caller":"traceutil/trace.go:171","msg":"trace[501812310] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1080; }","duration":"122.010167ms","start":"2026-04-01T23:27:23.198271Z","end":"2026-04-01T23:27:23.320281Z","steps":["trace[501812310] 'count revisions from in-memory index tree' (duration: 121.673338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:23.320341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.915439ms","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-04-01T23:27:23.320382Z","caller":"traceutil/trace.go:171","msg":"trace[1156177791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"115.011862ms","start":"2026-04-01T23:27:23.205363Z","end":"2026-04-01T23:27:23.320374Z","steps":["trace[1156177791] 'range keys from in-memory index tree' (duration: 114.744745ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:23.501743Z","caller":"traceutil/trace.go:171","msg":"trace[1920488527] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"133.511522ms","start":"2026-04-01T23:27:23.368177Z","end":"2026-04-01T23:27:23.501689Z","steps":["trace[1920488527] 'process raft request' (duration: 131.638033ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:31.769027Z","caller":"traceutil/trace.go:171","msg":"trace[1229685668] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1157; }","duration":"130.288877ms","start":"2026-04-01T23:27:31.638716Z","end":"2026-04-01T23:27:31.769005Z","steps":["trace[1229685668] 'read index received' (duration: 4.710095ms)","trace[1229685668] 'applied index is now lower than readState.Index' (duration: 125.577602ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:31.769119Z","caller":"traceutil/trace.go:171","msg":"trace[1122564018] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"152.536276ms","start":"2026-04-01T23:27:31.61655Z","end":"2026-04-01T23:27:31.769086Z","steps":["trace[1122564018] 'process raft request' (duration: 116.906041ms)","trace[1122564018] 'compare' (duration: 35.434891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:31.769199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.459841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:31.769358Z","caller":"traceutil/trace.go:171","msg":"trace[318162497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"130.602886ms","start":"2026-04-01T23:27:31.638682Z","end":"2026-04-01T23:27:31.769285Z","steps":["trace[318162497] 'agreement among raft nodes before linearized reading' (duration: 130.429252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:31.769455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.03441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-01T23:27:31.769552Z","caller":"traceutil/trace.go:171","msg":"trace[1734263293] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1111; }","duration":"104.170183ms","start":"2026-04-01T23:27:31.665364Z","end":"2026-04-01T23:27:31.769534Z","steps":["trace[1734263293] 'agreement among raft nodes before linearized reading' (duration: 103.819814ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:35.594848Z","caller":"traceutil/trace.go:171","msg":"trace[1006171640] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"120.98298ms","start":"2026-04-01T23:27:35.473842Z","end":"2026-04-01T23:27:35.594825Z","steps":["trace[1006171640] 'read index received' (duration: 120.788744ms)","trace[1006171640] 'applied index is now lower than readState.Index' (duration: 193.326µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:35.594934Z","caller":"traceutil/trace.go:171","msg":"trace[183153742] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"477.141692ms","start":"2026-04-01T23:27:35.117734Z","end":"2026-04-01T23:27:35.594875Z","steps":["trace[183153742] 'process raft request' (duration: 476.945636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:35.595081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:35.117716Z","time spent":"477.286365ms","remote":"127.0.0.1:55420","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-04-01T23:27:35.595134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.278978ms","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-04-01T23:27:35.595176Z","caller":"traceutil/trace.go:171","msg":"trace[1898458962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"121.377181ms","start":"2026-04-01T23:27:35.473786Z","end":"2026-04-01T23:27:35.595163Z","steps":["trace[1898458962] 'agreement among raft nodes before linearized reading' (duration: 121.200556ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:37.965792Z","caller":"traceutil/trace.go:171","msg":"trace[2025814704] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"577.889424ms","start":"2026-04-01T23:27:37.387874Z","end":"2026-04-01T23:27:37.965763Z","steps":["trace[2025814704] 'process raft request' (duration: 577.614917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.965988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.387855Z","time spent":"578.016568ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:27:37.966008Z","caller":"traceutil/trace.go:171","msg":"trace[2107190] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1227; }","duration":"483.909341ms","start":"2026-04-01T23:27:37.482081Z","end":"2026-04-01T23:27:37.965991Z","steps":["trace[2107190] 'read index received' (duration: 456.525924ms)","trace[2107190] 'applied index is now lower than readState.Index' (duration: 27.382497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:37.966343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.668152ms","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-04-01T23:27:37.966362Z","caller":"traceutil/trace.go:171","msg":"trace[426269284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"299.739463ms","start":"2026-04-01T23:27:37.666617Z","end":"2026-04-01T23:27:37.966357Z","steps":["trace[426269284] 'agreement among raft nodes before linearized reading' (duration: 299.653521ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:37.966419Z","caller":"traceutil/trace.go:171","msg":"trace[495359424] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"364.160253ms","start":"2026-04-01T23:27:37.602255Z","end":"2026-04-01T23:27:37.966415Z","steps":["trace[495359424] 'process raft request' (duration: 363.410754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.966468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.602232Z","time spent":"364.197224ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:27:37.966537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.454095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:27:37.966551Z","caller":"traceutil/trace.go:171","msg":"trace[1272013514] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1178; }","duration":"484.490396ms","start":"2026-04-01T23:27:37.482054Z","end":"2026-04-01T23:27:37.966545Z","steps":["trace[1272013514] 'agreement among raft nodes before linearized reading' (duration: 484.450054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.966568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.48204Z","time spent":"484.523636ms","remote":"127.0.0.1:50380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:27:37.966671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.076483ms","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-04-01T23:27:37.966684Z","caller":"traceutil/trace.go:171","msg":"trace[1487680661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"170.123724ms","start":"2026-04-01T23:27:37.796556Z","end":"2026-04-01T23:27:37.96668Z","steps":["trace[1487680661] 'agreement among raft nodes before linearized reading' (duration: 170.083833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.9669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.752178ms","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-04-01T23:27:37.966943Z","caller":"traceutil/trace.go:171","msg":"trace[156734056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"344.804079ms","start":"2026-04-01T23:27:37.622134Z","end":"2026-04-01T23:27:37.966938Z","steps":["trace[156734056] 'agreement among raft nodes before linearized reading' (duration: 344.733307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.966958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.622126Z","time spent":"344.82936ms","remote":"127.0.0.1:55420","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":"warn","ts":"2026-04-01T23:27:37.967005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.047511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:37.96702Z","caller":"traceutil/trace.go:171","msg":"trace[328661284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"329.104323ms","start":"2026-04-01T23:27:37.637912Z","end":"2026-04-01T23:27:37.967016Z","steps":["trace[328661284] 'agreement among raft nodes before linearized reading' (duration: 329.081952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.967031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.637887Z","time spent":"329.142063ms","remote":"127.0.0.1:55176","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-04-01T23:27:37.967178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.069757ms","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-04-01T23:27:37.96634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.970209ms","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-04-01T23:27:37.967198Z","caller":"traceutil/trace.go:171","msg":"trace[59308635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"345.115148ms","start":"2026-04-01T23:27:37.622077Z","end":"2026-04-01T23:27:37.967192Z","steps":["trace[59308635] 'agreement among raft nodes before linearized reading' (duration: 345.073126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.967222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.62206Z","time spent":"345.158288ms","remote":"127.0.0.1:55420","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-04-01T23:27:37.967236Z","caller":"traceutil/trace.go:171","msg":"trace[2033865463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"358.909494ms","start":"2026-04-01T23:27:37.608307Z","end":"2026-04-01T23:27:37.967217Z","steps":["trace[2033865463] 'agreement among raft nodes before linearized reading' (duration: 357.876547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:37.967298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:37.60829Z","time spent":"358.991956ms","remote":"127.0.0.1:55420","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-04-01T23:27:50.168491Z","caller":"traceutil/trace.go:171","msg":"trace[1132192055] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"104.688807ms","start":"2026-04-01T23:27:50.063765Z","end":"2026-04-01T23:27:50.168454Z","steps":["trace[1132192055] 'process raft request' (duration: 104.503182ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:52.900257Z","caller":"traceutil/trace.go:171","msg":"trace[1635645416] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"237.003699ms","start":"2026-04-01T23:27:52.663227Z","end":"2026-04-01T23:27:52.90023Z","steps":["trace[1635645416] 'process raft request' (duration: 236.858884ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:53.199975Z","caller":"traceutil/trace.go:171","msg":"trace[1216026875] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"293.593371ms","start":"2026-04-01T23:27:52.906358Z","end":"2026-04-01T23:27:53.199951Z","steps":["trace[1216026875] 'process raft request' (duration: 195.744324ms)","trace[1216026875] 'compare' (duration: 97.657431ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:53.200321Z","caller":"traceutil/trace.go:171","msg":"trace[1396182078] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"286.886703ms","start":"2026-04-01T23:27:52.913426Z","end":"2026-04-01T23:27:53.200313Z","steps":["trace[1396182078] 'process raft request' (duration: 286.462361ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:53.387847Z","caller":"traceutil/trace.go:171","msg":"trace[2035140490] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"119.039938ms","start":"2026-04-01T23:27:53.268788Z","end":"2026-04-01T23:27:53.387828Z","steps":["trace[2035140490] 'process raft request' (duration: 118.916765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.459025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.00727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:27:54.459161Z","caller":"traceutil/trace.go:171","msg":"trace[799007312] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.062771861s","start":"2026-04-01T23:27:53.396359Z","end":"2026-04-01T23:27:54.459131Z","steps":["trace[799007312] 'process raft request' (duration: 84.492803ms)","trace[799007312] 'compare' (duration: 977.139208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:54.459224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:53.396335Z","time spent":"1.062865413s","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:27:54.684201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104743872,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:27:54.89912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.364082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:27:54.899301Z","caller":"traceutil/trace.go:171","msg":"trace[1542299916] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"865.538988ms","start":"2026-04-01T23:27:54.03373Z","end":"2026-04-01T23:27:54.899269Z","steps":["trace[1542299916] 'process raft request' (duration: 680.98615ms)","trace[1542299916] 'compare' (duration: 184.199918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:54.899388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.033714Z","time spent":"865.626679ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:27:54.899474Z","caller":"traceutil/trace.go:171","msg":"trace[221053510] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1304; }","duration":"715.413254ms","start":"2026-04-01T23:27:54.184037Z","end":"2026-04-01T23:27:54.89945Z","steps":["trace[221053510] 'read index received' (duration: 530.663042ms)","trace[221053510] 'applied index is now lower than readState.Index' (duration: 184.749162ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:54.899595Z","caller":"traceutil/trace.go:171","msg":"trace[1650822293] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"724.392942ms","start":"2026-04-01T23:27:54.175193Z","end":"2026-04-01T23:27:54.899586Z","steps":["trace[1650822293] 'process raft request' (duration: 724.026102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.899699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.175166Z","time spent":"724.458494ms","remote":"127.0.0.1:55420","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-04-01T23:27:54.899779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.610992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:27:54.899816Z","caller":"traceutil/trace.go:171","msg":"trace[262028342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"711.708636ms","start":"2026-04-01T23:27:54.188097Z","end":"2026-04-01T23:27:54.899806Z","steps":["trace[262028342] 'agreement among raft nodes before linearized reading' (duration: 711.581323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.899844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.188071Z","time spent":"711.767247ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:27:54.899981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.821772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T23:27:54.900014Z","caller":"traceutil/trace.go:171","msg":"trace[1109191391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"707.874103ms","start":"2026-04-01T23:27:54.192132Z","end":"2026-04-01T23:27:54.900006Z","steps":["trace[1109191391] 'agreement among raft nodes before linearized reading' (duration: 707.791881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.900063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.19212Z","time spent":"707.936554ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:27:54.900119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.091002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:27:54.900191Z","caller":"traceutil/trace.go:171","msg":"trace[1311783455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"716.219414ms","start":"2026-04-01T23:27:54.183946Z","end":"2026-04-01T23:27:54.900165Z","steps":["trace[1311783455] 'agreement among raft nodes before linearized reading' (duration: 716.106351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.900253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.183902Z","time spent":"716.341849ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:27:54.90018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.676418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:27:54.900313Z","caller":"traceutil/trace.go:171","msg":"trace[1764732093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"710.867693ms","start":"2026-04-01T23:27:54.189434Z","end":"2026-04-01T23:27:54.900302Z","steps":["trace[1764732093] 'agreement among raft nodes before linearized reading' (duration: 710.688169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.900377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.18942Z","time spent":"710.942566ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:27:54.900395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.968535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:54.900495Z","caller":"traceutil/trace.go:171","msg":"trace[754113707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"261.12244ms","start":"2026-04-01T23:27:54.639362Z","end":"2026-04-01T23:27:54.900484Z","steps":["trace[754113707] 'agreement among raft nodes before linearized reading' (duration: 261.001477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.899981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.627527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T23:27:54.900613Z","caller":"traceutil/trace.go:171","msg":"trace[2143873199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"708.332016ms","start":"2026-04-01T23:27:54.192266Z","end":"2026-04-01T23:27:54.900598Z","steps":["trace[2143873199] 'agreement among raft nodes before linearized reading' (duration: 707.529825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:54.900674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:54.192231Z","time spent":"708.427039ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:27:55.060758Z","caller":"traceutil/trace.go:171","msg":"trace[483301005] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"152.62099ms","start":"2026-04-01T23:27:54.908114Z","end":"2026-04-01T23:27:55.060735Z","steps":["trace[483301005] 'process raft request' (duration: 152.584539ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:55.061175Z","caller":"traceutil/trace.go:171","msg":"trace[1503267562] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"155.714272ms","start":"2026-04-01T23:27:54.905448Z","end":"2026-04-01T23:27:55.061162Z","steps":["trace[1503267562] 'process raft request' (duration: 155.083495ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:55.061366Z","caller":"traceutil/trace.go:171","msg":"trace[678341421] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"155.841635ms","start":"2026-04-01T23:27:54.905511Z","end":"2026-04-01T23:27:55.061353Z","steps":["trace[678341421] 'process raft request' (duration: 155.115636ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:55.061488Z","caller":"traceutil/trace.go:171","msg":"trace[445307256] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"154.970133ms","start":"2026-04-01T23:27:54.906508Z","end":"2026-04-01T23:27:55.061478Z","steps":["trace[445307256] 'process raft request' (duration: 154.154261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.445719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104743888,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:27:57.468098Z","caller":"traceutil/trace.go:171","msg":"trace[276472772] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"562.746122ms","start":"2026-04-01T23:27:56.905328Z","end":"2026-04-01T23:27:57.468074Z","steps":["trace[276472772] 'process raft request' (duration: 562.505126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.468283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:56.905287Z","time spent":"562.871105ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:27:57.779844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.571346ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-01T23:27:57.780054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.094798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:57.780108Z","caller":"traceutil/trace.go:171","msg":"trace[1421044795] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1258; }","duration":"835.14264ms","start":"2026-04-01T23:27:56.944936Z","end":"2026-04-01T23:27:57.780078Z","steps":["trace[1421044795] 'agreement among raft nodes before linearized reading' (duration: 835.027106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.780152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:56.944901Z","time spent":"835.239143ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:27:57.780261Z","caller":"traceutil/trace.go:171","msg":"trace[718150198] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"834.967975ms","start":"2026-04-01T23:27:56.944964Z","end":"2026-04-01T23:27:57.779932Z","steps":["trace[718150198] 'read index received' (duration: 522.843534ms)","trace[718150198] 'applied index is now lower than readState.Index' (duration: 312.122741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:27:57.780575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.195059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T23:27:57.780605Z","caller":"traceutil/trace.go:171","msg":"trace[1425798505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"781.271831ms","start":"2026-04-01T23:27:56.999317Z","end":"2026-04-01T23:27:57.780588Z","steps":["trace[1425798505] 'agreement among raft nodes before linearized reading' (duration: 781.109957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.780623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:56.999303Z","time spent":"781.315572ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:27:57.780757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.944674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T23:27:57.780797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.991125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:27:57.780812Z","caller":"traceutil/trace.go:171","msg":"trace[965593684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"714.032726ms","start":"2026-04-01T23:27:57.066753Z","end":"2026-04-01T23:27:57.780785Z","steps":["trace[965593684] 'agreement among raft nodes before linearized reading' (duration: 713.880232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.780851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:57.066725Z","time spent":"714.118609ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:27:57.78087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.365545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:27:57.780886Z","caller":"traceutil/trace.go:171","msg":"trace[1590540994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"714.396467ms","start":"2026-04-01T23:27:57.066484Z","end":"2026-04-01T23:27:57.780881Z","steps":["trace[1590540994] 'agreement among raft nodes before linearized reading' (duration: 714.350486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.78091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:57.066476Z","time spent":"714.427557ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:27:57.781175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.264639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:57.78121Z","caller":"traceutil/trace.go:171","msg":"trace[923832432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"144.322749ms","start":"2026-04-01T23:27:57.636875Z","end":"2026-04-01T23:27:57.781198Z","steps":["trace[923832432] 'agreement among raft nodes before linearized reading' (duration: 144.171076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.781233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.783617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:27:57.781259Z","caller":"traceutil/trace.go:171","msg":"trace[90712099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"714.860988ms","start":"2026-04-01T23:27:57.066387Z","end":"2026-04-01T23:27:57.781248Z","steps":["trace[90712099] 'agreement among raft nodes before linearized reading' (duration: 714.714754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.781279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:57.066356Z","time spent":"714.91828ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T23:27:57.780823Z","caller":"traceutil/trace.go:171","msg":"trace[1643176384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"714.057556ms","start":"2026-04-01T23:27:57.066753Z","end":"2026-04-01T23:27:57.78081Z","steps":["trace[1643176384] 'agreement among raft nodes before linearized reading' (duration: 714.002766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:57.781394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:57.066729Z","time spent":"714.651363ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:27:57.888205Z","caller":"traceutil/trace.go:171","msg":"trace[195495926] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"101.519033ms","start":"2026-04-01T23:27:57.78666Z","end":"2026-04-01T23:27:57.888179Z","steps":["trace[195495926] 'process raft request' (duration: 101.324077ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:57.957814Z","caller":"traceutil/trace.go:171","msg":"trace[267055466] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"170.566426ms","start":"2026-04-01T23:27:57.787224Z","end":"2026-04-01T23:27:57.95779Z","steps":["trace[267055466] 'process raft request' (duration: 169.918169ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:57.957977Z","caller":"traceutil/trace.go:171","msg":"trace[332544416] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"169.598969ms","start":"2026-04-01T23:27:57.788368Z","end":"2026-04-01T23:27:57.957967Z","steps":["trace[332544416] 'process raft request' (duration: 168.891361ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:57.958099Z","caller":"traceutil/trace.go:171","msg":"trace[1558952127] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"168.302246ms","start":"2026-04-01T23:27:57.789788Z","end":"2026-04-01T23:27:57.95809Z","steps":["trace[1558952127] 'process raft request' (duration: 167.503335ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:57.95823Z","caller":"traceutil/trace.go:171","msg":"trace[194813397] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"168.391939ms","start":"2026-04-01T23:27:57.78983Z","end":"2026-04-01T23:27:57.958222Z","steps":["trace[194813397] 'process raft request' (duration: 167.490354ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:58.099379Z","caller":"traceutil/trace.go:171","msg":"trace[14898901] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"101.177594ms","start":"2026-04-01T23:27:57.998183Z","end":"2026-04-01T23:27:58.099361Z","steps":["trace[14898901] 'process raft request' (duration: 101.080522ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:58.210749Z","caller":"traceutil/trace.go:171","msg":"trace[1857616227] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"158.273549ms","start":"2026-04-01T23:27:58.05245Z","end":"2026-04-01T23:27:58.210724Z","steps":["trace[1857616227] 'process raft request' (duration: 158.097135ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:58.224215Z","caller":"traceutil/trace.go:171","msg":"trace[1883688736] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"121.254958ms","start":"2026-04-01T23:27:58.102943Z","end":"2026-04-01T23:27:58.224198Z","steps":["trace[1883688736] 'process raft request' (duration: 121.033322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:59.487655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.049738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T23:27:59.487705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.923834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:59.487733Z","caller":"traceutil/trace.go:171","msg":"trace[1624182752] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1270; }","duration":"506.184721ms","start":"2026-04-01T23:27:58.981531Z","end":"2026-04-01T23:27:59.487715Z","steps":["trace[1624182752] 'count revisions from in-memory index tree' (duration: 505.912233ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:59.487765Z","caller":"traceutil/trace.go:171","msg":"trace[2048571976] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1270; }","duration":"959.046927ms","start":"2026-04-01T23:27:58.528704Z","end":"2026-04-01T23:27:59.487751Z","steps":["trace[2048571976] 'count revisions from in-memory index tree' (duration: 958.849222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:59.487779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:58.981511Z","time spent":"506.257033ms","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:27:59.487798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:58.528652Z","time spent":"959.13709ms","remote":"127.0.0.1:45310","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/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:27:59.488406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.795965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:27:59.488546Z","caller":"traceutil/trace.go:171","msg":"trace[27574663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"851.169595ms","start":"2026-04-01T23:27:58.637267Z","end":"2026-04-01T23:27:59.488436Z","steps":["trace[27574663] 'range keys from in-memory index tree' (duration: 850.672522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:27:59.488595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:58.637251Z","time spent":"851.33274ms","remote":"127.0.0.1:55176","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-04-01T23:27:59.990458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104743911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:00.490799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104743911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:00.636246Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153064375s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:28:00.636768Z","caller":"traceutil/trace.go:171","msg":"trace[379456398] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"1.146864651s","start":"2026-04-01T23:27:59.489866Z","end":"2026-04-01T23:28:00.636731Z","steps":["trace[379456398] 'read index received' (duration: 1.146591403s)","trace[379456398] 'applied index is now lower than readState.Index' (duration: 271.998µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:00.636904Z","caller":"traceutil/trace.go:171","msg":"trace[2090224697] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.162230478s","start":"2026-04-01T23:27:59.474657Z","end":"2026-04-01T23:28:00.636888Z","steps":["trace[2090224697] 'process raft request' (duration: 1.16188846s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.637064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.474634Z","time spent":"1.162322552s","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:00.637167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.606566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:00.637282Z","caller":"traceutil/trace.go:171","msg":"trace[1902109298] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1271; }","duration":"958.775761ms","start":"2026-04-01T23:27:59.678483Z","end":"2026-04-01T23:28:00.637259Z","steps":["trace[1902109298] 'agreement among raft nodes before linearized reading' (duration: 958.482453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.637381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.678434Z","time spent":"958.925575ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:00.637418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.330676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:28:00.637492Z","caller":"traceutil/trace.go:171","msg":"trace[580219099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"673.441518ms","start":"2026-04-01T23:27:59.964032Z","end":"2026-04-01T23:28:00.637474Z","steps":["trace[580219099] 'agreement among raft nodes before linearized reading' (duration: 673.220083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.637541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.964015Z","time spent":"673.5176ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:28:00.637652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.686737ms","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-04-01T23:28:00.637739Z","caller":"traceutil/trace.go:171","msg":"trace[489287817] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1271; }","duration":"202.824501ms","start":"2026-04-01T23:28:00.434896Z","end":"2026-04-01T23:28:00.637721Z","steps":["trace[489287817] 'agreement among raft nodes before linearized reading' (duration: 202.698298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.637915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.788361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:28:00.637961Z","caller":"traceutil/trace.go:171","msg":"trace[1887638053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"672.834043ms","start":"2026-04-01T23:27:59.965116Z","end":"2026-04-01T23:28:00.63795Z","steps":["trace[1887638053] 'agreement among raft nodes before linearized reading' (duration: 672.72614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.637992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.9651Z","time spent":"672.885014ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:28:00.638028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148148115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:00.638138Z","caller":"traceutil/trace.go:171","msg":"trace[888758066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"1.14829244s","start":"2026-04-01T23:27:59.489831Z","end":"2026-04-01T23:28:00.638124Z","steps":["trace[888758066] 'agreement among raft nodes before linearized reading' (duration: 1.148118675s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.638168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.147508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-01T23:28:00.638218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.489815Z","time spent":"1.148387782s","remote":"127.0.0.1:55186","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-04-01T23:28:00.638346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.548798ms","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-04-01T23:28:00.638399Z","caller":"traceutil/trace.go:171","msg":"trace[1641296214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"746.62666ms","start":"2026-04-01T23:27:59.89176Z","end":"2026-04-01T23:28:00.638387Z","steps":["trace[1641296214] 'agreement among raft nodes before linearized reading' (duration: 746.501167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.638441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.891745Z","time spent":"746.683833ms","remote":"127.0.0.1:55420","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-04-01T23:28:00.638484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.788803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:28:00.638584Z","caller":"traceutil/trace.go:171","msg":"trace[2083365294] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1271; }","duration":"784.919596ms","start":"2026-04-01T23:27:59.853648Z","end":"2026-04-01T23:28:00.638568Z","steps":["trace[2083365294] 'agreement among raft nodes before linearized reading' (duration: 784.730581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.638042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.966983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T23:28:00.638792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.853632Z","time spent":"785.045969ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:28:00.638824Z","caller":"traceutil/trace.go:171","msg":"trace[2128175652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"674.759673ms","start":"2026-04-01T23:27:59.964032Z","end":"2026-04-01T23:28:00.638792Z","steps":["trace[2128175652] 'agreement among raft nodes before linearized reading' (duration: 673.8761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.638887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.964015Z","time spent":"674.854466ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:28:00.63823Z","caller":"traceutil/trace.go:171","msg":"trace[922919580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"674.24376ms","start":"2026-04-01T23:27:59.963971Z","end":"2026-04-01T23:28:00.638215Z","steps":["trace[922919580] 'agreement among raft nodes before linearized reading' (duration: 674.108627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:00.639117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:27:59.963947Z","time spent":"675.155514ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-01T23:28:00.816591Z","caller":"traceutil/trace.go:171","msg":"trace[944290286] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"171.197033ms","start":"2026-04-01T23:28:00.645369Z","end":"2026-04-01T23:28:00.816566Z","steps":["trace[944290286] 'process raft request' (duration: 171.07279ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:00.816694Z","caller":"traceutil/trace.go:171","msg":"trace[707086811] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"172.137799ms","start":"2026-04-01T23:28:00.644538Z","end":"2026-04-01T23:28:00.816675Z","steps":["trace[707086811] 'process raft request' (duration: 121.948937ms)","trace[707086811] 'compare' (duration: 49.662828ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:00.816776Z","caller":"traceutil/trace.go:171","msg":"trace[1269100004] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1327; }","duration":"170.201087ms","start":"2026-04-01T23:28:00.64655Z","end":"2026-04-01T23:28:00.816751Z","steps":["trace[1269100004] 'read index received' (duration: 119.960553ms)","trace[1269100004] 'applied index is now lower than readState.Index' (duration: 50.239174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:00.816881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.31475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:00.816879Z","caller":"traceutil/trace.go:171","msg":"trace[643496370] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"172.001185ms","start":"2026-04-01T23:28:00.644859Z","end":"2026-04-01T23:28:00.816861Z","steps":["trace[643496370] 'process raft request' (duration: 171.525042ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:00.816959Z","caller":"traceutil/trace.go:171","msg":"trace[584686874] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"171.52472ms","start":"2026-04-01T23:28:00.645428Z","end":"2026-04-01T23:28:00.816953Z","steps":["trace[584686874] 'process raft request' (duration: 171.075849ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:00.816592Z","caller":"traceutil/trace.go:171","msg":"trace[1904683927] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"171.790008ms","start":"2026-04-01T23:28:00.644756Z","end":"2026-04-01T23:28:00.816546Z","steps":["trace[1904683927] 'process raft request' (duration: 171.556501ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:00.816899Z","caller":"traceutil/trace.go:171","msg":"trace[1436768835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"170.383421ms","start":"2026-04-01T23:28:00.646509Z","end":"2026-04-01T23:28:00.816893Z","steps":["trace[1436768835] 'agreement among raft nodes before linearized reading' (duration: 170.313659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:09.423963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104744037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:09.924287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104744037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:10.425252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133771104744037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:10.819209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.849766406s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:28:10.825449Z","caller":"traceutil/trace.go:171","msg":"trace[325489480] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"1.965523237s","start":"2026-04-01T23:28:08.859885Z","end":"2026-04-01T23:28:10.825408Z","steps":["trace[325489480] 'process raft request' (duration: 1.965324221s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:10.825464Z","caller":"traceutil/trace.go:171","msg":"trace[1228529729] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"1.966615145s","start":"2026-04-01T23:28:08.858807Z","end":"2026-04-01T23:28:10.825422Z","steps":["trace[1228529729] 'process raft request' (duration: 1.960591586s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.825718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.858789Z","time spent":"1.966820611s","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:10.825713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.859872Z","time spent":"1.965701142s","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:10.826413Z","caller":"traceutil/trace.go:171","msg":"trace[1361355277] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1394; }","duration":"1.903011768s","start":"2026-04-01T23:28:08.923383Z","end":"2026-04-01T23:28:10.826395Z","steps":["trace[1361355277] 'read index received' (duration: 1.896028393s)","trace[1361355277] 'applied index is now lower than readState.Index' (duration: 6.982425ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:10.826552Z","caller":"traceutil/trace.go:171","msg":"trace[2053052191] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.899286129s","start":"2026-04-01T23:28:08.927253Z","end":"2026-04-01T23:28:10.826539Z","steps":["trace[2053052191] 'process raft request' (duration: 1.898466147s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.827101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.927231Z","time spent":"1.89972669s","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:10.827237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490297936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-01T23:28:10.827306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.382537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-01T23:28:10.827384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189567025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:10.827437Z","caller":"traceutil/trace.go:171","msg":"trace[1683478890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"1.189648139s","start":"2026-04-01T23:28:09.637776Z","end":"2026-04-01T23:28:10.827424Z","steps":["trace[1683478890] 'agreement among raft nodes before linearized reading' (duration: 1.189569447s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.827487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:09.637758Z","time spent":"1.1897212s","remote":"127.0.0.1:55176","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-04-01T23:28:10.827502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.969376907s","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-04-01T23:28:10.827493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.769827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131092"} {"level":"info","ts":"2026-04-01T23:28:10.827539Z","caller":"traceutil/trace.go:171","msg":"trace[1590980505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"1.969462181s","start":"2026-04-01T23:28:08.858066Z","end":"2026-04-01T23:28:10.827528Z","steps":["trace[1590980505] 'agreement among raft nodes before linearized reading' (duration: 1.969355278s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:10.827559Z","caller":"traceutil/trace.go:171","msg":"trace[309753671] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1340; }","duration":"439.868991ms","start":"2026-04-01T23:28:10.387675Z","end":"2026-04-01T23:28:10.827544Z","steps":["trace[309753671] 'agreement among raft nodes before linearized reading' (duration: 439.651346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.827584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.858048Z","time spent":"1.969527653s","remote":"127.0.0.1:55420","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-04-01T23:28:10.8276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:10.387657Z","time spent":"439.936602ms","remote":"127.0.0.1:55228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":131114,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-01T23:28:10.82738Z","caller":"traceutil/trace.go:171","msg":"trace[2123093572] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1340; }","duration":"439.487601ms","start":"2026-04-01T23:28:10.38787Z","end":"2026-04-01T23:28:10.827357Z","steps":["trace[2123093572] 'agreement among raft nodes before linearized reading' (duration: 439.370398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.827777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:10.387848Z","time spent":"439.916662ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-01T23:28:10.827248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946576244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-01T23:28:10.828085Z","caller":"traceutil/trace.go:171","msg":"trace[1794156315] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"1.947364575s","start":"2026-04-01T23:28:08.880609Z","end":"2026-04-01T23:28:10.827974Z","steps":["trace[1794156315] 'agreement among raft nodes before linearized reading' (duration: 1.946410079s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.828282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.880589Z","time spent":"1.947681444s","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-01T23:28:10.828724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.223753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:28:10.828801Z","caller":"traceutil/trace.go:171","msg":"trace[262179915] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1340; }","duration":"159.280294ms","start":"2026-04-01T23:28:10.669483Z","end":"2026-04-01T23:28:10.828763Z","steps":["trace[262179915] 'agreement among raft nodes before linearized reading' (duration: 159.193942ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:10.827317Z","caller":"traceutil/trace.go:171","msg":"trace[632303651] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1340; }","duration":"1.49042296s","start":"2026-04-01T23:28:09.336874Z","end":"2026-04-01T23:28:10.827297Z","steps":["trace[632303651] 'agreement among raft nodes before linearized reading' (duration: 1.490178333s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.828955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:09.336857Z","time spent":"1.492078383s","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-01T23:28:10.828132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.024701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-01T23:28:10.829047Z","caller":"traceutil/trace.go:171","msg":"trace[1615769322] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"591.967414ms","start":"2026-04-01T23:28:10.237041Z","end":"2026-04-01T23:28:10.829008Z","steps":["trace[1615769322] 'agreement among raft nodes before linearized reading' (duration: 590.237859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:10.829114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:10.237022Z","time spent":"592.070358ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-01T23:28:10.829225Z","caller":"traceutil/trace.go:171","msg":"trace[1829523353] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"159.694285ms","start":"2026-04-01T23:28:10.669369Z","end":"2026-04-01T23:28:10.829063Z","steps":["trace[1829523353] 'process raft request' (duration: 156.974054ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:27.790631Z","caller":"traceutil/trace.go:171","msg":"trace[2136932120] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"125.048961ms","start":"2026-04-01T23:28:27.665561Z","end":"2026-04-01T23:28:27.79061Z","steps":["trace[2136932120] 'process raft request' (duration: 124.865756ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:49.296411Z","caller":"traceutil/trace.go:171","msg":"trace[330226631] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"134.607122ms","start":"2026-04-01T23:28:49.161765Z","end":"2026-04-01T23:28:49.296372Z","steps":["trace[330226631] 'process raft request' (duration: 134.093279ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:49.302743Z","caller":"traceutil/trace.go:171","msg":"trace[473210725] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1581; }","duration":"127.172297ms","start":"2026-04-01T23:28:49.175545Z","end":"2026-04-01T23:28:49.302718Z","steps":["trace[473210725] 'read index received' (duration: 120.260445ms)","trace[473210725] 'applied index is now lower than readState.Index' (duration: 6.911132ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:49.302829Z","caller":"traceutil/trace.go:171","msg":"trace[1955616886] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"140.941111ms","start":"2026-04-01T23:28:49.16186Z","end":"2026-04-01T23:28:49.302801Z","steps":["trace[1955616886] 'process raft request' (duration: 140.817308ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:49.302801Z","caller":"traceutil/trace.go:171","msg":"trace[2028291170] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"140.91332ms","start":"2026-04-01T23:28:49.161853Z","end":"2026-04-01T23:28:49.302766Z","steps":["trace[2028291170] 'process raft request' (duration: 140.693734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:49.303199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.634939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T23:28:49.303259Z","caller":"traceutil/trace.go:171","msg":"trace[228751025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1518; }","duration":"127.742701ms","start":"2026-04-01T23:28:49.175503Z","end":"2026-04-01T23:28:49.303245Z","steps":["trace[228751025] 'agreement among raft nodes before linearized reading' (duration: 127.33948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:49.303177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.927458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:49.303486Z","caller":"traceutil/trace.go:171","msg":"trace[1749671548] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1518; }","duration":"108.221966ms","start":"2026-04-01T23:28:49.195194Z","end":"2026-04-01T23:28:49.303416Z","steps":["trace[1749671548] 'agreement among raft nodes before linearized reading' (duration: 107.841806ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:01.117616Z","caller":"traceutil/trace.go:171","msg":"trace[2079862924] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"128.362298ms","start":"2026-04-01T23:29:00.989206Z","end":"2026-04-01T23:29:01.117568Z","steps":["trace[2079862924] 'process raft request' (duration: 126.040067ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:03.335798Z","caller":"traceutil/trace.go:171","msg":"trace[1894208934] linearizableReadLoop","detail":"{readStateIndex:1648; appliedIndex:1647; }","duration":"115.421206ms","start":"2026-04-01T23:29:03.220356Z","end":"2026-04-01T23:29:03.335777Z","steps":["trace[1894208934] 'read index received' (duration: 115.255593ms)","trace[1894208934] 'applied index is now lower than readState.Index' (duration: 164.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:03.335915Z","caller":"traceutil/trace.go:171","msg":"trace[431872904] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"213.127106ms","start":"2026-04-01T23:29:03.122762Z","end":"2026-04-01T23:29:03.335889Z","steps":["trace[431872904] 'process raft request' (duration: 212.89703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:03.336155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.738925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:29:03.337296Z","caller":"traceutil/trace.go:171","msg":"trace[102697444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1579; }","duration":"116.933107ms","start":"2026-04-01T23:29:03.220306Z","end":"2026-04-01T23:29:03.337239Z","steps":["trace[102697444] 'agreement among raft nodes before linearized reading' (duration: 115.651773ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:03.483597Z","caller":"traceutil/trace.go:171","msg":"trace[1160811467] linearizableReadLoop","detail":"{readStateIndex:1649; appliedIndex:1648; }","duration":"113.498566ms","start":"2026-04-01T23:29:03.370073Z","end":"2026-04-01T23:29:03.483571Z","steps":["trace[1160811467] 'read index received' (duration: 39.566584ms)","trace[1160811467] 'applied index is now lower than readState.Index' (duration: 73.931092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:03.483832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.750692ms","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-04-01T23:29:03.48387Z","caller":"traceutil/trace.go:171","msg":"trace[1081933405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1580; }","duration":"113.845665ms","start":"2026-04-01T23:29:03.370015Z","end":"2026-04-01T23:29:03.48386Z","steps":["trace[1081933405] 'agreement among raft nodes before linearized reading' (duration: 113.695542ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:03.484099Z","caller":"traceutil/trace.go:171","msg":"trace[229114886] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"142.381758ms","start":"2026-04-01T23:29:03.341239Z","end":"2026-04-01T23:29:03.483621Z","steps":["trace[229114886] 'process raft request' (duration: 68.460517ms)","trace[229114886] 'compare' (duration: 73.764298ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:07.725917Z","caller":"traceutil/trace.go:171","msg":"trace[1001502411] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"122.766281ms","start":"2026-04-01T23:29:07.603119Z","end":"2026-04-01T23:29:07.725886Z","steps":["trace[1001502411] 'process raft request' (duration: 115.236622ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:07.725927Z","caller":"traceutil/trace.go:171","msg":"trace[1866747378] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"122.520424ms","start":"2026-04-01T23:29:07.60338Z","end":"2026-04-01T23:29:07.725901Z","steps":["trace[1866747378] 'process raft request' (duration: 122.436052ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:07.726586Z","caller":"traceutil/trace.go:171","msg":"trace[929380281] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"123.077679ms","start":"2026-04-01T23:29:07.603488Z","end":"2026-04-01T23:29:07.726565Z","steps":["trace[929380281] 'process raft request' (duration: 122.374041ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:09.639545Z","caller":"traceutil/trace.go:171","msg":"trace[1898477502] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"119.558526ms","start":"2026-04-01T23:29:09.519964Z","end":"2026-04-01T23:29:09.639523Z","steps":["trace[1898477502] 'process raft request' (duration: 119.140805ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:21.848711Z","caller":"traceutil/trace.go:171","msg":"trace[183771809] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"130.140461ms","start":"2026-04-01T23:29:21.718532Z","end":"2026-04-01T23:29:21.848673Z","steps":["trace[183771809] 'process raft request' (duration: 129.973167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:22.093318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.777558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-01T23:29:22.093425Z","caller":"traceutil/trace.go:171","msg":"trace[1718326896] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1666; }","duration":"127.906221ms","start":"2026-04-01T23:29:21.96549Z","end":"2026-04-01T23:29:22.093396Z","steps":["trace[1718326896] 'range keys from in-memory index tree' (duration: 127.611984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:22.093395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.750917ms","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-04-01T23:29:22.093576Z","caller":"traceutil/trace.go:171","msg":"trace[470869491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1666; }","duration":"136.939112ms","start":"2026-04-01T23:29:21.956579Z","end":"2026-04-01T23:29:22.093518Z","steps":["trace[470869491] 'range keys from in-memory index tree' (duration: 136.558002ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:22.258079Z","caller":"traceutil/trace.go:171","msg":"trace[2085768694] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"158.599075ms","start":"2026-04-01T23:29:22.099449Z","end":"2026-04-01T23:29:22.258048Z","steps":["trace[2085768694] 'process raft request' (duration: 109.089533ms)","trace[2085768694] 'compare' (duration: 49.186664ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:50.2818Z","caller":"traceutil/trace.go:171","msg":"trace[1955074205] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"190.258575ms","start":"2026-04-01T23:29:50.091486Z","end":"2026-04-01T23:29:50.281745Z","steps":["trace[1955074205] 'process raft request' (duration: 146.632178ms)","trace[1955074205] 'compare' (duration: 43.347461ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:51.729311Z","caller":"traceutil/trace.go:171","msg":"trace[1619628999] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"107.695286ms","start":"2026-04-01T23:29:51.621576Z","end":"2026-04-01T23:29:51.729271Z","steps":["trace[1619628999] 'process raft request' (duration: 107.47324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:56.508774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.586884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-01T23:29:56.508847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.28843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-01T23:29:56.508888Z","caller":"traceutil/trace.go:171","msg":"trace[12230161] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1820; }","duration":"170.742758ms","start":"2026-04-01T23:29:56.33812Z","end":"2026-04-01T23:29:56.508862Z","steps":["trace[12230161] 'range keys from in-memory index tree' (duration: 170.46433ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:56.508903Z","caller":"traceutil/trace.go:171","msg":"trace[344780532] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1820; }","duration":"261.381963ms","start":"2026-04-01T23:29:56.247504Z","end":"2026-04-01T23:29:56.508886Z","steps":["trace[344780532] 'range keys from in-memory index tree' (duration: 261.151947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:56.509113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.672299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-01T23:29:56.509201Z","caller":"traceutil/trace.go:171","msg":"trace[883973270] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1820; }","duration":"201.803431ms","start":"2026-04-01T23:29:56.30738Z","end":"2026-04-01T23:29:56.509184Z","steps":["trace[883973270] 'range keys from in-memory index tree' (duration: 201.529894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:56.509722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.631387ms","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-04-01T23:29:56.509758Z","caller":"traceutil/trace.go:171","msg":"trace[108935909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1820; }","duration":"201.686879ms","start":"2026-04-01T23:29:56.30806Z","end":"2026-04-01T23:29:56.509747Z","steps":["trace[108935909] 'range keys from in-memory index tree' (duration: 200.425366ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:04.38368Z","caller":"traceutil/trace.go:171","msg":"trace[1478916591] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"121.498172ms","start":"2026-04-01T23:30:04.26216Z","end":"2026-04-01T23:30:04.383658Z","steps":["trace[1478916591] 'process raft request' (duration: 86.846953ms)","trace[1478916591] 'compare' (duration: 34.533956ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:16.665263Z","caller":"traceutil/trace.go:171","msg":"trace[712258384] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1993; }","duration":"376.725663ms","start":"2026-04-01T23:30:16.288511Z","end":"2026-04-01T23:30:16.665236Z","steps":["trace[712258384] 'read index received' (duration: 376.470595ms)","trace[712258384] 'applied index is now lower than readState.Index' (duration: 253.847µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:16.665325Z","caller":"traceutil/trace.go:171","msg":"trace[492014693] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"590.610284ms","start":"2026-04-01T23:30:16.074687Z","end":"2026-04-01T23:30:16.665297Z","steps":["trace[492014693] 'process raft request' (duration: 590.370038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:16.665468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:16.074663Z","time spent":"590.721326ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:16.665731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.192505ms","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-04-01T23:30:16.665787Z","caller":"traceutil/trace.go:171","msg":"trace[249713450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"377.308346ms","start":"2026-04-01T23:30:16.288463Z","end":"2026-04-01T23:30:16.665771Z","steps":["trace[249713450] 'agreement among raft nodes before linearized reading' (duration: 376.922166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:16.665819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.990976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T23:30:16.665846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:16.288444Z","time spent":"377.389079ms","remote":"127.0.0.1:55420","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":"info","ts":"2026-04-01T23:30:16.665881Z","caller":"traceutil/trace.go:171","msg":"trace[1186237289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1911; }","duration":"234.090438ms","start":"2026-04-01T23:30:16.431776Z","end":"2026-04-01T23:30:16.665867Z","steps":["trace[1186237289] 'agreement among raft nodes before linearized reading' (duration: 233.707638ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:15.402323Z","caller":"traceutil/trace.go:171","msg":"trace[510576710] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"106.058193ms","start":"2026-04-01T23:31:15.296227Z","end":"2026-04-01T23:31:15.402286Z","steps":["trace[510576710] 'process raft request' (duration: 54.790813ms)","trace[510576710] 'compare' (duration: 51.036534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:25.555098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.540865ms","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-04-01T23:31:25.555198Z","caller":"traceutil/trace.go:171","msg":"trace[658736177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2252; }","duration":"428.650088ms","start":"2026-04-01T23:31:25.126528Z","end":"2026-04-01T23:31:25.555178Z","steps":["trace[658736177] 'agreement among raft nodes before linearized reading' (duration: 428.426851ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:25.554734Z","caller":"traceutil/trace.go:171","msg":"trace[1378559088] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"483.552853ms","start":"2026-04-01T23:31:25.071116Z","end":"2026-04-01T23:31:25.554669Z","steps":["trace[1378559088] 'process raft request' (duration: 483.211595ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:25.554574Z","caller":"traceutil/trace.go:171","msg":"trace[539736383] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2349; }","duration":"428.003721ms","start":"2026-04-01T23:31:25.126538Z","end":"2026-04-01T23:31:25.554541Z","steps":["trace[539736383] 'read index received' (duration: 427.665242ms)","trace[539736383] 'applied index is now lower than readState.Index' (duration: 336.849µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:25.555241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:25.126459Z","time spent":"428.772701ms","remote":"127.0.0.1:55420","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":"warn","ts":"2026-04-01T23:31:25.555587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:25.071101Z","time spent":"484.238981ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:25.555738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.987486ms","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-04-01T23:31:25.555826Z","caller":"traceutil/trace.go:171","msg":"trace[1888315891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2252; }","duration":"429.175102ms","start":"2026-04-01T23:31:25.126635Z","end":"2026-04-01T23:31:25.55581Z","steps":["trace[1888315891] 'agreement among raft nodes before linearized reading' (duration: 428.880215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:25.555903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:25.126626Z","time spent":"429.262954ms","remote":"127.0.0.1:55420","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-04-01T23:31:25.556023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.595449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-01T23:31:25.556109Z","caller":"traceutil/trace.go:171","msg":"trace[1575013845] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2252; }","duration":"400.805975ms","start":"2026-04-01T23:31:25.155279Z","end":"2026-04-01T23:31:25.556085Z","steps":["trace[1575013845] 'agreement among raft nodes before linearized reading' (duration: 400.331993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:25.556255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:25.155225Z","time spent":"400.926068ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-01T23:31:25.667657Z","caller":"traceutil/trace.go:171","msg":"trace[9177278] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"106.874335ms","start":"2026-04-01T23:31:25.560764Z","end":"2026-04-01T23:31:25.667639Z","steps":["trace[9177278] 'process raft request' (duration: 106.572397ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:25.667761Z","caller":"traceutil/trace.go:171","msg":"trace[145454832] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"106.445483ms","start":"2026-04-01T23:31:25.561308Z","end":"2026-04-01T23:31:25.667754Z","steps":["trace[145454832] 'process raft request' (duration: 106.110935ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:33.29003Z","caller":"traceutil/trace.go:171","msg":"trace[1587810932] linearizableReadLoop","detail":"{readStateIndex:2392; appliedIndex:2391; }","duration":"225.400056ms","start":"2026-04-01T23:31:33.064599Z","end":"2026-04-01T23:31:33.29Z","steps":["trace[1587810932] 'read index received' (duration: 225.124879ms)","trace[1587810932] 'applied index is now lower than readState.Index' (duration: 274.147µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:33.290163Z","caller":"traceutil/trace.go:171","msg":"trace[1990026397] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"450.856363ms","start":"2026-04-01T23:31:32.839216Z","end":"2026-04-01T23:31:33.290072Z","steps":["trace[1990026397] 'process raft request' (duration: 450.611847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:33.290331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.711205ms","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-04-01T23:31:33.29054Z","caller":"traceutil/trace.go:171","msg":"trace[631989285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2292; }","duration":"225.932951ms","start":"2026-04-01T23:31:33.064584Z","end":"2026-04-01T23:31:33.290517Z","steps":["trace[631989285] 'agreement among raft nodes before linearized reading' (duration: 225.582222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:33.290731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.041608ms","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-04-01T23:31:33.290846Z","caller":"traceutil/trace.go:171","msg":"trace[1991533517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2292; }","duration":"222.157991ms","start":"2026-04-01T23:31:33.068627Z","end":"2026-04-01T23:31:33.290785Z","steps":["trace[1991533517] 'agreement among raft nodes before linearized reading' (duration: 221.817951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:33.291123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:32.839183Z","time spent":"451.086929ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}