{"level":"warn","ts":"2026-02-18T22:53:43.31051Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:53:43.310597Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-02-18T22:53:43.310782Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:53:43.310798Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-02-18T22:53:43.310816Z","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-02-18T22:53:43.311721Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-02-18T22:53:43.311818Z","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":2,"max-cpu-available":2,"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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-02-18T22:53:43.321702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.11155ms"} {"level":"info","ts":"2026-02-18T22:53:43.346396Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-02-18T22:53:43.346601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:53:43.348393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:53:43.348489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:53:43.348503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:53:43.34859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-02-18T22:53:43.359454Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:53:43.37053Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:53:43.372516Z","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-02-18T22:53:43.376964Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:53:43.377417Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:53:43.377572Z","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-02-18T22:53:43.377611Z","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-02-18T22:53:43.377675Z","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-02-18T22:53:43.3836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-02-18T22:53:43.383746Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-02-18T22:53:43.388692Z","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-02-18T22:53:43.388811Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-02-18T22:53:43.389466Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-02-18T22:53:43.390137Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:53:43.390163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:53:44.349798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:53:44.349889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:53:44.349965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-02-18T22:53:44.349997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:53:44.35003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-02-18T22:53:44.350051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:53:44.350071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-02-18T22:53:44.352869Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:44.354415Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:53:44.354438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:44.354738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:44.354829Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:44.354927Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:44.354992Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:44.356839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:53:44.371369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-02-18T22:53:44.374251Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:53:44.374984Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:53:51.577843Z","caller":"traceutil/trace.go:171","msg":"trace[285876552] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"157.61197ms","start":"2026-02-18T22:53:51.420192Z","end":"2026-02-18T22:53:51.577804Z","steps":["trace[285876552] 'process raft request' (duration: 157.457556ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:51.590952Z","caller":"traceutil/trace.go:171","msg":"trace[236215281] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"150.680325ms","start":"2026-02-18T22:53:51.440262Z","end":"2026-02-18T22:53:51.590943Z","steps":["trace[236215281] 'process raft request' (duration: 150.48542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:52.085817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.404784ms","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-02-18T22:53:52.085902Z","caller":"traceutil/trace.go:171","msg":"trace[1545732045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:325; }","duration":"208.545607ms","start":"2026-02-18T22:53:51.877339Z","end":"2026-02-18T22:53:52.085885Z","steps":["trace[1545732045] 'range keys from in-memory index tree' (duration: 208.344612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:52.086207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.859072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T22:53:52.086275Z","caller":"traceutil/trace.go:171","msg":"trace[786795313] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:325; }","duration":"216.975485ms","start":"2026-02-18T22:53:51.869288Z","end":"2026-02-18T22:53:52.086263Z","steps":["trace[786795313] 'range keys from in-memory index tree' (duration: 216.76934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:55.101926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.831153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:55.10203Z","caller":"traceutil/trace.go:171","msg":"trace[1797154550] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"170.574888ms","start":"2026-02-18T22:53:54.931437Z","end":"2026-02-18T22:53:55.102012Z","steps":["trace[1797154550] 'process raft request' (duration: 46.580829ms)","trace[1797154550] 'compare' (duration: 122.69129ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:55.288492Z","caller":"traceutil/trace.go:171","msg":"trace[1825174261] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"178.565057ms","start":"2026-02-18T22:53:55.10991Z","end":"2026-02-18T22:53:55.288475Z","steps":["trace[1825174261] 'process raft request' (duration: 145.443288ms)","trace[1825174261] 'compare' (duration: 32.971536ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:55.451068Z","caller":"traceutil/trace.go:171","msg":"trace[1418839082] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"114.815024ms","start":"2026-02-18T22:53:55.332152Z","end":"2026-02-18T22:53:55.446967Z","steps":["trace[1418839082] 'process raft request' (duration: 81.300245ms)","trace[1418839082] 'compare' (duration: 32.469615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:00.724052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.273585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-18T22:54:00.724413Z","caller":"traceutil/trace.go:171","msg":"trace[835879522] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"391.663245ms","start":"2026-02-18T22:54:00.332728Z","end":"2026-02-18T22:54:00.724392Z","steps":["trace[835879522] 'range keys from in-memory index tree' (duration: 391.195354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.724642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:00.332713Z","time spent":"391.913821ms","remote":"127.0.0.1:43562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T22:54:02.354302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723357,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:02.496852Z","caller":"traceutil/trace.go:171","msg":"trace[59036152] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"642.870984ms","start":"2026-02-18T22:54:01.853959Z","end":"2026-02-18T22:54:02.49683Z","steps":["trace[59036152] 'read index received' (duration: 642.68941ms)","trace[59036152] 'applied index is now lower than readState.Index' (duration: 180.694µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:02.497171Z","caller":"traceutil/trace.go:171","msg":"trace[209770399] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"786.181223ms","start":"2026-02-18T22:54:01.710979Z","end":"2026-02-18T22:54:02.49716Z","steps":["trace[209770399] 'process raft request' (duration: 785.718443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:02.497275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:01.710961Z","time spent":"786.248225ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:02.498117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.162973ms","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-02-18T22:54:02.49815Z","caller":"traceutil/trace.go:171","msg":"trace[1922796590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"644.228954ms","start":"2026-02-18T22:54:01.853912Z","end":"2026-02-18T22:54:02.498141Z","steps":["trace[1922796590] 'agreement among raft nodes before linearized reading' (duration: 644.176413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:02.498171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:01.853893Z","time spent":"644.274046ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:54:02.69061Z","caller":"traceutil/trace.go:171","msg":"trace[973406766] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"193.668454ms","start":"2026-02-18T22:54:02.496918Z","end":"2026-02-18T22:54:02.690587Z","steps":["trace[973406766] 'read index received' (duration: 189.319427ms)","trace[973406766] 'applied index is now lower than readState.Index' (duration: 4.347026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:02.69077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.65362ms","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-02-18T22:54:02.690801Z","caller":"traceutil/trace.go:171","msg":"trace[377022668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"737.745373ms","start":"2026-02-18T22:54:01.953045Z","end":"2026-02-18T22:54:02.69079Z","steps":["trace[377022668] 'agreement among raft nodes before linearized reading' (duration: 737.617989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:02.690827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:01.953025Z","time spent":"737.795485ms","remote":"127.0.0.1:43658","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-02-18T22:54:02.691033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.239036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-18T22:54:02.691058Z","caller":"traceutil/trace.go:171","msg":"trace[1595267614] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:416; }","duration":"215.263417ms","start":"2026-02-18T22:54:02.475788Z","end":"2026-02-18T22:54:02.691051Z","steps":["trace[1595267614] 'agreement among raft nodes before linearized reading' (duration: 215.221506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:02.691156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.431909ms","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-02-18T22:54:02.69118Z","caller":"traceutil/trace.go:171","msg":"trace[868417770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"399.49298ms","start":"2026-02-18T22:54:02.291682Z","end":"2026-02-18T22:54:02.691175Z","steps":["trace[868417770] 'agreement among raft nodes before linearized reading' (duration: 399.45576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:02.691197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:02.291662Z","time spent":"399.531191ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:54:02.690615Z","caller":"traceutil/trace.go:171","msg":"trace[892783450] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"188.628432ms","start":"2026-02-18T22:54:02.501965Z","end":"2026-02-18T22:54:02.690593Z","steps":["trace[892783450] 'process raft request' (duration: 188.522079ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:06.665635Z","caller":"traceutil/trace.go:171","msg":"trace[982633940] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"157.011826ms","start":"2026-02-18T22:54:06.508602Z","end":"2026-02-18T22:54:06.665614Z","steps":["trace[982633940] 'read index received' (duration: 156.861102ms)","trace[982633940] 'applied index is now lower than readState.Index' (duration: 149.704µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:06.665745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.127708ms","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-02-18T22:54:06.665778Z","caller":"traceutil/trace.go:171","msg":"trace[53115357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"157.19506ms","start":"2026-02-18T22:54:06.508574Z","end":"2026-02-18T22:54:06.665769Z","steps":["trace[53115357] 'agreement among raft nodes before linearized reading' (duration: 157.120018ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:06.665845Z","caller":"traceutil/trace.go:171","msg":"trace[705850074] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"196.492317ms","start":"2026-02-18T22:54:06.469334Z","end":"2026-02-18T22:54:06.665826Z","steps":["trace[705850074] 'process raft request' (duration: 196.14309ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.1967Z","caller":"traceutil/trace.go:171","msg":"trace[23940560] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"100.808611ms","start":"2026-02-18T22:54:14.095866Z","end":"2026-02-18T22:54:14.196675Z","steps":["trace[23940560] 'process raft request' (duration: 100.76579ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.19694Z","caller":"traceutil/trace.go:171","msg":"trace[2024086070] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"102.405147ms","start":"2026-02-18T22:54:14.094509Z","end":"2026-02-18T22:54:14.196914Z","steps":["trace[2024086070] 'process raft request' (duration: 101.990278ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.1971Z","caller":"traceutil/trace.go:171","msg":"trace[41946807] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"101.284091ms","start":"2026-02-18T22:54:14.095808Z","end":"2026-02-18T22:54:14.197092Z","steps":["trace[41946807] 'process raft request' (duration: 100.78012ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.296533Z","caller":"traceutil/trace.go:171","msg":"trace[98012550] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"144.472175ms","start":"2026-02-18T22:54:14.152032Z","end":"2026-02-18T22:54:14.296504Z","steps":["trace[98012550] 'read index received' (duration: 44.625676ms)","trace[98012550] 'applied index is now lower than readState.Index' (duration: 99.845069ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:14.296653Z","caller":"traceutil/trace.go:171","msg":"trace[1885867231] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"171.058409ms","start":"2026-02-18T22:54:14.125584Z","end":"2026-02-18T22:54:14.296642Z","steps":["trace[1885867231] 'process raft request' (duration: 142.021711ms)","trace[1885867231] 'compare' (duration: 28.278432ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:14.296789Z","caller":"traceutil/trace.go:171","msg":"trace[243539357] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"148.663799ms","start":"2026-02-18T22:54:14.148115Z","end":"2026-02-18T22:54:14.296779Z","steps":["trace[243539357] 'process raft request' (duration: 147.991585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.297058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.007208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xqh4m\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-18T22:54:14.297091Z","caller":"traceutil/trace.go:171","msg":"trace[2124408842] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xqh4m; range_end:; response_count:1; response_revision:494; }","duration":"145.075529ms","start":"2026-02-18T22:54:14.152005Z","end":"2026-02-18T22:54:14.297081Z","steps":["trace[2124408842] 'agreement among raft nodes before linearized reading' (duration: 144.971187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.320087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.105497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14247"} {"level":"info","ts":"2026-02-18T22:54:14.320145Z","caller":"traceutil/trace.go:171","msg":"trace[10419858] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:497; }","duration":"165.185108ms","start":"2026-02-18T22:54:14.154949Z","end":"2026-02-18T22:54:14.320134Z","steps":["trace[10419858] 'agreement among raft nodes before linearized reading' (duration: 165.063896ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.320326Z","caller":"traceutil/trace.go:171","msg":"trace[1304109158] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"118.786943ms","start":"2026-02-18T22:54:14.201532Z","end":"2026-02-18T22:54:14.320319Z","steps":["trace[1304109158] 'process raft request' (duration: 118.23518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.320552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.961032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-18T22:54:14.320571Z","caller":"traceutil/trace.go:171","msg":"trace[739414301] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:497; }","duration":"117.095075ms","start":"2026-02-18T22:54:14.203471Z","end":"2026-02-18T22:54:14.320567Z","steps":["trace[739414301] 'agreement among raft nodes before linearized reading' (duration: 117.016523ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.320547Z","caller":"traceutil/trace.go:171","msg":"trace[1051700536] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"113.913454ms","start":"2026-02-18T22:54:14.206603Z","end":"2026-02-18T22:54:14.320516Z","steps":["trace[1051700536] 'process raft request' (duration: 113.360882ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.321034Z","caller":"traceutil/trace.go:171","msg":"trace[1692983157] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"116.716766ms","start":"2026-02-18T22:54:14.204308Z","end":"2026-02-18T22:54:14.321024Z","steps":["trace[1692983157] 'process raft request' (duration: 115.54125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.498972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.045868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:14.499318Z","caller":"traceutil/trace.go:171","msg":"trace[563921292] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:511; }","duration":"140.250572ms","start":"2026-02-18T22:54:14.359044Z","end":"2026-02-18T22:54:14.499294Z","steps":["trace[563921292] 'read index received' (duration: 37.744223ms)","trace[563921292] 'applied index is now lower than readState.Index' (duration: 102.504319ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:14.499461Z","caller":"traceutil/trace.go:171","msg":"trace[374524880] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"146.547873ms","start":"2026-02-18T22:54:14.352879Z","end":"2026-02-18T22:54:14.499427Z","steps":["trace[374524880] 'process raft request' (duration: 43.953162ms)","trace[374524880] 'compare' (duration: 101.897665ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:14.499713Z","caller":"traceutil/trace.go:171","msg":"trace[166868449] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"140.899216ms","start":"2026-02-18T22:54:14.358804Z","end":"2026-02-18T22:54:14.499703Z","steps":["trace[166868449] 'process raft request' (duration: 140.271982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.500151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.08977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14247"} {"level":"info","ts":"2026-02-18T22:54:14.500199Z","caller":"traceutil/trace.go:171","msg":"trace[2012441128] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:500; }","duration":"141.158881ms","start":"2026-02-18T22:54:14.359026Z","end":"2026-02-18T22:54:14.500185Z","steps":["trace[2012441128] 'agreement among raft nodes before linearized reading' (duration: 141.051869ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.500367Z","caller":"traceutil/trace.go:171","msg":"trace[593303013] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"141.401716ms","start":"2026-02-18T22:54:14.358959Z","end":"2026-02-18T22:54:14.500361Z","steps":["trace[593303013] 'process raft request' (duration: 140.19698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.501758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.660696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-02-18T22:54:14.502601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.223601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4287"} {"level":"info","ts":"2026-02-18T22:54:14.50263Z","caller":"traceutil/trace.go:171","msg":"trace[608356303] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:500; }","duration":"140.261682ms","start":"2026-02-18T22:54:14.362362Z","end":"2026-02-18T22:54:14.502624Z","steps":["trace[608356303] 'agreement among raft nodes before linearized reading' (duration: 140.217881ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:14.502839Z","caller":"traceutil/trace.go:171","msg":"trace[585864005] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:500; }","duration":"142.704537ms","start":"2026-02-18T22:54:14.359084Z","end":"2026-02-18T22:54:14.501789Z","steps":["trace[585864005] 'agreement among raft nodes before linearized reading' (duration: 142.648765ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:15.618775Z","caller":"traceutil/trace.go:171","msg":"trace[1312043098] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"121.441281ms","start":"2026-02-18T22:54:15.497317Z","end":"2026-02-18T22:54:15.618758Z","steps":["trace[1312043098] 'process raft request' (duration: 121.338739ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:19.352765Z","caller":"traceutil/trace.go:171","msg":"trace[2094002487] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"235.009858ms","start":"2026-02-18T22:54:19.117741Z","end":"2026-02-18T22:54:19.352751Z","steps":["trace[2094002487] 'read index received' (duration: 234.863644ms)","trace[2094002487] 'applied index is now lower than readState.Index' (duration: 145.494µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:19.352996Z","caller":"traceutil/trace.go:171","msg":"trace[126518917] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"269.678201ms","start":"2026-02-18T22:54:19.083311Z","end":"2026-02-18T22:54:19.352989Z","steps":["trace[126518917] 'process raft request' (duration: 269.363014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:19.353104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.355515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:19.353122Z","caller":"traceutil/trace.go:171","msg":"trace[781219267] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:547; }","duration":"235.397967ms","start":"2026-02-18T22:54:19.117717Z","end":"2026-02-18T22:54:19.353115Z","steps":["trace[781219267] 'agreement among raft nodes before linearized reading' (duration: 235.360336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.018408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723608,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:20.041321Z","caller":"traceutil/trace.go:171","msg":"trace[1006493647] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"680.556505ms","start":"2026-02-18T22:54:19.360743Z","end":"2026-02-18T22:54:20.0413Z","steps":["trace[1006493647] 'process raft request' (duration: 680.419582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.041477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.360735Z","time spent":"680.663077ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4613,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:20.589299Z","caller":"traceutil/trace.go:171","msg":"trace[39757481] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"1.071416233s","start":"2026-02-18T22:54:19.51786Z","end":"2026-02-18T22:54:20.589277Z","steps":["trace[39757481] 'read index received' (duration: 523.274804ms)","trace[39757481] 'applied index is now lower than readState.Index' (duration: 548.140189ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:20.589541Z","caller":"traceutil/trace.go:171","msg":"trace[1338125770] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.141992199s","start":"2026-02-18T22:54:19.447538Z","end":"2026-02-18T22:54:20.58953Z","steps":["trace[1338125770] 'process raft request' (duration: 1.141547039s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.58964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.447516Z","time spent":"1.14205646s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:20.589953Z","caller":"traceutil/trace.go:171","msg":"trace[1892079900] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"590.483815ms","start":"2026-02-18T22:54:19.999459Z","end":"2026-02-18T22:54:20.589943Z","steps":["trace[1892079900] 'process raft request' (duration: 589.773009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.590008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.99944Z","time spent":"590.537586ms","remote":"127.0.0.1:43562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:20.59016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072295402s","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-02-18T22:54:20.590192Z","caller":"traceutil/trace.go:171","msg":"trace[474277675] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"1.072347043s","start":"2026-02-18T22:54:19.517835Z","end":"2026-02-18T22:54:20.590182Z","steps":["trace[474277675] 'agreement among raft nodes before linearized reading' (duration: 1.072298102s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.590256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.51782Z","time spent":"1.072426676s","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:20.596667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.545328ms","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-02-18T22:54:20.596724Z","caller":"traceutil/trace.go:171","msg":"trace[1092843319] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"879.63442ms","start":"2026-02-18T22:54:19.717075Z","end":"2026-02-18T22:54:20.59671Z","steps":["trace[1092843319] 'agreement among raft nodes before linearized reading' (duration: 879.536038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.59677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.717061Z","time spent":"879.702911ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:20.596891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.364341ms","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-02-18T22:54:20.596986Z","caller":"traceutil/trace.go:171","msg":"trace[1940772741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:551; }","duration":"169.454933ms","start":"2026-02-18T22:54:20.427479Z","end":"2026-02-18T22:54:20.596934Z","steps":["trace[1940772741] 'agreement among raft nodes before linearized reading' (duration: 169.29732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.597014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.963728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-18T22:54:20.597041Z","caller":"traceutil/trace.go:171","msg":"trace[1509427101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:551; }","duration":"279.022449ms","start":"2026-02-18T22:54:20.318012Z","end":"2026-02-18T22:54:20.597035Z","steps":["trace[1509427101] 'agreement among raft nodes before linearized reading' (duration: 278.928367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.597145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.530407ms","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":"info","ts":"2026-02-18T22:54:20.597169Z","caller":"traceutil/trace.go:171","msg":"trace[1752824800] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:551; }","duration":"479.584749ms","start":"2026-02-18T22:54:20.117577Z","end":"2026-02-18T22:54:20.597162Z","steps":["trace[1752824800] 'agreement among raft nodes before linearized reading' (duration: 479.548877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.597185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:20.117557Z","time spent":"479.62465ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:20.597309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.15901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:20.59733Z","caller":"traceutil/trace.go:171","msg":"trace[1009663378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"604.206161ms","start":"2026-02-18T22:54:19.993118Z","end":"2026-02-18T22:54:20.597325Z","steps":["trace[1009663378] 'agreement among raft nodes before linearized reading' (duration: 604.17316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.597347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.993102Z","time spent":"604.241821ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:20.59738Z","caller":"traceutil/trace.go:171","msg":"trace[708490408] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"549.324076ms","start":"2026-02-18T22:54:20.047997Z","end":"2026-02-18T22:54:20.597321Z","steps":["trace[708490408] 'process raft request' (duration: 548.536837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.597464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.499384ms","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":"info","ts":"2026-02-18T22:54:20.597488Z","caller":"traceutil/trace.go:171","msg":"trace[396079084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:551; }","duration":"680.549735ms","start":"2026-02-18T22:54:19.916933Z","end":"2026-02-18T22:54:20.597483Z","steps":["trace[396079084] 'agreement among raft nodes before linearized reading' (duration: 680.489644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:20.597511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:19.916922Z","time spent":"680.585256ms","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:20.59758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:20.047981Z","time spent":"549.48127ms","remote":"127.0.0.1:43866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:23.017535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:23.518121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:23.815558Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.413131801s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:54:23.816424Z","caller":"traceutil/trace.go:171","msg":"trace[1351002971] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.421850967s","start":"2026-02-18T22:54:22.394556Z","end":"2026-02-18T22:54:23.816407Z","steps":["trace[1351002971] 'process raft request' (duration: 1.421607792s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.816655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.394528Z","time spent":"1.422031551s","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:24.3939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.741395ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:54:24.394016Z","caller":"traceutil/trace.go:171","msg":"trace[58638463] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"1.877075451s","start":"2026-02-18T22:54:22.516929Z","end":"2026-02-18T22:54:24.394004Z","steps":["trace[58638463] 'read index received' (duration: 1.30062143s)","trace[58638463] 'applied index is now lower than readState.Index' (duration: 576.452711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:24.394223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877261465s","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":"info","ts":"2026-02-18T22:54:24.39426Z","caller":"traceutil/trace.go:171","msg":"trace[193937471] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"1.877349887s","start":"2026-02-18T22:54:22.516903Z","end":"2026-02-18T22:54:24.394253Z","steps":["trace[193937471] 'agreement among raft nodes before linearized reading' (duration: 1.877195164s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.394309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.516893Z","time spent":"1.877408248s","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:24.394578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.444695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.394618Z","caller":"traceutil/trace.go:171","msg":"trace[251772016] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:572; }","duration":"183.497177ms","start":"2026-02-18T22:54:24.211114Z","end":"2026-02-18T22:54:24.394611Z","steps":["trace[251772016] 'agreement among raft nodes before linearized reading' (duration: 183.445416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.394765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778838018s","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-02-18T22:54:24.394937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.327811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.394994Z","caller":"traceutil/trace.go:171","msg":"trace[1078564317] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"122.405662ms","start":"2026-02-18T22:54:24.272583Z","end":"2026-02-18T22:54:24.394989Z","steps":["trace[1078564317] 'agreement among raft nodes before linearized reading' (duration: 122.340421ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.394968Z","caller":"traceutil/trace.go:171","msg":"trace[1981727471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.778960641s","start":"2026-02-18T22:54:22.615862Z","end":"2026-02-18T22:54:24.394823Z","steps":["trace[1981727471] 'agreement among raft nodes before linearized reading' (duration: 1.77846301s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.439618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.39519Z","caller":"traceutil/trace.go:171","msg":"trace[617147281] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"124.54262ms","start":"2026-02-18T22:54:24.270621Z","end":"2026-02-18T22:54:24.395163Z","steps":["trace[617147281] 'agreement among raft nodes before linearized reading' (duration: 124.462738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.679894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.395327Z","caller":"traceutil/trace.go:171","msg":"trace[1639771886] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:572; }","duration":"124.730375ms","start":"2026-02-18T22:54:24.270592Z","end":"2026-02-18T22:54:24.395323Z","steps":["trace[1639771886] 'agreement among raft nodes before linearized reading' (duration: 124.681134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.680014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.395486Z","caller":"traceutil/trace.go:171","msg":"trace[59902398] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"133.724135ms","start":"2026-02-18T22:54:24.261757Z","end":"2026-02-18T22:54:24.395481Z","steps":["trace[59902398] 'agreement among raft nodes before linearized reading' (duration: 133.658514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.037797ms","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-02-18T22:54:24.395656Z","caller":"traceutil/trace.go:171","msg":"trace[735284255] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"136.084938ms","start":"2026-02-18T22:54:24.259565Z","end":"2026-02-18T22:54:24.39565Z","steps":["trace[735284255] 'agreement among raft nodes before linearized reading' (duration: 136.017946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.343575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:54:24.395795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.416585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.39583Z","caller":"traceutil/trace.go:171","msg":"trace[1017128451] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:572; }","duration":"136.463008ms","start":"2026-02-18T22:54:24.259362Z","end":"2026-02-18T22:54:24.395825Z","steps":["trace[1017128451] 'agreement among raft nodes before linearized reading' (duration: 136.417316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.822159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.395978Z","caller":"traceutil/trace.go:171","msg":"trace[1075371346] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:572; }","duration":"142.86597ms","start":"2026-02-18T22:54:24.253106Z","end":"2026-02-18T22:54:24.395972Z","steps":["trace[1075371346] 'agreement among raft nodes before linearized reading' (duration: 142.805589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.396091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.257452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:54:24.396159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.40326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.396201Z","caller":"traceutil/trace.go:171","msg":"trace[978657669] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:572; }","duration":"146.458691ms","start":"2026-02-18T22:54:24.249737Z","end":"2026-02-18T22:54:24.396196Z","steps":["trace[978657669] 'agreement among raft nodes before linearized reading' (duration: 146.378819ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.396292Z","caller":"traceutil/trace.go:171","msg":"trace[620576020] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"209.324984ms","start":"2026-02-18T22:54:24.1868Z","end":"2026-02-18T22:54:24.396125Z","steps":["trace[620576020] 'agreement among raft nodes before linearized reading' (duration: 209.227532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.396378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.002773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.396421Z","caller":"traceutil/trace.go:171","msg":"trace[575057976] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"160.081574ms","start":"2026-02-18T22:54:24.236334Z","end":"2026-02-18T22:54:24.396416Z","steps":["trace[575057976] 'agreement among raft nodes before linearized reading' (duration: 160.031293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.39658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.264028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.396631Z","caller":"traceutil/trace.go:171","msg":"trace[1062608421] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"160.318649ms","start":"2026-02-18T22:54:24.236308Z","end":"2026-02-18T22:54:24.396626Z","steps":["trace[1062608421] 'agreement among raft nodes before linearized reading' (duration: 160.255818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.396749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.364486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.396799Z","caller":"traceutil/trace.go:171","msg":"trace[657587240] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"171.420797ms","start":"2026-02-18T22:54:24.225373Z","end":"2026-02-18T22:54:24.396794Z","steps":["trace[657587240] 'agreement among raft nodes before linearized reading' (duration: 171.362406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.39692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.552871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.396968Z","caller":"traceutil/trace.go:171","msg":"trace[925681921] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"171.601782ms","start":"2026-02-18T22:54:24.225362Z","end":"2026-02-18T22:54:24.396964Z","steps":["trace[925681921] 'agreement among raft nodes before linearized reading' (duration: 171.54446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.39708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.844536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.397122Z","caller":"traceutil/trace.go:171","msg":"trace[1439799018] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:572; }","duration":"171.894518ms","start":"2026-02-18T22:54:24.225222Z","end":"2026-02-18T22:54:24.397117Z","steps":["trace[1439799018] 'agreement among raft nodes before linearized reading' (duration: 171.841887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.39726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.109195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.397294Z","caller":"traceutil/trace.go:171","msg":"trace[1779956202] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:572; }","duration":"186.153046ms","start":"2026-02-18T22:54:24.211136Z","end":"2026-02-18T22:54:24.397289Z","steps":["trace[1779956202] 'agreement among raft nodes before linearized reading' (duration: 186.106125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.395281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.615846Z","time spent":"1.77941948s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T22:54:24.395933Z","caller":"traceutil/trace.go:171","msg":"trace[680267827] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:572; }","duration":"184.707073ms","start":"2026-02-18T22:54:24.211093Z","end":"2026-02-18T22:54:24.3958Z","steps":["trace[680267827] 'agreement among raft nodes before linearized reading' (duration: 184.217102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.396311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.91029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.397913Z","caller":"traceutil/trace.go:171","msg":"trace[2079090370] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:572; }","duration":"161.523105ms","start":"2026-02-18T22:54:24.236383Z","end":"2026-02-18T22:54:24.397906Z","steps":["trace[2079090370] 'agreement among raft nodes before linearized reading' (duration: 159.91364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.398607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.076024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.398657Z","caller":"traceutil/trace.go:171","msg":"trace[1394595225] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:572; }","duration":"611.152817ms","start":"2026-02-18T22:54:23.787498Z","end":"2026-02-18T22:54:24.398651Z","steps":["trace[1394595225] 'agreement among raft nodes before linearized reading' (duration: 611.092014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.398684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.787486Z","time spent":"611.192037ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:54:24.398837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.047075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.398884Z","caller":"traceutil/trace.go:171","msg":"trace[690641319] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"212.138757ms","start":"2026-02-18T22:54:24.18674Z","end":"2026-02-18T22:54:24.398878Z","steps":["trace[690641319] 'agreement among raft nodes before linearized reading' (duration: 212.081685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.399046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.314471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.399153Z","caller":"traceutil/trace.go:171","msg":"trace[494833626] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:572; }","duration":"212.489625ms","start":"2026-02-18T22:54:24.186658Z","end":"2026-02-18T22:54:24.399148Z","steps":["trace[494833626] 'agreement among raft nodes before linearized reading' (duration: 212.354692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.399319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.770776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.399392Z","caller":"traceutil/trace.go:171","msg":"trace[1988204831] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:572; }","duration":"575.850408ms","start":"2026-02-18T22:54:23.823535Z","end":"2026-02-18T22:54:24.399385Z","steps":["trace[1988204831] 'agreement among raft nodes before linearized reading' (duration: 575.768806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.39944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.823532Z","time spent":"575.901169ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:54:24.399604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.068713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.39968Z","caller":"traceutil/trace.go:171","msg":"trace[357500794] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:572; }","duration":"576.152344ms","start":"2026-02-18T22:54:23.823523Z","end":"2026-02-18T22:54:24.399675Z","steps":["trace[357500794] 'agreement among raft nodes before linearized reading' (duration: 576.046932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.399742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.82352Z","time spent":"576.214956ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:54:24.399873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.366589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.399933Z","caller":"traceutil/trace.go:171","msg":"trace[1883711101] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:572; }","duration":"576.436011ms","start":"2026-02-18T22:54:23.823491Z","end":"2026-02-18T22:54:24.399927Z","steps":["trace[1883711101] 'agreement among raft nodes before linearized reading' (duration: 576.365609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.400003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.823486Z","time spent":"576.510122ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:54:24.400134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.772389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.400223Z","caller":"traceutil/trace.go:171","msg":"trace[1006291423] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:572; }","duration":"612.572228ms","start":"2026-02-18T22:54:23.78763Z","end":"2026-02-18T22:54:24.400202Z","steps":["trace[1006291423] 'agreement among raft nodes before linearized reading' (duration: 612.489516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.400287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.787625Z","time spent":"612.655009ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:54:24.400425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.705871ms","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:5"} {"level":"info","ts":"2026-02-18T22:54:24.400529Z","caller":"traceutil/trace.go:171","msg":"trace[2122132515] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:572; }","duration":"612.824254ms","start":"2026-02-18T22:54:23.787699Z","end":"2026-02-18T22:54:24.400524Z","steps":["trace[2122132515] 'agreement among raft nodes before linearized reading' (duration: 612.711371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.400572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.787694Z","time spent":"612.869665ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:54:24.400715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.096929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.400774Z","caller":"traceutil/trace.go:171","msg":"trace[630841078] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:572; }","duration":"613.171991ms","start":"2026-02-18T22:54:23.787597Z","end":"2026-02-18T22:54:24.400769Z","steps":["trace[630841078] 'agreement among raft nodes before linearized reading' (duration: 613.1034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.400828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.787591Z","time spent":"613.231022ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:54:24.401514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491048172s","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-02-18T22:54:24.402349Z","caller":"traceutil/trace.go:171","msg":"trace[1199618477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.491923052s","start":"2026-02-18T22:54:22.910418Z","end":"2026-02-18T22:54:24.402342Z","steps":["trace[1199618477] 'agreement among raft nodes before linearized reading' (duration: 1.491056553s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.402395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.910383Z","time spent":"1.492005734s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:24.402525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.468852ms","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-02-18T22:54:24.403125Z","caller":"traceutil/trace.go:171","msg":"trace[788735577] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:572; }","duration":"642.089537ms","start":"2026-02-18T22:54:23.761029Z","end":"2026-02-18T22:54:24.403118Z","steps":["trace[788735577] 'agreement among raft nodes before linearized reading' (duration: 641.453213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.403282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.761025Z","time spent":"642.23542ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:54:24.403422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.387433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.403492Z","caller":"traceutil/trace.go:171","msg":"trace[2016244529] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:572; }","duration":"642.502276ms","start":"2026-02-18T22:54:23.760985Z","end":"2026-02-18T22:54:24.403487Z","steps":["trace[2016244529] 'agreement among raft nodes before linearized reading' (duration: 642.406094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.403525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.760971Z","time spent":"642.547497ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:54:24.403631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412531088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.403726Z","caller":"traceutil/trace.go:171","msg":"trace[736927733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"1.412675442s","start":"2026-02-18T22:54:22.991045Z","end":"2026-02-18T22:54:24.40372Z","steps":["trace[736927733] 'agreement among raft nodes before linearized reading' (duration: 1.41257267s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.403798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.991026Z","time spent":"1.412766234s","remote":"127.0.0.1:43352","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-02-18T22:54:24.403975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486521041s","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-02-18T22:54:24.404037Z","caller":"traceutil/trace.go:171","msg":"trace[1773833073] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"1.486596372s","start":"2026-02-18T22:54:22.917435Z","end":"2026-02-18T22:54:24.404032Z","steps":["trace[1773833073] 'agreement among raft nodes before linearized reading' (duration: 1.486466299s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.404069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.917429Z","time spent":"1.486634463s","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:24.404774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600624329s","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-02-18T22:54:24.404854Z","caller":"traceutil/trace.go:171","msg":"trace[927264971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"1.600724711s","start":"2026-02-18T22:54:22.804109Z","end":"2026-02-18T22:54:24.404834Z","steps":["trace[927264971] 'agreement among raft nodes before linearized reading' (duration: 1.600628999s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.404925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.804096Z","time spent":"1.600821394s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:54:24.405091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.545016057s","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-02-18T22:54:24.405159Z","caller":"traceutil/trace.go:171","msg":"trace[1373821009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.54510383s","start":"2026-02-18T22:54:22.860048Z","end":"2026-02-18T22:54:24.405151Z","steps":["trace[1373821009] 'agreement among raft nodes before linearized reading' (duration: 1.545008058s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.405199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.860035Z","time spent":"1.545159291s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:24.396102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.980946ms","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:5"} {"level":"info","ts":"2026-02-18T22:54:24.405369Z","caller":"traceutil/trace.go:171","msg":"trace[689356501] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:572; }","duration":"153.258682ms","start":"2026-02-18T22:54:24.252104Z","end":"2026-02-18T22:54:24.405362Z","steps":["trace[689356501] 'agreement among raft nodes before linearized reading' (duration: 143.964265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.405461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553136417s","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-02-18T22:54:24.405494Z","caller":"traceutil/trace.go:171","msg":"trace[962378550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"1.553167388s","start":"2026-02-18T22:54:22.852317Z","end":"2026-02-18T22:54:24.405484Z","steps":["trace[962378550] 'agreement among raft nodes before linearized reading' (duration: 1.553059656s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.405098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685649307s","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":"info","ts":"2026-02-18T22:54:24.405677Z","caller":"traceutil/trace.go:171","msg":"trace[367740362] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"1.686257831s","start":"2026-02-18T22:54:22.719413Z","end":"2026-02-18T22:54:24.40567Z","steps":["trace[367740362] 'agreement among raft nodes before linearized reading' (duration: 1.685526235s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.405728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.719399Z","time spent":"1.686321852s","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:54:24.405618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.852282Z","time spent":"1.55326258s","remote":"127.0.0.1:43658","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-02-18T22:54:24.563407Z","caller":"traceutil/trace.go:171","msg":"trace[1059270064] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"136.317013ms","start":"2026-02-18T22:54:24.426893Z","end":"2026-02-18T22:54:24.56321Z","steps":["trace[1059270064] 'process raft request' (duration: 136.15812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.758763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.673011ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:25.758877Z","caller":"traceutil/trace.go:171","msg":"trace[471724319] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:573; }","duration":"815.797064ms","start":"2026-02-18T22:54:24.943062Z","end":"2026-02-18T22:54:25.758859Z","steps":["trace[471724319] 'range keys from in-memory index tree' (duration: 815.658961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.759709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.003835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:25.759889Z","caller":"traceutil/trace.go:171","msg":"trace[1512863030] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:588; }","duration":"1.324553103s","start":"2026-02-18T22:54:24.435325Z","end":"2026-02-18T22:54:25.759878Z","steps":["trace[1512863030] 'read index received' (duration: 127.833633ms)","trace[1512863030] 'applied index is now lower than readState.Index' (duration: 1.196718609s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:25.75995Z","caller":"traceutil/trace.go:171","msg":"trace[862441578] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.326581259s","start":"2026-02-18T22:54:24.43336Z","end":"2026-02-18T22:54:25.759941Z","steps":["trace[862441578] 'process raft request' (duration: 1.326404455s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:25.759925Z","caller":"traceutil/trace.go:171","msg":"trace[580937673] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.326676081s","start":"2026-02-18T22:54:24.433218Z","end":"2026-02-18T22:54:25.759894Z","steps":["trace[580937673] 'process raft request' (duration: 933.433652ms)","trace[580937673] 'compare' (duration: 392.659356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.760002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.433355Z","time spent":"1.32661772s","remote":"127.0.0.1:43658","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-02-18T22:54:25.760066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.433211Z","time spent":"1.326776803s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:25.760172Z","caller":"traceutil/trace.go:171","msg":"trace[765594004] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.325424864s","start":"2026-02-18T22:54:24.434736Z","end":"2026-02-18T22:54:25.76016Z","steps":["trace[765594004] 'process raft request' (duration: 1.325076315s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.760225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.434729Z","time spent":"1.325469895s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:25.760337Z","caller":"traceutil/trace.go:171","msg":"trace[1185968131] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.325543886s","start":"2026-02-18T22:54:24.434785Z","end":"2026-02-18T22:54:25.760329Z","steps":["trace[1185968131] 'process raft request' (duration: 1.325060325s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.760383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.434781Z","time spent":"1.325577107s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:25.760519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325186507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:25.760556Z","caller":"traceutil/trace.go:171","msg":"trace[270039475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"1.325237739s","start":"2026-02-18T22:54:24.435308Z","end":"2026-02-18T22:54:25.760545Z","steps":["trace[270039475] 'agreement among raft nodes before linearized reading' (duration: 1.325183568s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.760582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.435302Z","time spent":"1.32527508s","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:25.899705Z","caller":"traceutil/trace.go:171","msg":"trace[1581349739] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"989.645986ms","start":"2026-02-18T22:54:24.910045Z","end":"2026-02-18T22:54:25.899691Z","steps":["trace[1581349739] 'process raft request' (duration: 976.402821ms)","trace[1581349739] 'compare' (duration: 12.314305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.89984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.910036Z","time spent":"989.738548ms","remote":"127.0.0.1:43562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4478,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:25.900115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.985485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:25.900149Z","caller":"traceutil/trace.go:171","msg":"trace[1490318721] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:578; }","duration":"995.046108ms","start":"2026-02-18T22:54:24.905093Z","end":"2026-02-18T22:54:25.900139Z","steps":["trace[1490318721] 'agreement among raft nodes before linearized reading' (duration: 994.987316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.900179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.905082Z","time spent":"995.089669ms","remote":"127.0.0.1:33320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-18T22:54:25.900494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.927052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:25.900535Z","caller":"traceutil/trace.go:171","msg":"trace[792333222] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:578; }","duration":"998.012213ms","start":"2026-02-18T22:54:24.902509Z","end":"2026-02-18T22:54:25.900521Z","steps":["trace[792333222] 'agreement among raft nodes before linearized reading' (duration: 997.955522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.900564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.90249Z","time spent":"998.066145ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-02-18T22:54:25.899596Z","caller":"traceutil/trace.go:171","msg":"trace[691045631] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"139.657038ms","start":"2026-02-18T22:54:25.759915Z","end":"2026-02-18T22:54:25.899572Z","steps":["trace[691045631] 'read index received' (duration: 126.475605ms)","trace[691045631] 'applied index is now lower than readState.Index' (duration: 13.179553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.900987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.317289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:25.901023Z","caller":"traceutil/trace.go:171","msg":"trace[1240256837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"138.410821ms","start":"2026-02-18T22:54:25.762603Z","end":"2026-02-18T22:54:25.901014Z","steps":["trace[1240256837] 'agreement among raft nodes before linearized reading' (duration: 138.323189ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:26.726874Z","caller":"traceutil/trace.go:171","msg":"trace[1994378366] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"157.567459ms","start":"2026-02-18T22:54:26.569249Z","end":"2026-02-18T22:54:26.726816Z","steps":["trace[1994378366] 'process raft request' (duration: 157.385995ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:27.310572Z","caller":"traceutil/trace.go:171","msg":"trace[2043690670] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"317.892488ms","start":"2026-02-18T22:54:26.992658Z","end":"2026-02-18T22:54:27.31055Z","steps":["trace[2043690670] 'read index received' (duration: 317.735914ms)","trace[2043690670] 'applied index is now lower than readState.Index' (duration: 155.754µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:27.310666Z","caller":"traceutil/trace.go:171","msg":"trace[167488468] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"358.583726ms","start":"2026-02-18T22:54:26.952073Z","end":"2026-02-18T22:54:27.310657Z","steps":["trace[167488468] 'process raft request' (duration: 358.361801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.310762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:26.95205Z","time spent":"358.639268ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:27.310899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.242036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:27.31094Z","caller":"traceutil/trace.go:171","msg":"trace[1601356894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"318.309997ms","start":"2026-02-18T22:54:26.992618Z","end":"2026-02-18T22:54:27.310928Z","steps":["trace[1601356894] 'agreement among raft nodes before linearized reading' (duration: 318.255856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.310967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:26.992605Z","time spent":"318.356129ms","remote":"127.0.0.1:43352","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-02-18T22:54:30.283351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723686,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:30.442316Z","caller":"traceutil/trace.go:171","msg":"trace[890006132] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"663.011024ms","start":"2026-02-18T22:54:29.77928Z","end":"2026-02-18T22:54:30.442291Z","steps":["trace[890006132] 'process raft request' (duration: 662.82926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:30.442458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.779261Z","time spent":"663.124956ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:30.64837Z","caller":"traceutil/trace.go:171","msg":"trace[621460430] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"865.946544ms","start":"2026-02-18T22:54:29.782404Z","end":"2026-02-18T22:54:30.648351Z","steps":["trace[621460430] 'read index received' (duration: 659.901424ms)","trace[621460430] 'applied index is now lower than readState.Index' (duration: 206.04413ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:30.648596Z","caller":"traceutil/trace.go:171","msg":"trace[2122655201] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"866.762303ms","start":"2026-02-18T22:54:29.781823Z","end":"2026-02-18T22:54:30.648586Z","steps":["trace[2122655201] 'process raft request' (duration: 862.17829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:30.648688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.781815Z","time spent":"866.808124ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:30.648876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.466256ms","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-02-18T22:54:30.648905Z","caller":"traceutil/trace.go:171","msg":"trace[1231256686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"866.507317ms","start":"2026-02-18T22:54:29.782389Z","end":"2026-02-18T22:54:30.648896Z","steps":["trace[1231256686] 'agreement among raft nodes before linearized reading' (duration: 866.391114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:30.648944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.782383Z","time spent":"866.554828ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:30.649048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.344603ms","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-02-18T22:54:30.649145Z","caller":"traceutil/trace.go:171","msg":"trace[1553484989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"866.434245ms","start":"2026-02-18T22:54:29.782698Z","end":"2026-02-18T22:54:30.649132Z","steps":["trace[1553484989] 'agreement among raft nodes before linearized reading' (duration: 866.301582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:30.649194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.78268Z","time spent":"866.506377ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:30.649371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.863508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:30.649397Z","caller":"traceutil/trace.go:171","msg":"trace[631034768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"657.92221ms","start":"2026-02-18T22:54:29.991468Z","end":"2026-02-18T22:54:30.64939Z","steps":["trace[631034768] 'agreement among raft nodes before linearized reading' (duration: 657.885329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:30.649415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.991451Z","time spent":"657.9604ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:31.021297Z","caller":"traceutil/trace.go:171","msg":"trace[1886200536] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"151.358279ms","start":"2026-02-18T22:54:30.869919Z","end":"2026-02-18T22:54:31.021278Z","steps":["trace[1886200536] 'process raft request' (duration: 150.770786ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:36.433209Z","caller":"traceutil/trace.go:171","msg":"trace[1278232959] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"280.079434ms","start":"2026-02-18T22:54:36.15311Z","end":"2026-02-18T22:54:36.43319Z","steps":["trace[1278232959] 'process raft request' (duration: 279.866739ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:39.721135Z","caller":"traceutil/trace.go:171","msg":"trace[1846005900] transaction","detail":"{read_only:false; number_of_response:0; response_revision:648; }","duration":"156.127866ms","start":"2026-02-18T22:54:39.564991Z","end":"2026-02-18T22:54:39.721119Z","steps":["trace[1846005900] 'process raft request' (duration: 156.072985ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:39.822425Z","caller":"traceutil/trace.go:171","msg":"trace[1337782791] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"100.431822ms","start":"2026-02-18T22:54:39.721975Z","end":"2026-02-18T22:54:39.822407Z","steps":["trace[1337782791] 'read index received' (duration: 58.045776ms)","trace[1337782791] 'applied index is now lower than readState.Index' (duration: 42.385206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:39.822537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.546454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1889"} {"level":"info","ts":"2026-02-18T22:54:39.822558Z","caller":"traceutil/trace.go:171","msg":"trace[624697755] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:649; }","duration":"100.601556ms","start":"2026-02-18T22:54:39.72195Z","end":"2026-02-18T22:54:39.822552Z","steps":["trace[624697755] 'agreement among raft nodes before linearized reading' (duration: 100.527755ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:39.822658Z","caller":"traceutil/trace.go:171","msg":"trace[719392243] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"169.158116ms","start":"2026-02-18T22:54:39.653492Z","end":"2026-02-18T22:54:39.82265Z","steps":["trace[719392243] 'process raft request' (duration: 126.538185ms)","trace[719392243] 'compare' (duration: 42.155791ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:42.809691Z","caller":"traceutil/trace.go:171","msg":"trace[459404163] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"100.287879ms","start":"2026-02-18T22:54:42.70938Z","end":"2026-02-18T22:54:42.809668Z","steps":["trace[459404163] 'process raft request' (duration: 99.430439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:43.347083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:43.847501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:44.348398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:44.849408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:44.991623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000136848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T22:54:44.991729Z","caller":"traceutil/trace.go:171","msg":"trace[763213925] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000284801s","start":"2026-02-18T22:54:42.991416Z","end":"2026-02-18T22:54:44.991701Z","steps":["trace[763213925] 'agreement among raft nodes before linearized reading' (duration: 2.000156478s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:44.991825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:42.991403Z","time spent":"2.000404654s","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 22:54:44 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T22:54:45.156082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.779291ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:45.156207Z","caller":"traceutil/trace.go:171","msg":"trace[719291144] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:660; }","duration":"212.913684ms","start":"2026-02-18T22:54:44.943273Z","end":"2026-02-18T22:54:45.156186Z","steps":["trace[719291144] 'range keys from in-memory index tree' (duration: 212.76335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.310943Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.486015148s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:54:45.311231Z","caller":"traceutil/trace.go:171","msg":"trace[1409595487] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"2.486358745s","start":"2026-02-18T22:54:42.824848Z","end":"2026-02-18T22:54:45.311207Z","steps":["trace[1409595487] 'process raft request' (duration: 2.486206731s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.31132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:42.824829Z","time spent":"2.486430017s","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:45.885677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.371106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:45.885895Z","caller":"traceutil/trace.go:171","msg":"trace[119575699] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:681; }","duration":"3.039501124s","start":"2026-02-18T22:54:42.846378Z","end":"2026-02-18T22:54:45.885879Z","steps":["trace[119575699] 'read index received' (duration: 2.464786433s)","trace[119575699] 'applied index is now lower than readState.Index' (duration: 574.713481ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:45.886097Z","caller":"traceutil/trace.go:171","msg":"trace[247187804] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"3.057168239s","start":"2026-02-18T22:54:42.828917Z","end":"2026-02-18T22:54:45.886086Z","steps":["trace[247187804] 'process raft request' (duration: 2.898308522s)","trace[247187804] 'compare' (duration: 157.759512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:45.886156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:42.828906Z","time spent":"3.05721691s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:45.88633Z","caller":"traceutil/trace.go:171","msg":"trace[1515665543] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"3.057357053s","start":"2026-02-18T22:54:42.828963Z","end":"2026-02-18T22:54:45.88632Z","steps":["trace[1515665543] 'process raft request' (duration: 3.056837242s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.886379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:42.828959Z","time spent":"3.057393544s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:45.886607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.04022428s","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-02-18T22:54:45.886635Z","caller":"traceutil/trace.go:171","msg":"trace[1444024064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"3.040268931s","start":"2026-02-18T22:54:42.846357Z","end":"2026-02-18T22:54:45.886626Z","steps":["trace[1444024064] 'agreement among raft nodes before linearized reading' (duration: 3.040160258s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.886663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:42.84635Z","time spent":"3.040305472s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:45.886836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353669724s","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-02-18T22:54:45.886862Z","caller":"traceutil/trace.go:171","msg":"trace[865082018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"1.353744776s","start":"2026-02-18T22:54:44.533109Z","end":"2026-02-18T22:54:45.886854Z","steps":["trace[865082018] 'agreement among raft nodes before linearized reading' (duration: 1.353674054s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.886887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:44.533084Z","time spent":"1.353797926s","remote":"127.0.0.1:43658","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-02-18T22:54:45.887153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.428264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xqh4m\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-18T22:54:45.88718Z","caller":"traceutil/trace.go:171","msg":"trace[2044725164] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xqh4m; range_end:; response_count:1; response_revision:663; }","duration":"569.484565ms","start":"2026-02-18T22:54:45.317688Z","end":"2026-02-18T22:54:45.887172Z","steps":["trace[2044725164] 'agreement among raft nodes before linearized reading' (duration: 569.400843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.887204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:45.317675Z","time spent":"569.523706ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-xqh4m\" "} {"level":"warn","ts":"2026-02-18T22:54:45.887332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.035123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:45.887355Z","caller":"traceutil/trace.go:171","msg":"trace[1195786151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"895.088425ms","start":"2026-02-18T22:54:44.992259Z","end":"2026-02-18T22:54:45.887348Z","steps":["trace[1195786151] 'agreement among raft nodes before linearized reading' (duration: 895.053724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.887377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:44.992217Z","time spent":"895.155486ms","remote":"127.0.0.1:43358","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-02-18T22:54:45.887804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073023768s","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-02-18T22:54:45.887889Z","caller":"traceutil/trace.go:171","msg":"trace[1485481391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"1.0731399s","start":"2026-02-18T22:54:44.81472Z","end":"2026-02-18T22:54:45.88786Z","steps":["trace[1485481391] 'agreement among raft nodes before linearized reading' (duration: 1.072920515s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.887932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:44.814698Z","time spent":"1.073225152s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:54:56.438771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920723993,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:56.487014Z","caller":"traceutil/trace.go:171","msg":"trace[315300946] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"549.243118ms","start":"2026-02-18T22:54:55.937751Z","end":"2026-02-18T22:54:56.486994Z","steps":["trace[315300946] 'read index received' (duration: 549.122755ms)","trace[315300946] 'applied index is now lower than readState.Index' (duration: 119.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:56.487108Z","caller":"traceutil/trace.go:171","msg":"trace[1452906343] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"552.03103ms","start":"2026-02-18T22:54:55.935067Z","end":"2026-02-18T22:54:56.487098Z","steps":["trace[1452906343] 'process raft request' (duration: 551.813985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:56.487188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:55.935058Z","time spent":"552.069981ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:56.487299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.922184ms","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-02-18T22:54:56.48735Z","caller":"traceutil/trace.go:171","msg":"trace[1616835339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"469.995805ms","start":"2026-02-18T22:54:56.01734Z","end":"2026-02-18T22:54:56.487336Z","steps":["trace[1616835339] 'agreement among raft nodes before linearized reading' (duration: 469.861262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:56.487377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:56.017329Z","time spent":"470.043247ms","remote":"127.0.0.1:43658","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-02-18T22:54:56.487503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.75033ms","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-02-18T22:54:56.487521Z","caller":"traceutil/trace.go:171","msg":"trace[338855025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"549.776381ms","start":"2026-02-18T22:54:55.937738Z","end":"2026-02-18T22:54:56.487514Z","steps":["trace[338855025] 'agreement among raft nodes before linearized reading' (duration: 549.72513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:56.487538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:55.937733Z","time spent":"549.801511ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:56.487619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.700997ms","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-02-18T22:54:56.487649Z","caller":"traceutil/trace.go:171","msg":"trace[182543103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"446.738578ms","start":"2026-02-18T22:54:56.040902Z","end":"2026-02-18T22:54:56.487641Z","steps":["trace[182543103] 'agreement among raft nodes before linearized reading' (duration: 446.646126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:56.487656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.037235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T22:54:56.487668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:56.040898Z","time spent":"446.766488ms","remote":"127.0.0.1:43658","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-02-18T22:54:56.487674Z","caller":"traceutil/trace.go:171","msg":"trace[433230600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"450.081027ms","start":"2026-02-18T22:54:56.037588Z","end":"2026-02-18T22:54:56.487669Z","steps":["trace[433230600] 'agreement among raft nodes before linearized reading' (duration: 450.032495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:56.487696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:56.037575Z","time spent":"450.117227ms","remote":"127.0.0.1:43658","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-02-18T22:54:56.7878Z","caller":"traceutil/trace.go:171","msg":"trace[154792024] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"293.382324ms","start":"2026-02-18T22:54:56.494401Z","end":"2026-02-18T22:54:56.787784Z","steps":["trace[154792024] 'process raft request' (duration: 253.902806ms)","trace[154792024] 'compare' (duration: 38.832043ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:56.787908Z","caller":"traceutil/trace.go:171","msg":"trace[237620696] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"293.461476ms","start":"2026-02-18T22:54:56.494441Z","end":"2026-02-18T22:54:56.787903Z","steps":["trace[237620696] 'process raft request' (duration: 293.036166ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:56.787999Z","caller":"traceutil/trace.go:171","msg":"trace[329974484] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"291.876409ms","start":"2026-02-18T22:54:56.496117Z","end":"2026-02-18T22:54:56.787993Z","steps":["trace[329974484] 'process raft request' (duration: 291.390148ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:56.788066Z","caller":"traceutil/trace.go:171","msg":"trace[71346838] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:770; }","duration":"236.587489ms","start":"2026-02-18T22:54:56.551473Z","end":"2026-02-18T22:54:56.788061Z","steps":["trace[71346838] 'read index received' (duration: 19.228582ms)","trace[71346838] 'applied index is now lower than readState.Index' (duration: 217.358267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:56.788204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.629421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:56.788238Z","caller":"traceutil/trace.go:171","msg":"trace[1786682517] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:751; }","duration":"236.764504ms","start":"2026-02-18T22:54:56.551464Z","end":"2026-02-18T22:54:56.788228Z","steps":["trace[1786682517] 'agreement among raft nodes before linearized reading' (duration: 236.615741ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:57.846396Z","caller":"traceutil/trace.go:171","msg":"trace[5171076] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"187.124128ms","start":"2026-02-18T22:54:57.659243Z","end":"2026-02-18T22:54:57.846367Z","steps":["trace[5171076] 'process raft request' (duration: 186.945644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:59.003111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.401144ms","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-02-18T22:54:59.00317Z","caller":"traceutil/trace.go:171","msg":"trace[119558099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"204.475905ms","start":"2026-02-18T22:54:58.79868Z","end":"2026-02-18T22:54:59.003156Z","steps":["trace[119558099] 'range keys from in-memory index tree' (duration: 204.288991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:59.003204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010906217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:59.003261Z","caller":"traceutil/trace.go:171","msg":"trace[1764032685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"1.011008218s","start":"2026-02-18T22:54:57.992236Z","end":"2026-02-18T22:54:59.003245Z","steps":["trace[1764032685] 'range keys from in-memory index tree' (duration: 1.010779404s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:59.003289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.992216Z","time spent":"1.01106772s","remote":"127.0.0.1:43352","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-02-18T22:54:59.00339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.713349ms","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-02-18T22:54:59.00341Z","caller":"traceutil/trace.go:171","msg":"trace[1881148482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"409.732509ms","start":"2026-02-18T22:54:58.593671Z","end":"2026-02-18T22:54:59.003404Z","steps":["trace[1881148482] 'range keys from in-memory index tree' (duration: 409.602896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:59.003428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.593609Z","time spent":"409.8134ms","remote":"127.0.0.1:43658","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-02-18T22:54:59.003577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.50149ms","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-02-18T22:54:59.003589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.582311ms","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-02-18T22:54:59.003598Z","caller":"traceutil/trace.go:171","msg":"trace[1021734632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"511.557101ms","start":"2026-02-18T22:54:58.492034Z","end":"2026-02-18T22:54:59.003592Z","steps":["trace[1021734632] 'range keys from in-memory index tree' (duration: 511.394838ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:59.003608Z","caller":"traceutil/trace.go:171","msg":"trace[902631451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"208.636903ms","start":"2026-02-18T22:54:58.794965Z","end":"2026-02-18T22:54:59.003602Z","steps":["trace[902631451] 'range keys from in-memory index tree' (duration: 208.402578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:59.003642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.492016Z","time spent":"511.619402ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:59.003724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.304534ms","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-02-18T22:54:59.003745Z","caller":"traceutil/trace.go:171","msg":"trace[1149324001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"205.361966ms","start":"2026-02-18T22:54:58.798377Z","end":"2026-02-18T22:54:59.003739Z","steps":["trace[1149324001] 'range keys from in-memory index tree' (duration: 205.205822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:59.516725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920724019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:00.017621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920724019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:00.095923Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.085244873s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:00.096267Z","caller":"traceutil/trace.go:171","msg":"trace[2060582249] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.085644271s","start":"2026-02-18T22:54:59.010605Z","end":"2026-02-18T22:55:00.096249Z","steps":["trace[2060582249] 'process raft request' (duration: 1.085548099s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.096387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:59.01059Z","time spent":"1.085740584s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:00.099386Z","caller":"traceutil/trace.go:171","msg":"trace[363149521] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.087376634s","start":"2026-02-18T22:54:59.011996Z","end":"2026-02-18T22:55:00.099373Z","steps":["trace[363149521] 'process raft request' (duration: 1.087232949s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:00.099394Z","caller":"traceutil/trace.go:171","msg":"trace[1674077848] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:779; }","duration":"1.083535163s","start":"2026-02-18T22:54:59.015843Z","end":"2026-02-18T22:55:00.099378Z","steps":["trace[1674077848] 'read index received' (duration: 1.08084676s)","trace[1674077848] 'applied index is now lower than readState.Index' (duration: 2.687663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:00.09945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:59.011984Z","time spent":"1.087433706s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:00.099476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083612475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:00.099493Z","caller":"traceutil/trace.go:171","msg":"trace[1385192146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"1.083657116s","start":"2026-02-18T22:54:59.015831Z","end":"2026-02-18T22:55:00.099488Z","steps":["trace[1385192146] 'agreement among raft nodes before linearized reading' (duration: 1.083595015s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.099514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:59.015825Z","time spent":"1.083684397s","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:00.099654Z","caller":"traceutil/trace.go:171","msg":"trace[1915008561] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.087552498s","start":"2026-02-18T22:54:59.012096Z","end":"2026-02-18T22:55:00.099649Z","steps":["trace[1915008561] 'process raft request' (duration: 1.087213s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.099702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:59.012091Z","time spent":"1.087575519s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:00.09979Z","caller":"traceutil/trace.go:171","msg":"trace[397821432] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.0876481s","start":"2026-02-18T22:54:59.012137Z","end":"2026-02-18T22:55:00.099785Z","steps":["trace[397821432] 'process raft request' (duration: 1.08719831s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.099817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:59.012133Z","time spent":"1.087669511s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:00.099859Z","caller":"traceutil/trace.go:171","msg":"trace[235052226] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"1.087674161s","start":"2026-02-18T22:54:59.012181Z","end":"2026-02-18T22:55:00.099855Z","steps":["trace[235052226] 'process raft request' (duration: 1.087176099s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.099878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:59.012177Z","time spent":"1.087690571s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:01.597784Z","caller":"traceutil/trace.go:171","msg":"trace[200022215] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"163.304067ms","start":"2026-02-18T22:55:01.434458Z","end":"2026-02-18T22:55:01.597762Z","steps":["trace[200022215] 'process raft request' (duration: 163.176214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.433505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.889088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.433572Z","caller":"traceutil/trace.go:171","msg":"trace[2018059988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"442.98579ms","start":"2026-02-18T22:55:01.990569Z","end":"2026-02-18T22:55:02.433555Z","steps":["trace[2018059988] 'range keys from in-memory index tree' (duration: 442.841547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.433621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.990557Z","time spent":"443.056382ms","remote":"127.0.0.1:43358","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-02-18T22:55:02.43394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.338666ms","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-02-18T22:55:02.43397Z","caller":"traceutil/trace.go:171","msg":"trace[1647158351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"334.399098ms","start":"2026-02-18T22:55:02.099561Z","end":"2026-02-18T22:55:02.43396Z","steps":["trace[1647158351] 'range keys from in-memory index tree' (duration: 334.222964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.433995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.099547Z","time spent":"334.442829ms","remote":"127.0.0.1:43658","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-02-18T22:55:02.434165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.003239ms","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-02-18T22:55:02.434191Z","caller":"traceutil/trace.go:171","msg":"trace[890601668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"331.041929ms","start":"2026-02-18T22:55:02.103141Z","end":"2026-02-18T22:55:02.434183Z","steps":["trace[890601668] 'range keys from in-memory index tree' (duration: 330.929067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.434261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.103135Z","time spent":"331.07429ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:02.434417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.62529ms","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-02-18T22:55:02.434442Z","caller":"traceutil/trace.go:171","msg":"trace[1387327479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"330.65722ms","start":"2026-02-18T22:55:02.103777Z","end":"2026-02-18T22:55:02.434434Z","steps":["trace[1387327479] 'range keys from in-memory index tree' (duration: 330.572499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.43447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.103774Z","time spent":"330.690731ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:55:02.43461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.855815ms","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-02-18T22:55:02.434633Z","caller":"traceutil/trace.go:171","msg":"trace[1675310606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"330.889636ms","start":"2026-02-18T22:55:02.103737Z","end":"2026-02-18T22:55:02.434627Z","steps":["trace[1675310606] 'range keys from in-memory index tree' (duration: 330.759063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.434677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.103732Z","time spent":"330.940137ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:02.43482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.690421ms","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-02-18T22:55:02.434844Z","caller":"traceutil/trace.go:171","msg":"trace[929185336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"330.724172ms","start":"2026-02-18T22:55:02.104113Z","end":"2026-02-18T22:55:02.434837Z","steps":["trace[929185336] 'range keys from in-memory index tree' (duration: 330.619569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.434868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.104106Z","time spent":"330.755863ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:55:02.888242Z","caller":"traceutil/trace.go:171","msg":"trace[1003743451] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"448.50823ms","start":"2026-02-18T22:55:02.439716Z","end":"2026-02-18T22:55:02.888224Z","steps":["trace[1003743451] 'process raft request' (duration: 448.397607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.888355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.439697Z","time spent":"448.604322ms","remote":"127.0.0.1:43658","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-02-18T22:55:03.106754Z","caller":"traceutil/trace.go:171","msg":"trace[1264211612] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"665.331385ms","start":"2026-02-18T22:55:02.441399Z","end":"2026-02-18T22:55:03.106731Z","steps":["trace[1264211612] 'process raft request' (duration: 629.263757ms)","trace[1264211612] 'compare' (duration: 35.748511ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:03.106799Z","caller":"traceutil/trace.go:171","msg":"trace[745742933] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"664.24181ms","start":"2026-02-18T22:55:02.442529Z","end":"2026-02-18T22:55:03.106771Z","steps":["trace[745742933] 'process raft request' (duration: 664.014555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:03.106885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.441391Z","time spent":"665.433497ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:03.106892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.442525Z","time spent":"664.314871ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:03.106978Z","caller":"traceutil/trace.go:171","msg":"trace[1823853144] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"662.99702ms","start":"2026-02-18T22:55:02.443975Z","end":"2026-02-18T22:55:03.106972Z","steps":["trace[1823853144] 'process raft request' (duration: 662.592431ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:03.107008Z","caller":"traceutil/trace.go:171","msg":"trace[583780781] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"664.539496ms","start":"2026-02-18T22:55:02.442463Z","end":"2026-02-18T22:55:03.107003Z","steps":["trace[583780781] 'process raft request' (duration: 664.054185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:03.107007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.443968Z","time spent":"663.022941ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:03.107034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.442457Z","time spent":"664.562587ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:03.371454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.839054ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:55:03.371588Z","caller":"traceutil/trace.go:171","msg":"trace[963586625] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:785; }","duration":"453.856366ms","start":"2026-02-18T22:55:02.917715Z","end":"2026-02-18T22:55:03.371571Z","steps":["trace[963586625] 'read index received' (duration: 152.875183ms)","trace[963586625] 'applied index is now lower than readState.Index' (duration: 300.979703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:03.371958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.232614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:55:03.371942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.915105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:03.371994Z","caller":"traceutil/trace.go:171","msg":"trace[1013352865] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:764; }","duration":"454.304266ms","start":"2026-02-18T22:55:02.917677Z","end":"2026-02-18T22:55:03.371981Z","steps":["trace[1013352865] 'agreement among raft nodes before linearized reading' (duration: 454.233665ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:03.372007Z","caller":"traceutil/trace.go:171","msg":"trace[208149506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"382.014348ms","start":"2026-02-18T22:55:02.989976Z","end":"2026-02-18T22:55:03.37199Z","steps":["trace[208149506] 'agreement among raft nodes before linearized reading' (duration: 381.915765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:03.372051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.917664Z","time spent":"454.376968ms","remote":"127.0.0.1:43620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:03.372088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.989959Z","time spent":"382.121321ms","remote":"127.0.0.1:43352","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-02-18T22:55:03.372271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.539826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:03.372299Z","caller":"traceutil/trace.go:171","msg":"trace[1581878221] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:764; }","duration":"175.570097ms","start":"2026-02-18T22:55:03.19672Z","end":"2026-02-18T22:55:03.37229Z","steps":["trace[1581878221] 'agreement among raft nodes before linearized reading' (duration: 175.524605ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:08.51179Z","caller":"traceutil/trace.go:171","msg":"trace[1927758714] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"263.114062ms","start":"2026-02-18T22:55:08.248654Z","end":"2026-02-18T22:55:08.511768Z","steps":["trace[1927758714] 'process raft request' (duration: 262.985529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.366161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.070783ms","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-02-18T22:55:09.366271Z","caller":"traceutil/trace.go:171","msg":"trace[1748599957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"229.213637ms","start":"2026-02-18T22:55:09.137031Z","end":"2026-02-18T22:55:09.366244Z","steps":["trace[1748599957] 'range keys from in-memory index tree' (duration: 228.926619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.366501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.770759ms","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-02-18T22:55:09.366528Z","caller":"traceutil/trace.go:171","msg":"trace[2007436700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"226.798739ms","start":"2026-02-18T22:55:09.139721Z","end":"2026-02-18T22:55:09.36652Z","steps":["trace[2007436700] 'range keys from in-memory index tree' (duration: 226.696637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.366639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.479267ms","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-02-18T22:55:09.366685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.639232ms","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-02-18T22:55:09.366685Z","caller":"traceutil/trace.go:171","msg":"trace[544173418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"456.548009ms","start":"2026-02-18T22:55:08.910115Z","end":"2026-02-18T22:55:09.366663Z","steps":["trace[544173418] 'range keys from in-memory index tree' (duration: 456.302343ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:09.366702Z","caller":"traceutil/trace.go:171","msg":"trace[1648624341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"219.676723ms","start":"2026-02-18T22:55:09.14702Z","end":"2026-02-18T22:55:09.366697Z","steps":["trace[1648624341] 'range keys from in-memory index tree' (duration: 219.589461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.366717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:08.910095Z","time spent":"456.613301ms","remote":"127.0.0.1:43658","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-02-18T22:55:09.366648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.906312ms","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-02-18T22:55:09.366835Z","caller":"traceutil/trace.go:171","msg":"trace[1160838268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"224.115427ms","start":"2026-02-18T22:55:09.142713Z","end":"2026-02-18T22:55:09.366828Z","steps":["trace[1160838268] 'range keys from in-memory index tree' (duration: 223.82188ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:09.836215Z","caller":"traceutil/trace.go:171","msg":"trace[1370225414] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"463.421ms","start":"2026-02-18T22:55:09.372777Z","end":"2026-02-18T22:55:09.836198Z","steps":["trace[1370225414] 'process raft request' (duration: 463.299718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.836324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.37262Z","time spent":"463.645926ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:09.861718Z","caller":"traceutil/trace.go:171","msg":"trace[1754091443] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"488.590831ms","start":"2026-02-18T22:55:09.373103Z","end":"2026-02-18T22:55:09.861694Z","steps":["trace[1754091443] 'process raft request' (duration: 488.410047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.86187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.373092Z","time spent":"488.690775ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:09.861966Z","caller":"traceutil/trace.go:171","msg":"trace[1578876391] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"488.761746ms","start":"2026-02-18T22:55:09.373182Z","end":"2026-02-18T22:55:09.861944Z","steps":["trace[1578876391] 'process raft request' (duration: 488.47416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.862093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.373175Z","time spent":"488.848168ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:09.862324Z","caller":"traceutil/trace.go:171","msg":"trace[896493074] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"488.280206ms","start":"2026-02-18T22:55:09.374034Z","end":"2026-02-18T22:55:09.862315Z","steps":["trace[896493074] 'process raft request' (duration: 487.837095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.862379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.374022Z","time spent":"488.328267ms","remote":"127.0.0.1:43658","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-02-18T22:55:09.86249Z","caller":"traceutil/trace.go:171","msg":"trace[736215657] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"485.670634ms","start":"2026-02-18T22:55:09.376808Z","end":"2026-02-18T22:55:09.862478Z","steps":["trace[736215657] 'process raft request' (duration: 485.473159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.862554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.376792Z","time spent":"485.728566ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:10.257783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.320659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:10.25785Z","caller":"traceutil/trace.go:171","msg":"trace[989626278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"266.429991ms","start":"2026-02-18T22:55:09.991404Z","end":"2026-02-18T22:55:10.257834Z","steps":["trace[989626278] 'range keys from in-memory index tree' (duration: 266.238337ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:20.178171Z","caller":"traceutil/trace.go:171","msg":"trace[1092337473] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"180.519112ms","start":"2026-02-18T22:55:19.99763Z","end":"2026-02-18T22:55:20.178149Z","steps":["trace[1092337473] 'process raft request' (duration: 180.469771ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:20.178663Z","caller":"traceutil/trace.go:171","msg":"trace[744750718] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"181.891725ms","start":"2026-02-18T22:55:19.996757Z","end":"2026-02-18T22:55:20.178648Z","steps":["trace[744750718] 'process raft request' (duration: 140.372309ms)","trace[744750718] 'compare' (duration: 40.84395ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:24.110033Z","caller":"traceutil/trace.go:171","msg":"trace[1388433254] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"103.327778ms","start":"2026-02-18T22:55:24.00669Z","end":"2026-02-18T22:55:24.110017Z","steps":["trace[1388433254] 'process raft request' (duration: 102.992059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.771706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.489121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:24.771776Z","caller":"traceutil/trace.go:171","msg":"trace[540998] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"575.164657ms","start":"2026-02-18T22:55:24.196602Z","end":"2026-02-18T22:55:24.771767Z","steps":["trace[540998] 'read index received' (duration: 400.541563ms)","trace[540998] 'applied index is now lower than readState.Index' (duration: 174.622084ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:24.771828Z","caller":"traceutil/trace.go:171","msg":"trace[1842403765] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"658.72814ms","start":"2026-02-18T22:55:24.113095Z","end":"2026-02-18T22:55:24.771823Z","steps":["trace[1842403765] 'process raft request' (duration: 484.063885ms)","trace[1842403765] 'compare' (duration: 174.311547ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:24.771862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.113084Z","time spent":"658.758791ms","remote":"127.0.0.1:43658","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-02-18T22:55:24.772006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.105298ms","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-02-18T22:55:24.772053Z","caller":"traceutil/trace.go:171","msg":"trace[2006192238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"573.17671ms","start":"2026-02-18T22:55:24.198862Z","end":"2026-02-18T22:55:24.772039Z","steps":["trace[2006192238] 'agreement among raft nodes before linearized reading' (duration: 572.990426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.772089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.198847Z","time spent":"573.237911ms","remote":"127.0.0.1:43658","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-02-18T22:55:24.772147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.548325ms","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-02-18T22:55:24.772165Z","caller":"traceutil/trace.go:171","msg":"trace[484513665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:874; }","duration":"575.588547ms","start":"2026-02-18T22:55:24.19657Z","end":"2026-02-18T22:55:24.772159Z","steps":["trace[484513665] 'agreement among raft nodes before linearized reading' (duration: 575.508295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.77219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.196545Z","time spent":"575.641447ms","remote":"127.0.0.1:43658","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-02-18T22:55:25.132141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.025181ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:25.13222Z","caller":"traceutil/trace.go:171","msg":"trace[558115595] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:874; }","duration":"189.120414ms","start":"2026-02-18T22:55:24.943088Z","end":"2026-02-18T22:55:25.132208Z","steps":["trace[558115595] 'range keys from in-memory index tree' (duration: 189.013931ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:25.132342Z","caller":"traceutil/trace.go:171","msg":"trace[706724321] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"356.387175ms","start":"2026-02-18T22:55:24.775922Z","end":"2026-02-18T22:55:25.132309Z","steps":["trace[706724321] 'process raft request' (duration: 328.886189ms)","trace[706724321] 'compare' (duration: 27.25792ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:25.132612Z","caller":"traceutil/trace.go:171","msg":"trace[111702912] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:905; }","duration":"142.98085ms","start":"2026-02-18T22:55:24.989624Z","end":"2026-02-18T22:55:25.132605Z","steps":["trace[111702912] 'read index received' (duration: 115.112205ms)","trace[111702912] 'applied index is now lower than readState.Index' (duration: 27.868095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:25.132662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.056252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:25.132677Z","caller":"traceutil/trace.go:171","msg":"trace[558819187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"143.095392ms","start":"2026-02-18T22:55:24.989576Z","end":"2026-02-18T22:55:25.132672Z","steps":["trace[558819187] 'agreement among raft nodes before linearized reading' (duration: 143.051141ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:25.132841Z","caller":"traceutil/trace.go:171","msg":"trace[1166053929] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"355.76104ms","start":"2026-02-18T22:55:24.777074Z","end":"2026-02-18T22:55:25.132835Z","steps":["trace[1166053929] 'process raft request' (duration: 355.455453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:25.132886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.77706Z","time spent":"355.799552ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:25.132466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.775908Z","time spent":"356.501658ms","remote":"127.0.0.1:43658","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-02-18T22:55:28.131909Z","caller":"traceutil/trace.go:171","msg":"trace[1163746648] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"198.833102ms","start":"2026-02-18T22:55:27.933068Z","end":"2026-02-18T22:55:28.131902Z","steps":["trace[1163746648] 'process raft request' (duration: 198.523365ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.131749Z","caller":"traceutil/trace.go:171","msg":"trace[93960659] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"143.00842ms","start":"2026-02-18T22:55:27.988727Z","end":"2026-02-18T22:55:28.131735Z","steps":["trace[93960659] 'read index received' (duration: 142.905588ms)","trace[93960659] 'applied index is now lower than readState.Index' (duration: 102.232µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:28.132415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.678196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:28.132449Z","caller":"traceutil/trace.go:171","msg":"trace[2104826104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"143.742858ms","start":"2026-02-18T22:55:27.988696Z","end":"2026-02-18T22:55:28.132439Z","steps":["trace[2104826104] 'agreement among raft nodes before linearized reading' (duration: 143.41539ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.457896Z","caller":"traceutil/trace.go:171","msg":"trace[1954566539] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"320.83658ms","start":"2026-02-18T22:55:28.137042Z","end":"2026-02-18T22:55:28.457879Z","steps":["trace[1954566539] 'read index received' (duration: 319.236292ms)","trace[1954566539] 'applied index is now lower than readState.Index' (duration: 1.599838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:28.458001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.928342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:28.458027Z","caller":"traceutil/trace.go:171","msg":"trace[1114945016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"321.001523ms","start":"2026-02-18T22:55:28.137019Z","end":"2026-02-18T22:55:28.458021Z","steps":["trace[1114945016] 'agreement among raft nodes before linearized reading' (duration: 320.918511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.458053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:28.13701Z","time spent":"321.038205ms","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:28.458997Z","caller":"traceutil/trace.go:171","msg":"trace[657294041] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"322.53824ms","start":"2026-02-18T22:55:28.136449Z","end":"2026-02-18T22:55:28.458987Z","steps":["trace[657294041] 'process raft request' (duration: 319.805606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.459076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:28.136432Z","time spent":"322.604031ms","remote":"127.0.0.1:43658","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-02-18T22:55:33.262682Z","caller":"traceutil/trace.go:171","msg":"trace[843676194] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"100.615294ms","start":"2026-02-18T22:55:33.162042Z","end":"2026-02-18T22:55:33.262657Z","steps":["trace[843676194] 'process raft request' (duration: 100.4669ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:33.263593Z","caller":"traceutil/trace.go:171","msg":"trace[109419164] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"100.314157ms","start":"2026-02-18T22:55:33.163264Z","end":"2026-02-18T22:55:33.263578Z","steps":["trace[109419164] 'process raft request' (duration: 99.99321ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:35.146979Z","caller":"traceutil/trace.go:171","msg":"trace[1013577206] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"148.871598ms","start":"2026-02-18T22:55:34.998091Z","end":"2026-02-18T22:55:35.146962Z","steps":["trace[1013577206] 'read index received' (duration: 148.731775ms)","trace[1013577206] 'applied index is now lower than readState.Index' (duration: 138.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:35.147064Z","caller":"traceutil/trace.go:171","msg":"trace[409659054] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"333.557089ms","start":"2026-02-18T22:55:34.813502Z","end":"2026-02-18T22:55:35.147059Z","steps":["trace[409659054] 'process raft request' (duration: 333.358074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:35.147132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:34.813493Z","time spent":"333.586199ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:35.147206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.372967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:35.147301Z","caller":"traceutil/trace.go:171","msg":"trace[627797726] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:902; }","duration":"117.48254ms","start":"2026-02-18T22:55:35.029791Z","end":"2026-02-18T22:55:35.147274Z","steps":["trace[627797726] 'agreement among raft nodes before linearized reading' (duration: 117.366657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:35.147397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.310239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:35.147422Z","caller":"traceutil/trace.go:171","msg":"trace[1078262139] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:902; }","duration":"149.33189ms","start":"2026-02-18T22:55:34.99808Z","end":"2026-02-18T22:55:35.147412Z","steps":["trace[1078262139] 'agreement among raft nodes before linearized reading' (duration: 149.296069ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:36.946401Z","caller":"traceutil/trace.go:171","msg":"trace[51692762] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"455.517125ms","start":"2026-02-18T22:55:36.49087Z","end":"2026-02-18T22:55:36.946387Z","steps":["trace[51692762] 'read index received' (duration: 455.402342ms)","trace[51692762] 'applied index is now lower than readState.Index' (duration: 114.173µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:36.94653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.647918ms","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-02-18T22:55:36.946561Z","caller":"traceutil/trace.go:171","msg":"trace[1576532071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:905; }","duration":"455.71409ms","start":"2026-02-18T22:55:36.490842Z","end":"2026-02-18T22:55:36.946556Z","steps":["trace[1576532071] 'agreement among raft nodes before linearized reading' (duration: 455.597867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.94658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.490829Z","time spent":"455.7471ms","remote":"127.0.0.1:43658","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-02-18T22:55:36.946704Z","caller":"traceutil/trace.go:171","msg":"trace[1225024094] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"694.47819ms","start":"2026-02-18T22:55:36.252221Z","end":"2026-02-18T22:55:36.9467Z","steps":["trace[1225024094] 'process raft request' (duration: 694.085001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.946754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.252212Z","time spent":"694.508131ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:37.465766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920724389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:37.966125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958620920724389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:38.109171Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15960436s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:38.109378Z","caller":"traceutil/trace.go:171","msg":"trace[1526391321] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"1.143997355s","start":"2026-02-18T22:55:36.965367Z","end":"2026-02-18T22:55:38.109364Z","steps":["trace[1526391321] 'read index received' (duration: 1.143883792s)","trace[1526391321] 'applied index is now lower than readState.Index' (duration: 113.043µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:38.109524Z","caller":"traceutil/trace.go:171","msg":"trace[425861651] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"1.16000097s","start":"2026-02-18T22:55:36.949518Z","end":"2026-02-18T22:55:38.109519Z","steps":["trace[425861651] 'process raft request' (duration: 1.159765614s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.109584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.949506Z","time spent":"1.16003604s","remote":"127.0.0.1:43658","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-02-18T22:55:38.109688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144318422s","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-02-18T22:55:38.109704Z","caller":"traceutil/trace.go:171","msg":"trace[622248235] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:906; }","duration":"1.144348903s","start":"2026-02-18T22:55:36.96535Z","end":"2026-02-18T22:55:38.109699Z","steps":["trace[622248235] 'agreement among raft nodes before linearized reading' (duration: 1.144319222s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.109726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.965343Z","time spent":"1.144378993s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:38.10983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.283563ms","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-02-18T22:55:38.109842Z","caller":"traceutil/trace.go:171","msg":"trace[1318523247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"959.320483ms","start":"2026-02-18T22:55:37.150518Z","end":"2026-02-18T22:55:38.109838Z","steps":["trace[1318523247] 'agreement among raft nodes before linearized reading' (duration: 959.267472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.109854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:37.150492Z","time spent":"959.359174ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:38.109938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.252239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:38.10995Z","caller":"traceutil/trace.go:171","msg":"trace[1323785915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"121.285851ms","start":"2026-02-18T22:55:37.98866Z","end":"2026-02-18T22:55:38.109946Z","steps":["trace[1323785915] 'agreement among raft nodes before linearized reading' (duration: 121.26547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.110049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.033964ms","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-02-18T22:55:38.110061Z","caller":"traceutil/trace.go:171","msg":"trace[1443017039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"838.070014ms","start":"2026-02-18T22:55:37.271987Z","end":"2026-02-18T22:55:38.110057Z","steps":["trace[1443017039] 'agreement among raft nodes before linearized reading' (duration: 838.035843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.110072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:37.271972Z","time spent":"838.097015ms","remote":"127.0.0.1:43658","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-02-18T22:55:38.110144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.205888ms","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-02-18T22:55:38.110155Z","caller":"traceutil/trace.go:171","msg":"trace[1536902784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"838.226928ms","start":"2026-02-18T22:55:37.271925Z","end":"2026-02-18T22:55:38.110152Z","steps":["trace[1536902784] 'agreement among raft nodes before linearized reading' (duration: 838.195168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.110165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:37.27192Z","time spent":"838.242749ms","remote":"127.0.0.1:43658","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-02-18T22:55:38.110307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.090554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:38.110326Z","caller":"traceutil/trace.go:171","msg":"trace[1070117876] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:906; }","duration":"849.131834ms","start":"2026-02-18T22:55:37.26119Z","end":"2026-02-18T22:55:38.110322Z","steps":["trace[1070117876] 'agreement among raft nodes before linearized reading' (duration: 849.022692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.110338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:37.261165Z","time spent":"849.170576ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-02-18T22:55:38.3032Z","caller":"traceutil/trace.go:171","msg":"trace[1995859359] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"188.004317ms","start":"2026-02-18T22:55:38.115181Z","end":"2026-02-18T22:55:38.303186Z","steps":["trace[1995859359] 'process raft request' (duration: 187.986067ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:38.303438Z","caller":"traceutil/trace.go:171","msg":"trace[1271723564] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"189.372639ms","start":"2026-02-18T22:55:38.114059Z","end":"2026-02-18T22:55:38.303431Z","steps":["trace[1271723564] 'process raft request' (duration: 189.030631ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:38.303498Z","caller":"traceutil/trace.go:171","msg":"trace[719347925] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"188.345556ms","start":"2026-02-18T22:55:38.115149Z","end":"2026-02-18T22:55:38.303495Z","steps":["trace[719347925] 'process raft request' (duration: 188.000058ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:39.517439Z","caller":"traceutil/trace.go:171","msg":"trace[2075230299] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"126.285158ms","start":"2026-02-18T22:55:39.391141Z","end":"2026-02-18T22:55:39.517427Z","steps":["trace[2075230299] 'read index received' (duration: 126.165435ms)","trace[2075230299] 'applied index is now lower than readState.Index' (duration: 119.183µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:39.517576Z","caller":"traceutil/trace.go:171","msg":"trace[690639546] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"346.478633ms","start":"2026-02-18T22:55:39.171091Z","end":"2026-02-18T22:55:39.51757Z","steps":["trace[690639546] 'process raft request' (duration: 346.248397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:39.517632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:39.171074Z","time spent":"346.516113ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:39.517735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.592186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:39.517752Z","caller":"traceutil/trace.go:171","msg":"trace[493902369] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:912; }","duration":"126.634217ms","start":"2026-02-18T22:55:39.391113Z","end":"2026-02-18T22:55:39.517747Z","steps":["trace[493902369] 'agreement among raft nodes before linearized reading' (duration: 126.600446ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:40.45856Z","caller":"traceutil/trace.go:171","msg":"trace[338554780] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"149.405311ms","start":"2026-02-18T22:55:40.309139Z","end":"2026-02-18T22:55:40.458545Z","steps":["trace[338554780] 'read index received' (duration: 149.298529ms)","trace[338554780] 'applied index is now lower than readState.Index' (duration: 106.212µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:40.458639Z","caller":"traceutil/trace.go:171","msg":"trace[455569627] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"342.760005ms","start":"2026-02-18T22:55:40.115875Z","end":"2026-02-18T22:55:40.458635Z","steps":["trace[455569627] 'process raft request' (duration: 342.574831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:40.458702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:40.115866Z","time spent":"342.787756ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:40.458855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.642108ms","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-02-18T22:55:40.458914Z","caller":"traceutil/trace.go:171","msg":"trace[504018227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"149.720599ms","start":"2026-02-18T22:55:40.309178Z","end":"2026-02-18T22:55:40.458898Z","steps":["trace[504018227] 'agreement among raft nodes before linearized reading' (duration: 149.570076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:40.458967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.826842ms","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-02-18T22:55:40.458985Z","caller":"traceutil/trace.go:171","msg":"trace[1976317229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"149.878423ms","start":"2026-02-18T22:55:40.309101Z","end":"2026-02-18T22:55:40.458979Z","steps":["trace[1976317229] 'agreement among raft nodes before linearized reading' (duration: 149.806961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:40.459048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.830692ms","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-02-18T22:55:40.459059Z","caller":"traceutil/trace.go:171","msg":"trace[1898814165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"149.852762ms","start":"2026-02-18T22:55:40.309202Z","end":"2026-02-18T22:55:40.459055Z","steps":["trace[1898814165] 'agreement among raft nodes before linearized reading' (duration: 149.821551ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:41.370555Z","caller":"traceutil/trace.go:171","msg":"trace[761048970] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"379.265512ms","start":"2026-02-18T22:55:40.991269Z","end":"2026-02-18T22:55:41.370535Z","steps":["trace[761048970] 'read index received' (duration: 379.091228ms)","trace[761048970] 'applied index is now lower than readState.Index' (duration: 173.534µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:41.370764Z","caller":"traceutil/trace.go:171","msg":"trace[269332916] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"400.521582ms","start":"2026-02-18T22:55:40.970232Z","end":"2026-02-18T22:55:41.370753Z","steps":["trace[269332916] 'process raft request' (duration: 400.176314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:41.37085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:40.970223Z","time spent":"400.568083ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:41.371053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.790995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:41.371084Z","caller":"traceutil/trace.go:171","msg":"trace[1212466272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"379.844086ms","start":"2026-02-18T22:55:40.991231Z","end":"2026-02-18T22:55:41.371075Z","steps":["trace[1212466272] 'agreement among raft nodes before linearized reading' (duration: 379.793244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:41.371111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:40.991198Z","time spent":"379.906727ms","remote":"127.0.0.1:43352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:42.980336Z","caller":"traceutil/trace.go:171","msg":"trace[1275878597] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"689.023462ms","start":"2026-02-18T22:55:42.291293Z","end":"2026-02-18T22:55:42.980316Z","steps":["trace[1275878597] 'process raft request' (duration: 688.91945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.980448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.291267Z","time spent":"689.118065ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:43.199109Z","caller":"traceutil/trace.go:171","msg":"trace[487530408] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"675.019053ms","start":"2026-02-18T22:55:42.524071Z","end":"2026-02-18T22:55:43.19909Z","steps":["trace[487530408] 'read index received' (duration: 456.671322ms)","trace[487530408] 'applied index is now lower than readState.Index' (duration: 218.346691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:43.199299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.211328ms","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-02-18T22:55:43.199326Z","caller":"traceutil/trace.go:171","msg":"trace[641434270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"675.286449ms","start":"2026-02-18T22:55:42.524033Z","end":"2026-02-18T22:55:43.199319Z","steps":["trace[641434270] 'agreement among raft nodes before linearized reading' (duration: 675.168166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.199353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.524015Z","time spent":"675.33299ms","remote":"127.0.0.1:43658","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-02-18T22:55:43.199593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.095776ms","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-02-18T22:55:43.199616Z","caller":"traceutil/trace.go:171","msg":"trace[1157545656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"661.133216ms","start":"2026-02-18T22:55:42.538477Z","end":"2026-02-18T22:55:43.19961Z","steps":["trace[1157545656] 'agreement among raft nodes before linearized reading' (duration: 661.077115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.199637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.538469Z","time spent":"661.163508ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:43.199761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.168692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:43.199782Z","caller":"traceutil/trace.go:171","msg":"trace[1696672802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"211.207383ms","start":"2026-02-18T22:55:42.98857Z","end":"2026-02-18T22:55:43.199777Z","steps":["trace[1696672802] 'agreement among raft nodes before linearized reading' (duration: 211.176913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.199905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.037054ms","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-02-18T22:55:43.199927Z","caller":"traceutil/trace.go:171","msg":"trace[1200632235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"658.071525ms","start":"2026-02-18T22:55:42.54185Z","end":"2026-02-18T22:55:43.199922Z","steps":["trace[1200632235] 'agreement among raft nodes before linearized reading' (duration: 658.017783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.199949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.541844Z","time spent":"658.098896ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:43.200049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.768462ms","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-02-18T22:55:43.200071Z","caller":"traceutil/trace.go:171","msg":"trace[1778390247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"658.812432ms","start":"2026-02-18T22:55:42.541252Z","end":"2026-02-18T22:55:43.200065Z","steps":["trace[1778390247] 'agreement among raft nodes before linearized reading' (duration: 658.764481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.200091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.541246Z","time spent":"658.841002ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:55:43.462003Z","caller":"traceutil/trace.go:171","msg":"trace[1919724496] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"256.941578ms","start":"2026-02-18T22:55:43.205043Z","end":"2026-02-18T22:55:43.461984Z","steps":["trace[1919724496] 'process raft request' (duration: 210.387274ms)","trace[1919724496] 'compare' (duration: 45.306185ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:43.462126Z","caller":"traceutil/trace.go:171","msg":"trace[1917736185] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"257.051321ms","start":"2026-02-18T22:55:43.205067Z","end":"2026-02-18T22:55:43.462119Z","steps":["trace[1917736185] 'process raft request' (duration: 255.869512ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:43.462239Z","caller":"traceutil/trace.go:171","msg":"trace[1427768059] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"257.122643ms","start":"2026-02-18T22:55:43.205086Z","end":"2026-02-18T22:55:43.462209Z","steps":["trace[1427768059] 'process raft request' (duration: 255.969475ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:51.695101Z","caller":"traceutil/trace.go:171","msg":"trace[678002889] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"198.955095ms","start":"2026-02-18T22:55:51.496122Z","end":"2026-02-18T22:55:51.695077Z","steps":["trace[678002889] 'read index received' (duration: 198.75053ms)","trace[678002889] 'applied index is now lower than readState.Index' (duration: 203.725µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:51.695469Z","caller":"traceutil/trace.go:171","msg":"trace[1990977244] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"200.681956ms","start":"2026-02-18T22:55:51.494766Z","end":"2026-02-18T22:55:51.695448Z","steps":["trace[1990977244] 'process raft request' (duration: 200.162453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.695736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.59367ms","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-02-18T22:55:51.695772Z","caller":"traceutil/trace.go:171","msg":"trace[723503485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"199.649312ms","start":"2026-02-18T22:55:51.496109Z","end":"2026-02-18T22:55:51.695759Z","steps":["trace[723503485] 'agreement among raft nodes before linearized reading' (duration: 199.530239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.695991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.144466ms","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-02-18T22:55:51.696019Z","caller":"traceutil/trace.go:171","msg":"trace[1635179669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"198.172457ms","start":"2026-02-18T22:55:51.497838Z","end":"2026-02-18T22:55:51.696011Z","steps":["trace[1635179669] 'agreement among raft nodes before linearized reading' (duration: 198.086235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.696282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.294422ms","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-02-18T22:55:51.696318Z","caller":"traceutil/trace.go:171","msg":"trace[450515923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"170.356774ms","start":"2026-02-18T22:55:51.525953Z","end":"2026-02-18T22:55:51.69631Z","steps":["trace[450515923] 'agreement among raft nodes before linearized reading' (duration: 170.263342ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:51.927475Z","caller":"traceutil/trace.go:171","msg":"trace[750022051] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1013; }","duration":"183.298387ms","start":"2026-02-18T22:55:51.744154Z","end":"2026-02-18T22:55:51.927453Z","steps":["trace[750022051] 'read index received' (duration: 120.86761ms)","trace[750022051] 'applied index is now lower than readState.Index' (duration: 62.429717ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:51.927721Z","caller":"traceutil/trace.go:171","msg":"trace[229010308] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"226.938973ms","start":"2026-02-18T22:55:51.700767Z","end":"2026-02-18T22:55:51.927706Z","steps":["trace[229010308] 'process raft request' (duration: 164.229309ms)","trace[229010308] 'compare' (duration: 62.262983ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:51.927874Z","caller":"traceutil/trace.go:171","msg":"trace[1673239156] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"221.496736ms","start":"2026-02-18T22:55:51.706368Z","end":"2026-02-18T22:55:51.927865Z","steps":["trace[1673239156] 'process raft request' (duration: 221.013703ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:51.92799Z","caller":"traceutil/trace.go:171","msg":"trace[2045603911] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"215.307639ms","start":"2026-02-18T22:55:51.712673Z","end":"2026-02-18T22:55:51.92798Z","steps":["trace[2045603911] 'process raft request' (duration: 214.748665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.92812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.955273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:51.928155Z","caller":"traceutil/trace.go:171","msg":"trace[19579335] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:979; }","duration":"184.034765ms","start":"2026-02-18T22:55:51.744109Z","end":"2026-02-18T22:55:51.928144Z","steps":["trace[19579335] 'agreement among raft nodes before linearized reading' (duration: 183.967334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.928303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.427116ms","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-02-18T22:55:51.928364Z","caller":"traceutil/trace.go:171","msg":"trace[691463293] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:979; }","duration":"133.476107ms","start":"2026-02-18T22:55:51.794845Z","end":"2026-02-18T22:55:51.928321Z","steps":["trace[691463293] 'agreement among raft nodes before linearized reading' (duration: 133.430936ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.601276Z","caller":"traceutil/trace.go:171","msg":"trace[21086613] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"338.712659ms","start":"2026-02-18T22:55:57.262535Z","end":"2026-02-18T22:55:57.601248Z","steps":["trace[21086613] 'read index received' (duration: 338.537715ms)","trace[21086613] 'applied index is now lower than readState.Index' (duration: 173.864µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:57.601445Z","caller":"traceutil/trace.go:171","msg":"trace[1525741658] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"445.968061ms","start":"2026-02-18T22:55:57.155461Z","end":"2026-02-18T22:55:57.601429Z","steps":["trace[1525741658] 'process raft request' (duration: 445.643283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.601567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:57.155439Z","time spent":"446.041322ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:57.601635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.807976ms","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-02-18T22:55:57.601703Z","caller":"traceutil/trace.go:171","msg":"trace[1964654166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"202.922258ms","start":"2026-02-18T22:55:57.398763Z","end":"2026-02-18T22:55:57.601685Z","steps":["trace[1964654166] 'agreement among raft nodes before linearized reading' (duration: 202.724304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.601861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.352784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:57.601885Z","caller":"traceutil/trace.go:171","msg":"trace[252119100] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1021; }","duration":"339.399306ms","start":"2026-02-18T22:55:57.262478Z","end":"2026-02-18T22:55:57.601878Z","steps":["trace[252119100] 'agreement among raft nodes before linearized reading' (duration: 339.326214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.60191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:57.262466Z","time spent":"339.436737ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-02-18T22:56:14.14185Z","caller":"traceutil/trace.go:171","msg":"trace[1012244168] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"110.025127ms","start":"2026-02-18T22:56:14.031807Z","end":"2026-02-18T22:56:14.141832Z","steps":["trace[1012244168] 'read index received' (duration: 109.863233ms)","trace[1012244168] 'applied index is now lower than readState.Index' (duration: 161.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:14.14193Z","caller":"traceutil/trace.go:171","msg":"trace[2089305306] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"110.828625ms","start":"2026-02-18T22:56:14.031095Z","end":"2026-02-18T22:56:14.141923Z","steps":["trace[2089305306] 'process raft request' (duration: 110.61922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.142121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.299863ms","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-02-18T22:56:14.142141Z","caller":"traceutil/trace.go:171","msg":"trace[749394856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"110.356885ms","start":"2026-02-18T22:56:14.031779Z","end":"2026-02-18T22:56:14.142136Z","steps":["trace[749394856] 'agreement among raft nodes before linearized reading' (duration: 110.253292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:38.840131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.261809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:38.840194Z","caller":"traceutil/trace.go:171","msg":"trace[742993357] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"343.377411ms","start":"2026-02-18T22:56:38.496805Z","end":"2026-02-18T22:56:38.840183Z","steps":["trace[742993357] 'read index received' (duration: 132.968868ms)","trace[742993357] 'applied index is now lower than readState.Index' (duration: 210.407893ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:38.840244Z","caller":"traceutil/trace.go:171","msg":"trace[2019581978] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"365.45543ms","start":"2026-02-18T22:56:38.474782Z","end":"2026-02-18T22:56:38.840238Z","steps":["trace[2019581978] 'process raft request' (duration: 155.033087ms)","trace[2019581978] 'compare' (duration: 210.025194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:38.840273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:38.474763Z","time spent":"365.493901ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:38.840414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.857771ms","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-02-18T22:56:38.840483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.681217ms","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-02-18T22:56:38.840482Z","caller":"traceutil/trace.go:171","msg":"trace[769990612] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1192; }","duration":"320.953123ms","start":"2026-02-18T22:56:38.519512Z","end":"2026-02-18T22:56:38.840465Z","steps":["trace[769990612] 'agreement among raft nodes before linearized reading' (duration: 320.773019ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:38.840502Z","caller":"traceutil/trace.go:171","msg":"trace[347289600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1192; }","duration":"343.721519ms","start":"2026-02-18T22:56:38.496775Z","end":"2026-02-18T22:56:38.840497Z","steps":["trace[347289600] 'agreement among raft nodes before linearized reading' (duration: 343.648587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:38.840519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:38.496757Z","time spent":"343.758469ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:38.840523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:38.519502Z","time spent":"321.012145ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-18T22:56:51.117959Z","caller":"traceutil/trace.go:171","msg":"trace[1486272859] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"126.254653ms","start":"2026-02-18T22:56:50.991681Z","end":"2026-02-18T22:56:51.117936Z","steps":["trace[1486272859] 'read index received' (duration: 126.057837ms)","trace[1486272859] 'applied index is now lower than readState.Index' (duration: 195.756µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:51.118183Z","caller":"traceutil/trace.go:171","msg":"trace[1478111159] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"166.093002ms","start":"2026-02-18T22:56:50.952067Z","end":"2026-02-18T22:56:51.11816Z","steps":["trace[1478111159] 'process raft request' (duration: 165.694693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:51.118371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.684426ms","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-02-18T22:56:51.119307Z","caller":"traceutil/trace.go:171","msg":"trace[33823882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"102.651888ms","start":"2026-02-18T22:56:51.016627Z","end":"2026-02-18T22:56:51.119279Z","steps":["trace[33823882] 'agreement among raft nodes before linearized reading' (duration: 101.574204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:51.118431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.749544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:51.119495Z","caller":"traceutil/trace.go:171","msg":"trace[169872769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"127.843849ms","start":"2026-02-18T22:56:50.991641Z","end":"2026-02-18T22:56:51.119485Z","steps":["trace[169872769] 'agreement among raft nodes before linearized reading' (duration: 126.772725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:51.118497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.417266ms","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-02-18T22:56:51.11967Z","caller":"traceutil/trace.go:171","msg":"trace[88218273] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1241; }","duration":"101.585543ms","start":"2026-02-18T22:56:51.018075Z","end":"2026-02-18T22:56:51.11966Z","steps":["trace[88218273] 'agreement among raft nodes before linearized reading' (duration: 100.372935ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:38.04122Z","caller":"traceutil/trace.go:171","msg":"trace[1493689226] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"162.321664ms","start":"2026-02-18T22:58:37.878871Z","end":"2026-02-18T22:58:38.041192Z","steps":["trace[1493689226] 'process raft request' (duration: 128.859448ms)","trace[1493689226] 'compare' (duration: 32.868431ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:57.382175Z","caller":"traceutil/trace.go:171","msg":"trace[652475127] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1854; }","duration":"109.023998ms","start":"2026-02-18T22:58:57.273131Z","end":"2026-02-18T22:58:57.382155Z","steps":["trace[652475127] 'read index received' (duration: 108.889005ms)","trace[652475127] 'applied index is now lower than readState.Index' (duration: 134.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:57.382318Z","caller":"traceutil/trace.go:171","msg":"trace[1691836632] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"115.55233ms","start":"2026-02-18T22:58:57.266754Z","end":"2026-02-18T22:58:57.382307Z","steps":["trace[1691836632] 'process raft request' (duration: 115.295004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:57.382585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.435308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:58:57.382644Z","caller":"traceutil/trace.go:171","msg":"trace[339161501] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1780; }","duration":"109.52451ms","start":"2026-02-18T22:58:57.273104Z","end":"2026-02-18T22:58:57.382628Z","steps":["trace[339161501] 'agreement among raft nodes before linearized reading' (duration: 109.386537ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:02.438128Z","caller":"traceutil/trace.go:171","msg":"trace[1440522363] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"197.309746ms","start":"2026-02-18T22:59:02.240795Z","end":"2026-02-18T22:59:02.438105Z","steps":["trace[1440522363] 'process raft request' (duration: 118.562131ms)","trace[1440522363] 'compare' (duration: 78.677044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:59:02.947051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.741234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:59:02.947102Z","caller":"traceutil/trace.go:171","msg":"trace[736873413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1803; }","duration":"197.823877ms","start":"2026-02-18T22:59:02.749266Z","end":"2026-02-18T22:59:02.94709Z","steps":["trace[736873413] 'range keys from in-memory index tree' (duration: 197.646543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:02.947111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.82513ms","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-02-18T22:59:02.947183Z","caller":"traceutil/trace.go:171","msg":"trace[302481849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1803; }","duration":"198.947473ms","start":"2026-02-18T22:59:02.748218Z","end":"2026-02-18T22:59:02.947165Z","steps":["trace[302481849] 'range keys from in-memory index tree' (duration: 198.667897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:02.947334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.604709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:59:02.993915Z","caller":"traceutil/trace.go:171","msg":"trace[1044625312] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1803; }","duration":"378.657141ms","start":"2026-02-18T22:59:02.568693Z","end":"2026-02-18T22:59:02.94735Z","steps":["trace[1044625312] 'count revisions from in-memory index tree' (duration: 378.540788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:02.994014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:59:02.568679Z","time spent":"425.308272ms","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:59:03.016522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.042311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:59:03.460611Z","caller":"traceutil/trace.go:171","msg":"trace[1129568963] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"144.350728ms","start":"2026-02-18T22:59:03.316242Z","end":"2026-02-18T22:59:03.460593Z","steps":["trace[1129568963] 'process raft request' (duration: 143.704802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:03.774676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.647824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:59:03.775091Z","caller":"traceutil/trace.go:171","msg":"trace[315652064] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"305.159127ms","start":"2026-02-18T22:59:03.469913Z","end":"2026-02-18T22:59:03.775072Z","steps":["trace[315652064] 'process raft request' (duration: 304.914151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:03.775245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:59:03.469903Z","time spent":"305.232118ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:59:03.775741Z","caller":"traceutil/trace.go:171","msg":"trace[154701947] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"306.329993ms","start":"2026-02-18T22:59:03.469396Z","end":"2026-02-18T22:59:03.775726Z","steps":["trace[154701947] 'process raft request' (duration: 157.560293ms)","trace[154701947] 'compare' (duration: 147.385338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:59:03.776091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:59:03.469378Z","time spent":"306.399085ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:59:12.756296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.049253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:59:12.756392Z","caller":"traceutil/trace.go:171","msg":"trace[234482372] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1854; }","duration":"113.218847ms","start":"2026-02-18T22:59:12.643151Z","end":"2026-02-18T22:59:12.756369Z","steps":["trace[234482372] 'range keys from in-memory index tree' (duration: 112.975021ms)"],"step_count":1}