{"level":"warn","ts":"2026-06-26T10:23:21.998033Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T10:23:21.998143Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-06-26T10:23:21.998244Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-26T10:23:21.998264Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-26T10:23:21.998292Z","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-06-26T10:23:21.999179Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-06-26T10:23:21.999386Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-06-26T10:23:22.001985Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.266533ms"} {"level":"info","ts":"2026-06-26T10:23:22.00892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-06-26T10:23:22.008982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-06-26T10:23:22.009001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-06-26T10:23:22.009019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-26T10:23:22.009033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-06-26T10:23:22.009074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-06-26T10:23:22.014204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-26T10:23:22.015872Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-26T10:23:22.017166Z","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-06-26T10:23:22.020431Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-26T10:23:22.020735Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-26T10:23:22.020853Z","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-06-26T10:23:22.02088Z","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-06-26T10:23:22.020887Z","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-06-26T10:23:22.027405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-06-26T10:23:22.028871Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-26T10:23:22.030968Z","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-06-26T10:23:22.031334Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-26T10:23:22.0314Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-26T10:23:22.031512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-26T10:23:22.031592Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-26T10:23:22.709426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-06-26T10:23:22.70948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-26T10:23:22.709512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-06-26T10:23:22.709533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-06-26T10:23:22.709544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-26T10:23:22.70956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-06-26T10:23:22.709579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-26T10:23:22.710502Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:23:22.711275Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T10:23:22.711274Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-06-26T10:23:22.711341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T10:23:22.711789Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:23:22.711871Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:23:22.711902Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T10:23:22.712191Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-26T10:23:22.712258Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-26T10:23:22.714863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-26T10:23:22.716508Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-06-26T10:23:33.616887Z","caller":"traceutil/trace.go:171","msg":"trace[749040146] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"368.250735ms","start":"2026-06-26T10:23:33.24855Z","end":"2026-06-26T10:23:33.616801Z","steps":["trace[749040146] 'read index received' (duration: 367.464864ms)","trace[749040146] 'applied index is now lower than readState.Index' (duration: 784.331µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:23:33.617384Z","caller":"traceutil/trace.go:171","msg":"trace[1742462082] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"451.795919ms","start":"2026-06-26T10:23:33.16557Z","end":"2026-06-26T10:23:33.617366Z","steps":["trace[1742462082] 'process raft request' (duration: 450.888644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:23:33.617784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.544346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:23:33.617877Z","caller":"traceutil/trace.go:171","msg":"trace[1326063860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:355; }","duration":"222.66552ms","start":"2026-06-26T10:23:33.395182Z","end":"2026-06-26T10:23:33.617847Z","steps":["trace[1326063860] 'agreement among raft nodes before linearized reading' (duration: 222.538455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:23:33.619283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:33.165551Z","time spent":"451.882402ms","remote":"127.0.0.1:42822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7270,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:23:33.62041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.870397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-26T10:23:33.620619Z","caller":"traceutil/trace.go:171","msg":"trace[744886364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:355; }","duration":"372.120204ms","start":"2026-06-26T10:23:33.248477Z","end":"2026-06-26T10:23:33.620597Z","steps":["trace[744886364] 'agreement among raft nodes before linearized reading' (duration: 369.167422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:23:33.620787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:33.248431Z","time spent":"372.321871ms","remote":"127.0.0.1:42904","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-06-26T10:23:33.725746Z","caller":"traceutil/trace.go:171","msg":"trace[20899533] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"101.71989ms","start":"2026-06-26T10:23:33.624001Z","end":"2026-06-26T10:23:33.725721Z","steps":["trace[20899533] 'process raft request' (duration: 99.110477ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:23:33.973324Z","caller":"traceutil/trace.go:171","msg":"trace[205479043] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"343.315468ms","start":"2026-06-26T10:23:33.629965Z","end":"2026-06-26T10:23:33.97328Z","steps":["trace[205479043] 'process raft request' (duration: 310.001938ms)","trace[205479043] 'compare' (duration: 33.130125ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:23:33.973564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:33.629944Z","time spent":"343.495392ms","remote":"127.0.0.1:42822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7078,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:24:00.455467Z","caller":"traceutil/trace.go:171","msg":"trace[1836066977] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"605.353798ms","start":"2026-06-26T10:23:59.850082Z","end":"2026-06-26T10:24:00.455435Z","steps":["trace[1836066977] 'process raft request' (duration: 605.185593ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.455554Z","caller":"traceutil/trace.go:171","msg":"trace[893945278] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"605.447551ms","start":"2026-06-26T10:23:59.850082Z","end":"2026-06-26T10:24:00.455529Z","steps":["trace[893945278] 'process raft request' (duration: 605.246755ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.455633Z","caller":"traceutil/trace.go:171","msg":"trace[2025620366] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"605.443821ms","start":"2026-06-26T10:23:59.85015Z","end":"2026-06-26T10:24:00.455593Z","steps":["trace[2025620366] 'process raft request' (duration: 605.270797ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.455695Z","caller":"traceutil/trace.go:171","msg":"trace[50048150] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"605.520022ms","start":"2026-06-26T10:23:59.850155Z","end":"2026-06-26T10:24:00.455675Z","steps":["trace[50048150] 'process raft request' (duration: 605.315667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.455712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.850063Z","time spent":"605.578425ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:24:00.455755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.850145Z","time spent":"605.545924ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:24:00.455785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.850151Z","time spent":"605.591766ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T10:24:00.455924Z","caller":"traceutil/trace.go:171","msg":"trace[1559957008] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"603.105586ms","start":"2026-06-26T10:23:59.852805Z","end":"2026-06-26T10:24:00.455911Z","steps":["trace[1559957008] 'process raft request' (duration: 602.710495ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.455909Z","caller":"traceutil/trace.go:171","msg":"trace[956781802] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"614.01335ms","start":"2026-06-26T10:23:59.841448Z","end":"2026-06-26T10:24:00.455461Z","steps":["trace[956781802] 'process raft request' (duration: 613.449745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.455964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.852785Z","time spent":"603.160158ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:24:00.456094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.850064Z","time spent":"605.476092ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T10:24:00.456383Z","caller":"traceutil/trace.go:171","msg":"trace[2145048633] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"571.266807ms","start":"2026-06-26T10:23:59.885106Z","end":"2026-06-26T10:24:00.456372Z","steps":["trace[2145048633] 'process raft request' (duration: 571.157093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.456425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.885082Z","time spent":"571.323737ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T10:24:00.456426Z","caller":"traceutil/trace.go:171","msg":"trace[1843031587] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"573.233431ms","start":"2026-06-26T10:23:59.883098Z","end":"2026-06-26T10:24:00.456332Z","steps":["trace[1843031587] 'process raft request' (duration: 572.47474ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.456546Z","caller":"traceutil/trace.go:171","msg":"trace[610966200] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"571.355668ms","start":"2026-06-26T10:23:59.885182Z","end":"2026-06-26T10:24:00.456538Z","steps":["trace[610966200] 'process raft request' (duration: 571.202004ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.456552Z","caller":"traceutil/trace.go:171","msg":"trace[102109809] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:506; }","duration":"605.300297ms","start":"2026-06-26T10:23:59.851227Z","end":"2026-06-26T10:24:00.456527Z","steps":["trace[102109809] 'read index received' (duration: 83.043322ms)","trace[102109809] 'applied index is now lower than readState.Index' (duration: 522.255505ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:24:00.456626Z","caller":"traceutil/trace.go:171","msg":"trace[353366051] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"547.581324ms","start":"2026-06-26T10:23:59.90904Z","end":"2026-06-26T10:24:00.456621Z","steps":["trace[353366051] 'process raft request' (duration: 547.421439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.456604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.883077Z","time spent":"573.449117ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:24:00.456652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.909019Z","time spent":"547.623675ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:24:00.456588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.885178Z","time spent":"571.393979ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:24:00.456743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.496223ms","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-06-26T10:24:00.456799Z","caller":"traceutil/trace.go:171","msg":"trace[980933586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"605.572344ms","start":"2026-06-26T10:23:59.851208Z","end":"2026-06-26T10:24:00.45678Z","steps":["trace[980933586] 'agreement among raft nodes before linearized reading' (duration: 605.438731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.456846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.851202Z","time spent":"605.631216ms","remote":"127.0.0.1:42904","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-06-26T10:24:00.45694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.841431Z","time spent":"614.559086ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T10:24:00.467269Z","caller":"traceutil/trace.go:171","msg":"trace[1678046840] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"519.271552ms","start":"2026-06-26T10:23:59.947984Z","end":"2026-06-26T10:24:00.467255Z","steps":["trace[1678046840] 'process raft request' (duration: 518.775698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.467446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.947957Z","time spent":"519.445527ms","remote":"127.0.0.1:43170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T10:24:00.467688Z","caller":"traceutil/trace.go:171","msg":"trace[392936089] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"518.230203ms","start":"2026-06-26T10:23:59.94945Z","end":"2026-06-26T10:24:00.46768Z","steps":["trace[392936089] 'process raft request' (duration: 517.38712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.467736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.949426Z","time spent":"518.278555ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:24:00.467862Z","caller":"traceutil/trace.go:171","msg":"trace[1506030181] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"517.222486ms","start":"2026-06-26T10:23:59.950633Z","end":"2026-06-26T10:24:00.467856Z","steps":["trace[1506030181] 'process raft request' (duration: 516.240888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.467903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:23:59.950616Z","time spent":"517.260726ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:24:00.468136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.491887ms","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-06-26T10:24:00.468159Z","caller":"traceutil/trace.go:171","msg":"trace[738334567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"160.590558ms","start":"2026-06-26T10:24:00.307561Z","end":"2026-06-26T10:24:00.468152Z","steps":["trace[738334567] 'agreement among raft nodes before linearized reading' (duration: 160.473205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.468281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.135784ms","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-06-26T10:24:00.4683Z","caller":"traceutil/trace.go:171","msg":"trace[393342704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"156.181375ms","start":"2026-06-26T10:24:00.312112Z","end":"2026-06-26T10:24:00.468293Z","steps":["trace[393342704] 'agreement among raft nodes before linearized reading' (duration: 156.126164ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:00.471202Z","caller":"traceutil/trace.go:171","msg":"trace[808808196] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"464.052339ms","start":"2026-06-26T10:24:00.002918Z","end":"2026-06-26T10:24:00.466971Z","steps":["trace[808808196] 'process raft request' (duration: 464.000057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:00.471276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:00.002867Z","time spent":"468.37723ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T10:24:06.588257Z","caller":"traceutil/trace.go:171","msg":"trace[2049122553] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"110.487177ms","start":"2026-06-26T10:24:06.477732Z","end":"2026-06-26T10:24:06.588219Z","steps":["trace[2049122553] 'read index received' (duration: 108.068469ms)","trace[2049122553] 'applied index is now lower than readState.Index' (duration: 2.417198ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:24:06.58841Z","caller":"traceutil/trace.go:171","msg":"trace[314547687] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"122.079252ms","start":"2026-06-26T10:24:06.466265Z","end":"2026-06-26T10:24:06.588344Z","steps":["trace[314547687] 'process raft request' (duration: 120.339793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:06.588706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.942781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2k5sx\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-06-26T10:24:06.588758Z","caller":"traceutil/trace.go:171","msg":"trace[1685457567] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2k5sx; range_end:; response_count:1; response_revision:584; }","duration":"111.053704ms","start":"2026-06-26T10:24:06.477687Z","end":"2026-06-26T10:24:06.588741Z","steps":["trace[1685457567] 'agreement among raft nodes before linearized reading' (duration: 110.850768ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:06.864717Z","caller":"traceutil/trace.go:171","msg":"trace[1608640790] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"270.951343ms","start":"2026-06-26T10:24:06.593719Z","end":"2026-06-26T10:24:06.86467Z","steps":["trace[1608640790] 'process raft request' (duration: 183.385795ms)","trace[1608640790] 'compare' (duration: 87.284959ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:24:06.864781Z","caller":"traceutil/trace.go:171","msg":"trace[1650595678] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"261.752695ms","start":"2026-06-26T10:24:06.602988Z","end":"2026-06-26T10:24:06.864741Z","steps":["trace[1650595678] 'process raft request' (duration: 261.674813ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:06.864801Z","caller":"traceutil/trace.go:171","msg":"trace[1047559832] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"271.029325ms","start":"2026-06-26T10:24:06.593745Z","end":"2026-06-26T10:24:06.864775Z","steps":["trace[1047559832] 'process raft request' (duration: 270.828959ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:06.872187Z","caller":"traceutil/trace.go:171","msg":"trace[1788342375] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:610; }","duration":"214.681679ms","start":"2026-06-26T10:24:06.657474Z","end":"2026-06-26T10:24:06.872155Z","steps":["trace[1788342375] 'read index received' (duration: 119.745707ms)","trace[1788342375] 'applied index is now lower than readState.Index' (duration: 94.934742ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:24:06.872534Z","caller":"traceutil/trace.go:171","msg":"trace[2139380227] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"217.383205ms","start":"2026-06-26T10:24:06.655122Z","end":"2026-06-26T10:24:06.872505Z","steps":["trace[2139380227] 'process raft request' (duration: 216.867731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:06.872561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.781415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:24:06.872632Z","caller":"traceutil/trace.go:171","msg":"trace[762098610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"125.899139ms","start":"2026-06-26T10:24:06.746708Z","end":"2026-06-26T10:24:06.872608Z","steps":["trace[762098610] 'agreement among raft nodes before linearized reading' (duration: 125.796176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:06.872672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.175453ms","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-06-26T10:24:06.872732Z","caller":"traceutil/trace.go:171","msg":"trace[2020985054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"215.285607ms","start":"2026-06-26T10:24:06.657431Z","end":"2026-06-26T10:24:06.872717Z","steps":["trace[2020985054] 'agreement among raft nodes before linearized reading' (duration: 214.996549ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:07.865984Z","caller":"traceutil/trace.go:171","msg":"trace[334099551] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"575.722239ms","start":"2026-06-26T10:24:07.290231Z","end":"2026-06-26T10:24:07.865953Z","steps":["trace[334099551] 'process raft request' (duration: 574.350531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:07.866217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:07.290204Z","time spent":"575.922205ms","remote":"127.0.0.1:42712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:24:23.264154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.62621ms","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-06-26T10:24:23.264252Z","caller":"traceutil/trace.go:171","msg":"trace[1378480134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"325.783064ms","start":"2026-06-26T10:24:22.938451Z","end":"2026-06-26T10:24:23.264234Z","steps":["trace[1378480134] 'range keys from in-memory index tree' (duration: 325.422714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:23.264293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:22.938428Z","time spent":"325.855836ms","remote":"127.0.0.1:42904","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-06-26T10:24:23.264457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.284149ms","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-06-26T10:24:23.264522Z","caller":"traceutil/trace.go:171","msg":"trace[356525362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"321.410832ms","start":"2026-06-26T10:24:22.943095Z","end":"2026-06-26T10:24:23.264506Z","steps":["trace[356525362] 'range keys from in-memory index tree' (duration: 321.032872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:23.264557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:22.943081Z","time spent":"321.466174ms","remote":"127.0.0.1:42904","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-06-26T10:24:23.264612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.726162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-26T10:24:23.264669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.75099ms","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-06-26T10:24:23.264716Z","caller":"traceutil/trace.go:171","msg":"trace[1578177988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"317.830112ms","start":"2026-06-26T10:24:22.946872Z","end":"2026-06-26T10:24:23.264702Z","steps":["trace[1578177988] 'range keys from in-memory index tree' (duration: 317.626476ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:23.264712Z","caller":"traceutil/trace.go:171","msg":"trace[1976790933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"520.871857ms","start":"2026-06-26T10:24:22.743817Z","end":"2026-06-26T10:24:23.264689Z","steps":["trace[1976790933] 'range keys from in-memory index tree' (duration: 520.64473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:23.264745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:22.946856Z","time spent":"317.882553ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T10:24:23.264765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:22.743801Z","time spent":"520.953839ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T10:24:23.264973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.19898ms","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-06-26T10:24:23.265043Z","caller":"traceutil/trace.go:171","msg":"trace[1393227719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"480.314643ms","start":"2026-06-26T10:24:22.784711Z","end":"2026-06-26T10:24:23.265026Z","steps":["trace[1393227719] 'range keys from in-memory index tree' (duration: 479.981034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:23.265092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:24:22.784689Z","time spent":"480.388475ms","remote":"127.0.0.1:42904","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-06-26T10:24:23.373957Z","caller":"traceutil/trace.go:171","msg":"trace[885219302] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"104.627133ms","start":"2026-06-26T10:24:23.269302Z","end":"2026-06-26T10:24:23.373929Z","steps":["trace[885219302] 'process raft request' (duration: 104.427928ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:23.446926Z","caller":"traceutil/trace.go:171","msg":"trace[725003020] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"176.977325ms","start":"2026-06-26T10:24:23.269931Z","end":"2026-06-26T10:24:23.446909Z","steps":["trace[725003020] 'process raft request' (duration: 176.942525ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:23.446977Z","caller":"traceutil/trace.go:171","msg":"trace[1882367658] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"177.48935ms","start":"2026-06-26T10:24:23.269421Z","end":"2026-06-26T10:24:23.44691Z","steps":["trace[1882367658] 'process raft request' (duration: 177.331706ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:23.447022Z","caller":"traceutil/trace.go:171","msg":"trace[289334723] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"177.514452ms","start":"2026-06-26T10:24:23.269467Z","end":"2026-06-26T10:24:23.446982Z","steps":["trace[289334723] 'process raft request' (duration: 177.364797ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:24:56.639607Z","caller":"traceutil/trace.go:171","msg":"trace[2047974594] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"244.33392ms","start":"2026-06-26T10:24:56.395245Z","end":"2026-06-26T10:24:56.639579Z","steps":["trace[2047974594] 'process raft request' (duration: 244.166696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:24:57.006082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.36265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:24:57.006222Z","caller":"traceutil/trace.go:171","msg":"trace[2085913737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"260.562435ms","start":"2026-06-26T10:24:56.745625Z","end":"2026-06-26T10:24:57.006187Z","steps":["trace[2085913737] 'range keys from in-memory index tree' (duration: 260.275827ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:21.875715Z","caller":"traceutil/trace.go:171","msg":"trace[1497759657] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"179.412425ms","start":"2026-06-26T10:25:21.696257Z","end":"2026-06-26T10:25:21.87567Z","steps":["trace[1497759657] 'read index received' (duration: 179.300131ms)","trace[1497759657] 'applied index is now lower than readState.Index' (duration: 111.033µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:25:21.875872Z","caller":"traceutil/trace.go:171","msg":"trace[2124059797] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"235.385617ms","start":"2026-06-26T10:25:21.640474Z","end":"2026-06-26T10:25:21.87586Z","steps":["trace[2124059797] 'process raft request' (duration: 235.035748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:21.876065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.775064ms","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-06-26T10:25:21.876125Z","caller":"traceutil/trace.go:171","msg":"trace[1601843863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1054; }","duration":"179.884649ms","start":"2026-06-26T10:25:21.696223Z","end":"2026-06-26T10:25:21.876108Z","steps":["trace[1601843863] 'agreement among raft nodes before linearized reading' (duration: 179.775556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:21.87631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.021116ms","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-06-26T10:25:21.876442Z","caller":"traceutil/trace.go:171","msg":"trace[507989347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"146.200822ms","start":"2026-06-26T10:25:21.730216Z","end":"2026-06-26T10:25:21.876417Z","steps":["trace[507989347] 'agreement among raft nodes before linearized reading' (duration: 145.927933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:21.876492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.348945ms","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-06-26T10:25:21.876551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.249572ms","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-06-26T10:25:21.876598Z","caller":"traceutil/trace.go:171","msg":"trace[249002866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"141.470759ms","start":"2026-06-26T10:25:21.735103Z","end":"2026-06-26T10:25:21.876574Z","steps":["trace[249002866] 'agreement among raft nodes before linearized reading' (duration: 141.114279ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:21.876636Z","caller":"traceutil/trace.go:171","msg":"trace[1084410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"146.344644ms","start":"2026-06-26T10:25:21.730271Z","end":"2026-06-26T10:25:21.876615Z","steps":["trace[1084410] 'agreement among raft nodes before linearized reading' (duration: 146.130208ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:22.867733Z","caller":"traceutil/trace.go:171","msg":"trace[1658463763] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"105.702511ms","start":"2026-06-26T10:25:22.762013Z","end":"2026-06-26T10:25:22.867715Z","steps":["trace[1658463763] 'process raft request' (duration: 105.544667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:24.180603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.177779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T10:25:24.180928Z","caller":"traceutil/trace.go:171","msg":"trace[1484453348] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"198.225344ms","start":"2026-06-26T10:25:23.982681Z","end":"2026-06-26T10:25:24.180907Z","steps":["trace[1484453348] 'process raft request' (duration: 198.164552ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:24.181161Z","caller":"traceutil/trace.go:171","msg":"trace[1287593748] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"199.271172ms","start":"2026-06-26T10:25:23.981853Z","end":"2026-06-26T10:25:24.181124Z","steps":["trace[1287593748] 'process raft request' (duration: 41.28013ms)","trace[1287593748] 'compare' (duration: 157.011474ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:25:24.1812Z","caller":"traceutil/trace.go:171","msg":"trace[275338958] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"198.783179ms","start":"2026-06-26T10:25:23.98237Z","end":"2026-06-26T10:25:24.181153Z","steps":["trace[275338958] 'process raft request' (duration: 198.400829ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:39.194187Z","caller":"traceutil/trace.go:171","msg":"trace[669252377] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"206.576318ms","start":"2026-06-26T10:25:38.987575Z","end":"2026-06-26T10:25:39.194151Z","steps":["trace[669252377] 'process raft request' (duration: 206.436875ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:40.77702Z","caller":"traceutil/trace.go:171","msg":"trace[313804545] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"511.595635ms","start":"2026-06-26T10:25:40.265405Z","end":"2026-06-26T10:25:40.777001Z","steps":["trace[313804545] 'read index received' (duration: 120.214µs)","trace[313804545] 'applied index is now lower than readState.Index' (duration: 511.474641ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:25:40.777083Z","caller":"traceutil/trace.go:171","msg":"trace[330694074] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"511.665116ms","start":"2026-06-26T10:25:40.265394Z","end":"2026-06-26T10:25:40.777059Z","steps":["trace[330694074] 'process raft request' (duration: 511.483002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:40.777157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.757379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-26T10:25:40.777175Z","caller":"traceutil/trace.go:171","msg":"trace[1445078046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"511.83261ms","start":"2026-06-26T10:25:40.265336Z","end":"2026-06-26T10:25:40.777168Z","steps":["trace[1445078046] 'agreement among raft nodes before linearized reading' (duration: 511.744859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:40.777191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:25:40.265308Z","time spent":"511.879343ms","remote":"127.0.0.1:42904","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-06-26T10:25:40.777193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:25:40.265381Z","time spent":"511.747268ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:25:41.253508Z","caller":"traceutil/trace.go:171","msg":"trace[823717395] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"986.383813ms","start":"2026-06-26T10:25:40.267094Z","end":"2026-06-26T10:25:41.253478Z","steps":["trace[823717395] 'process raft request' (duration: 953.54112ms)","trace[823717395] 'compare' (duration: 32.530464ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:25:41.253645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:25:40.267072Z","time spent":"986.502946ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:25:41.308991Z","caller":"traceutil/trace.go:171","msg":"trace[967006574] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1215; }","duration":"109.92604ms","start":"2026-06-26T10:25:41.199048Z","end":"2026-06-26T10:25:41.308974Z","steps":["trace[967006574] 'read index received' (duration: 21.595947ms)","trace[967006574] 'applied index is now lower than readState.Index' (duration: 88.329133ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:25:41.309069Z","caller":"traceutil/trace.go:171","msg":"trace[1873989168] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"528.442498ms","start":"2026-06-26T10:25:40.780613Z","end":"2026-06-26T10:25:41.309055Z","steps":["trace[1873989168] 'process raft request' (duration: 528.201251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:41.309145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.101235ms","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-06-26T10:25:41.309152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:25:40.780593Z","time spent":"528.50265ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:25:41.30918Z","caller":"traceutil/trace.go:171","msg":"trace[1196929300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1167; }","duration":"110.25823ms","start":"2026-06-26T10:25:41.198911Z","end":"2026-06-26T10:25:41.309169Z","steps":["trace[1196929300] 'agreement among raft nodes before linearized reading' (duration: 110.136247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:57.728135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.82254ms","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-06-26T10:25:57.728193Z","caller":"traceutil/trace.go:171","msg":"trace[1710006766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"267.920954ms","start":"2026-06-26T10:25:57.460259Z","end":"2026-06-26T10:25:57.72818Z","steps":["trace[1710006766] 'range keys from in-memory index tree' (duration: 267.693667ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:58.177204Z","caller":"traceutil/trace.go:171","msg":"trace[929821154] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"433.002855ms","start":"2026-06-26T10:25:57.744173Z","end":"2026-06-26T10:25:58.177175Z","steps":["trace[929821154] 'read index received' (duration: 432.659815ms)","trace[929821154] 'applied index is now lower than readState.Index' (duration: 341.729µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:25:58.177299Z","caller":"traceutil/trace.go:171","msg":"trace[978271385] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"444.785906ms","start":"2026-06-26T10:25:57.732467Z","end":"2026-06-26T10:25:58.177253Z","steps":["trace[978271385] 'process raft request' (duration: 444.522098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:58.177335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.143528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:25:58.17739Z","caller":"traceutil/trace.go:171","msg":"trace[291099627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"433.250491ms","start":"2026-06-26T10:25:57.744129Z","end":"2026-06-26T10:25:58.17738Z","steps":["trace[291099627] 'agreement among raft nodes before linearized reading' (duration: 433.148138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:58.177432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:25:57.744112Z","time spent":"433.309632ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T10:25:58.177469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:25:57.732443Z","time spent":"444.93629ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:25:58.355473Z","caller":"traceutil/trace.go:171","msg":"trace[1005166448] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"159.101373ms","start":"2026-06-26T10:25:58.196342Z","end":"2026-06-26T10:25:58.355443Z","steps":["trace[1005166448] 'process raft request' (duration: 104.593811ms)","trace[1005166448] 'compare' (duration: 54.402299ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:25:58.921997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.159321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:25:58.922097Z","caller":"traceutil/trace.go:171","msg":"trace[1398250856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"177.281924ms","start":"2026-06-26T10:25:58.744795Z","end":"2026-06-26T10:25:58.922077Z","steps":["trace[1398250856] 'range keys from in-memory index tree' (duration: 177.033316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:25:59.643637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.108164ms","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-06-26T10:25:59.643723Z","caller":"traceutil/trace.go:171","msg":"trace[1610066724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"270.251627ms","start":"2026-06-26T10:25:59.373453Z","end":"2026-06-26T10:25:59.643704Z","steps":["trace[1610066724] 'range keys from in-memory index tree' (duration: 269.902818ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:59.644147Z","caller":"traceutil/trace.go:171","msg":"trace[328065797] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"194.299273ms","start":"2026-06-26T10:25:59.449833Z","end":"2026-06-26T10:25:59.644132Z","steps":["trace[328065797] 'read index received' (duration: 118.818101ms)","trace[328065797] 'applied index is now lower than readState.Index' (duration: 75.480142ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:25:59.644353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.513668ms","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-06-26T10:25:59.644417Z","caller":"traceutil/trace.go:171","msg":"trace[640465931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"194.634192ms","start":"2026-06-26T10:25:59.449771Z","end":"2026-06-26T10:25:59.644405Z","steps":["trace[640465931] 'agreement among raft nodes before linearized reading' (duration: 194.459456ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:25:59.644508Z","caller":"traceutil/trace.go:171","msg":"trace[1120582931] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"219.363127ms","start":"2026-06-26T10:25:59.425134Z","end":"2026-06-26T10:25:59.644497Z","steps":["trace[1120582931] 'process raft request' (duration: 143.603677ms)","trace[1120582931] 'compare' (duration: 74.985499ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:25:59.812547Z","caller":"traceutil/trace.go:171","msg":"trace[639177257] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"164.245078ms","start":"2026-06-26T10:25:59.648264Z","end":"2026-06-26T10:25:59.812509Z","steps":["trace[639177257] 'process raft request' (duration: 154.682179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:00.860724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266776620639220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T10:26:01.242787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055419124s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T10:26:01.243668Z","caller":"traceutil/trace.go:171","msg":"trace[1519349740] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"1.056390221s","start":"2026-06-26T10:26:00.18723Z","end":"2026-06-26T10:26:01.24362Z","steps":["trace[1519349740] 'process raft request' (duration: 1.055988051s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:01.243934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:00.187204Z","time spent":"1.056586617s","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:26:01.900934Z","caller":"traceutil/trace.go:171","msg":"trace[535282295] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.623208468s","start":"2026-06-26T10:26:00.277697Z","end":"2026-06-26T10:26:01.900906Z","steps":["trace[535282295] 'process raft request' (duration: 1.622985012s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:01.901065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:00.277661Z","time spent":"1.623334081s","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5881,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T10:26:01.901135Z","caller":"traceutil/trace.go:171","msg":"trace[353985111] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"1.408356238s","start":"2026-06-26T10:26:00.492752Z","end":"2026-06-26T10:26:01.901109Z","steps":["trace[353985111] 'process raft request' (duration: 1.40809023s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:01.901309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:00.49273Z","time spent":"1.40846924s","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:26:02.20386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.955229ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-26T10:26:02.203951Z","caller":"traceutil/trace.go:171","msg":"trace[588969817] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1301; }","duration":"1.844051056s","start":"2026-06-26T10:26:00.359885Z","end":"2026-06-26T10:26:02.203937Z","steps":["trace[588969817] 'read index received' (duration: 883.238032ms)","trace[588969817] 'applied index is now lower than readState.Index' (duration: 960.811984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:26:02.204532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844635684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-26T10:26:02.204572Z","caller":"traceutil/trace.go:171","msg":"trace[1023919259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"1.844735966s","start":"2026-06-26T10:26:00.359826Z","end":"2026-06-26T10:26:02.204562Z","steps":["trace[1023919259] 'agreement among raft nodes before linearized reading' (duration: 1.844610152s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.204604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:00.359797Z","time spent":"1.844798898s","remote":"127.0.0.1:42904","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-06-26T10:26:02.206865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459602228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:26:02.206944Z","caller":"traceutil/trace.go:171","msg":"trace[506324772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"1.459732331s","start":"2026-06-26T10:26:00.747195Z","end":"2026-06-26T10:26:02.206927Z","steps":["trace[506324772] 'agreement among raft nodes before linearized reading' (duration: 1.459606728s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.206985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:00.747164Z","time spent":"1.459812124s","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T10:26:02.207081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.515408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T10:26:02.207138Z","caller":"traceutil/trace.go:171","msg":"trace[1538356503] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1252; }","duration":"349.61014ms","start":"2026-06-26T10:26:01.857515Z","end":"2026-06-26T10:26:02.207125Z","steps":["trace[1538356503] 'agreement among raft nodes before linearized reading' (duration: 349.518917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.207173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:01.857494Z","time spent":"349.669991ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-26T10:26:02.207204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201034198s","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-06-26T10:26:02.207242Z","caller":"traceutil/trace.go:171","msg":"trace[1620785051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"1.20110579s","start":"2026-06-26T10:26:01.006127Z","end":"2026-06-26T10:26:02.207232Z","steps":["trace[1620785051] 'agreement among raft nodes before linearized reading' (duration: 1.200966726s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.207247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.520861ms","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-06-26T10:26:02.207277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:01.006106Z","time spent":"1.201164592s","remote":"127.0.0.1:42904","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-06-26T10:26:02.207324Z","caller":"traceutil/trace.go:171","msg":"trace[538364508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"475.650914ms","start":"2026-06-26T10:26:01.731655Z","end":"2026-06-26T10:26:02.207306Z","steps":["trace[538364508] 'agreement among raft nodes before linearized reading' (duration: 475.417428ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:26:02.20737Z","caller":"traceutil/trace.go:171","msg":"trace[439206637] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"557.925506ms","start":"2026-06-26T10:26:01.649435Z","end":"2026-06-26T10:26:02.207361Z","steps":["trace[439206637] 'process raft request' (duration: 557.202007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.207381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:01.731632Z","time spent":"475.739625ms","remote":"127.0.0.1:42904","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-06-26T10:26:02.207443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:01.649414Z","time spent":"557.977759ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:26:02.207523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.010006ms","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-06-26T10:26:02.20752Z","caller":"traceutil/trace.go:171","msg":"trace[726476971] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"332.88799ms","start":"2026-06-26T10:26:01.874599Z","end":"2026-06-26T10:26:02.207487Z","steps":["trace[726476971] 'process raft request' (duration: 332.15278ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:26:02.207552Z","caller":"traceutil/trace.go:171","msg":"trace[979852738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"390.081118ms","start":"2026-06-26T10:26:01.817465Z","end":"2026-06-26T10:26:02.207546Z","steps":["trace[979852738] 'agreement among raft nodes before linearized reading' (duration: 390.013995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.20757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:01.817434Z","time spent":"390.132058ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T10:26:02.208365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:01.874577Z","time spent":"332.979542ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T10:26:02.804168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.681197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T10:26:02.804382Z","caller":"traceutil/trace.go:171","msg":"trace[602948867] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1307; }","duration":"585.281165ms","start":"2026-06-26T10:26:02.219048Z","end":"2026-06-26T10:26:02.804329Z","steps":["trace[602948867] 'read index received' (duration: 33.43031ms)","trace[602948867] 'applied index is now lower than readState.Index' (duration: 551.849955ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:26:02.804414Z","caller":"traceutil/trace.go:171","msg":"trace[1587028325] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"591.888951ms","start":"2026-06-26T10:26:02.212493Z","end":"2026-06-26T10:26:02.804382Z","steps":["trace[1587028325] 'process raft request' (duration: 435.920776ms)","trace[1587028325] 'compare' (duration: 155.579374ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:26:02.804465Z","caller":"traceutil/trace.go:171","msg":"trace[1209233861] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"590.655836ms","start":"2026-06-26T10:26:02.213798Z","end":"2026-06-26T10:26:02.804454Z","steps":["trace[1209233861] 'process raft request' (duration: 590.496761ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T10:26:02.804435Z","caller":"traceutil/trace.go:171","msg":"trace[296653296] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"591.83789ms","start":"2026-06-26T10:26:02.212568Z","end":"2026-06-26T10:26:02.804406Z","steps":["trace[296653296] 'process raft request' (duration: 591.681806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.804527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:02.213778Z","time spent":"590.714868ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:26:02.80455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:02.212484Z","time spent":"591.992474ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:26:02.804579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:02.212541Z","time spent":"591.964163ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T10:26:02.804644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.598954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:26:02.804673Z","caller":"traceutil/trace.go:171","msg":"trace[1063344526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"585.647546ms","start":"2026-06-26T10:26:02.219017Z","end":"2026-06-26T10:26:02.804664Z","steps":["trace[1063344526] 'agreement among raft nodes before linearized reading' (duration: 585.598785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:02.804699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:02.219007Z","time spent":"585.686937ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T10:26:02.804736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.871318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:26:02.804769Z","caller":"traceutil/trace.go:171","msg":"trace[1355267705] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1256; }","duration":"142.940298ms","start":"2026-06-26T10:26:02.661818Z","end":"2026-06-26T10:26:02.804758Z","steps":["trace[1355267705] 'agreement among raft nodes before linearized reading' (duration: 142.851487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:12.295965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.172475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:26:12.296081Z","caller":"traceutil/trace.go:171","msg":"trace[868774551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"658.33121ms","start":"2026-06-26T10:26:11.637723Z","end":"2026-06-26T10:26:12.296054Z","steps":["trace[868774551] 'range keys from in-memory index tree' (duration: 658.098513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:12.296177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.833209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T10:26:12.296283Z","caller":"traceutil/trace.go:171","msg":"trace[2033874569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"549.995363ms","start":"2026-06-26T10:26:11.746247Z","end":"2026-06-26T10:26:12.296242Z","steps":["trace[2033874569] 'range keys from in-memory index tree' (duration: 549.683075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:12.296327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:11.746226Z","time spent":"550.088826ms","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T10:26:12.296524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:11.637708Z","time spent":"658.601786ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-26T10:26:12.296567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.224958ms","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-06-26T10:26:12.296636Z","caller":"traceutil/trace.go:171","msg":"trace[494500969] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1325; }","duration":"779.336522ms","start":"2026-06-26T10:26:11.517281Z","end":"2026-06-26T10:26:12.296618Z","steps":["trace[494500969] 'range keys from in-memory index tree' (duration: 779.099195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T10:26:12.296677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T10:26:11.517261Z","time spent":"779.406113ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-26T10:26:12.296822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.668106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T10:26:12.296938Z","caller":"traceutil/trace.go:171","msg":"trace[128785383] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"124.060348ms","start":"2026-06-26T10:26:12.172858Z","end":"2026-06-26T10:26:12.296918Z","steps":["trace[128785383] 'read index received' (duration: 45.151µs)","trace[128785383] 'applied index is now lower than readState.Index' (duration: 124.013627ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T10:26:12.297171Z","caller":"traceutil/trace.go:171","msg":"trace[595432045] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"232.844586ms","start":"2026-06-26T10:26:12.064312Z","end":"2026-06-26T10:26:12.297157Z","steps":["trace[595432045] 'process raft request' (duration: 28.761358ms)","trace[595432045] 'compare' (duration: 203.061439ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T10:26:12.297476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.615114ms","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:530"} {"level":"info","ts":"2026-06-26T10:26:12.297537Z","caller":"traceutil/trace.go:171","msg":"trace[799781193] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1326; }","duration":"124.701666ms","start":"2026-06-26T10:26:12.172817Z","end":"2026-06-26T10:26:12.297519Z","steps":["trace[799781193] 'agreement among raft nodes before linearized reading' (duration: 124.409659ms)"],"step_count":1}