{"level":"warn","ts":"2026-02-24T02:21:58.778103Z","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-24T02:21:58.778182Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-24T02:21:58.778323Z","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-24T02:21:58.778338Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-02-24T02:21:58.778356Z","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-24T02:21:58.779021Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-02-24T02:21:58.779092Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-24T02:21:58.782931Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.575745ms"} {"level":"info","ts":"2026-02-24T02:21:58.788236Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-02-24T02:21:58.788287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T02:21:58.78837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-02-24T02:21:58.788387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T02:21:58.788394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-02-24T02:21:58.788447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-02-24T02:21:58.794467Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T02:21:58.795601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T02:21:58.796418Z","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-24T02:21:58.798072Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T02:21:58.798226Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T02:21:58.798591Z","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-24T02:21:58.802376Z","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-24T02:21:58.802436Z","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-24T02:21:58.804215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-02-24T02:21:58.805043Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-02-24T02:21:58.807002Z","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-24T02:21:58.807318Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T02:21:58.80737Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T02:21:58.80799Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-02-24T02:21:58.808051Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-02-24T02:21:59.189155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T02:21:59.189219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T02:21:59.189236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-02-24T02:21:59.189251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-02-24T02:21:59.189258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-02-24T02:21:59.189397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-02-24T02:21:59.189407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-02-24T02:21:59.190525Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T02:21:59.190614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T02:21:59.190847Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:21:59.190539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T02:21:59.191048Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T02:21:59.191105Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T02:21:59.19186Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:21:59.19197Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:21:59.192006Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:21:59.203447Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-02-24T02:21:59.203629Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-24T02:22:12.951489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.908461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-24T02:22:12.951657Z","caller":"traceutil/trace.go:171","msg":"trace[1387253309] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"337.175512ms","start":"2026-02-24T02:22:12.614452Z","end":"2026-02-24T02:22:12.951627Z","steps":["trace[1387253309] 'range keys from in-memory index tree' (duration: 336.73935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:12.951636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.477466ms","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-24T02:22:12.951723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:12.614415Z","time spent":"337.296113ms","remote":"127.0.0.1:37552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6918,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-24T02:22:12.951771Z","caller":"traceutil/trace.go:171","msg":"trace[1380861889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"283.705287ms","start":"2026-02-24T02:22:12.668035Z","end":"2026-02-24T02:22:12.95174Z","steps":["trace[1380861889] 'range keys from in-memory index tree' (duration: 283.301295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:12.951916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.750351ms","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-24T02:22:12.952202Z","caller":"traceutil/trace.go:171","msg":"trace[167156890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"214.059382ms","start":"2026-02-24T02:22:12.7381Z","end":"2026-02-24T02:22:12.952159Z","steps":["trace[167156890] 'range keys from in-memory index tree' (duration: 213.175339ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:15.406866Z","caller":"traceutil/trace.go:171","msg":"trace[1473288967] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"124.094053ms","start":"2026-02-24T02:22:15.282746Z","end":"2026-02-24T02:22:15.40684Z","steps":["trace[1473288967] 'process raft request' (duration: 103.787111ms)","trace[1473288967] 'compare' (duration: 19.989761ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:16.443127Z","caller":"traceutil/trace.go:171","msg":"trace[613409935] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"196.533845ms","start":"2026-02-24T02:22:16.246566Z","end":"2026-02-24T02:22:16.4431Z","steps":["trace[613409935] 'process raft request' (duration: 196.379525ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:21.720555Z","caller":"traceutil/trace.go:171","msg":"trace[265899837] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"142.919878ms","start":"2026-02-24T02:22:21.577612Z","end":"2026-02-24T02:22:21.720532Z","steps":["trace[265899837] 'process raft request' (duration: 142.792677ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:23.884164Z","caller":"traceutil/trace.go:171","msg":"trace[916159112] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"156.136665ms","start":"2026-02-24T02:22:23.728005Z","end":"2026-02-24T02:22:23.884141Z","steps":["trace[916159112] 'process raft request' (duration: 155.983684ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:24.233403Z","caller":"traceutil/trace.go:171","msg":"trace[1483141502] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"336.388687ms","start":"2026-02-24T02:22:23.896988Z","end":"2026-02-24T02:22:24.233376Z","steps":["trace[1483141502] 'process raft request' (duration: 336.228506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:24.233996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:23.896971Z","time spent":"336.510217ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:22:29.186635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.699158ms","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-24T02:22:29.18674Z","caller":"traceutil/trace.go:171","msg":"trace[690145498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"125.822829ms","start":"2026-02-24T02:22:29.060884Z","end":"2026-02-24T02:22:29.186707Z","steps":["trace[690145498] 'range keys from in-memory index tree' (duration: 125.625178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:29.186831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.305127ms","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-24T02:22:29.186931Z","caller":"traceutil/trace.go:171","msg":"trace[1306561777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"125.429117ms","start":"2026-02-24T02:22:29.061481Z","end":"2026-02-24T02:22:29.18691Z","steps":["trace[1306561777] 'range keys from in-memory index tree' (duration: 125.237807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:32.235879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.431436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.47\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T02:22:32.236323Z","caller":"traceutil/trace.go:171","msg":"trace[754040494] range","detail":"{range_begin:/registry/masterleases/199.19.213.47; range_end:; response_count:1; response_revision:496; }","duration":"193.578477ms","start":"2026-02-24T02:22:32.042382Z","end":"2026-02-24T02:22:32.23596Z","steps":["trace[754040494] 'range keys from in-memory index tree' (duration: 193.343306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:32.244712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.898854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:22:32.244794Z","caller":"traceutil/trace.go:171","msg":"trace[1082821436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"121.040255ms","start":"2026-02-24T02:22:32.123738Z","end":"2026-02-24T02:22:32.244778Z","steps":["trace[1082821436] 'range keys from in-memory index tree' (duration: 120.828984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:32.245092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.560398ms","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-24T02:22:32.245183Z","caller":"traceutil/trace.go:171","msg":"trace[366097265] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:496; }","duration":"187.650568ms","start":"2026-02-24T02:22:32.057522Z","end":"2026-02-24T02:22:32.245172Z","steps":["trace[366097265] 'range keys from in-memory index tree' (duration: 187.552278ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:32.383878Z","caller":"traceutil/trace.go:171","msg":"trace[1492891523] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"111.801257ms","start":"2026-02-24T02:22:32.272051Z","end":"2026-02-24T02:22:32.383852Z","steps":["trace[1492891523] 'read index received' (duration: 111.649756ms)","trace[1492891523] 'applied index is now lower than readState.Index' (duration: 150.611µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:32.384002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.928657ms","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-24T02:22:32.384026Z","caller":"traceutil/trace.go:171","msg":"trace[109608857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"111.999027ms","start":"2026-02-24T02:22:32.272019Z","end":"2026-02-24T02:22:32.384018Z","steps":["trace[109608857] 'agreement among raft nodes before linearized reading' (duration: 111.918517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:35.161842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.92571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w95zf\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-24T02:22:35.161943Z","caller":"traceutil/trace.go:171","msg":"trace[1552914389] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w95zf; range_end:; response_count:1; response_revision:506; }","duration":"194.068651ms","start":"2026-02-24T02:22:34.967854Z","end":"2026-02-24T02:22:35.161922Z","steps":["trace[1552914389] 'range keys from in-memory index tree' (duration: 193.80444ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:35.62488Z","caller":"traceutil/trace.go:171","msg":"trace[1013003651] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"341.481103ms","start":"2026-02-24T02:22:35.283371Z","end":"2026-02-24T02:22:35.624852Z","steps":["trace[1013003651] 'read index received' (duration: 341.169103ms)","trace[1013003651] 'applied index is now lower than readState.Index' (duration: 310.93µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:35.6249Z","caller":"traceutil/trace.go:171","msg":"trace[1126063151] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"452.992715ms","start":"2026-02-24T02:22:35.171877Z","end":"2026-02-24T02:22:35.62487Z","steps":["trace[1126063151] 'process raft request' (duration: 452.816734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:35.625106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.721334ms","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-24T02:22:35.625109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:35.171848Z","time spent":"453.166145ms","remote":"127.0.0.1:37552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:22:35.625153Z","caller":"traceutil/trace.go:171","msg":"trace[1223555458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"341.814814ms","start":"2026-02-24T02:22:35.283327Z","end":"2026-02-24T02:22:35.625142Z","steps":["trace[1223555458] 'agreement among raft nodes before linearized reading' (duration: 341.624544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:35.625185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:35.283308Z","time spent":"341.869914ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T02:22:35.625449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.803144ms","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-24T02:22:35.625652Z","caller":"traceutil/trace.go:171","msg":"trace[2077304528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"342.022196ms","start":"2026-02-24T02:22:35.28361Z","end":"2026-02-24T02:22:35.625632Z","steps":["trace[2077304528] 'agreement among raft nodes before linearized reading' (duration: 341.584855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:35.625809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:35.283604Z","time spent":"342.188116ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T02:22:36.188195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.492165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:22:36.188301Z","caller":"traceutil/trace.go:171","msg":"trace[1690833592] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"559.527515ms","start":"2026-02-24T02:22:35.628758Z","end":"2026-02-24T02:22:36.188286Z","steps":["trace[1690833592] 'process raft request' (duration: 429.717099ms)","trace[1690833592] 'compare' (duration: 129.385005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:36.188357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:35.628731Z","time spent":"559.599447ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:22:36.635349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.54673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:22:36.635441Z","caller":"traceutil/trace.go:171","msg":"trace[1123544410] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"705.34092ms","start":"2026-02-24T02:22:35.930085Z","end":"2026-02-24T02:22:36.635426Z","steps":["trace[1123544410] 'read index received' (duration: 128.408843ms)","trace[1123544410] 'applied index is now lower than readState.Index' (duration: 576.930677ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:36.635542Z","caller":"traceutil/trace.go:171","msg":"trace[1065241128] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.005608203s","start":"2026-02-24T02:22:35.629918Z","end":"2026-02-24T02:22:36.635526Z","steps":["trace[1065241128] 'process raft request' (duration: 701.799892ms)","trace[1065241128] 'compare' (duration: 303.37369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:36.635606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:35.629903Z","time spent":"1.005671033s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:22:36.635718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.115943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T02:22:36.635749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.655611ms","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-24T02:22:36.635768Z","caller":"traceutil/trace.go:171","msg":"trace[393235746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"512.203874ms","start":"2026-02-24T02:22:36.123553Z","end":"2026-02-24T02:22:36.635757Z","steps":["trace[393235746] 'agreement among raft nodes before linearized reading' (duration: 512.063233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:36.635797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:36.123538Z","time spent":"512.254684ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T02:22:36.635801Z","caller":"traceutil/trace.go:171","msg":"trace[672719454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"705.744341ms","start":"2026-02-24T02:22:35.930043Z","end":"2026-02-24T02:22:36.635787Z","steps":["trace[672719454] 'agreement among raft nodes before linearized reading' (duration: 705.58061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:36.635836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:35.930024Z","time spent":"705.804401ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T02:22:36.635809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.032033ms","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-24T02:22:36.63591Z","caller":"traceutil/trace.go:171","msg":"trace[516190584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"234.178363ms","start":"2026-02-24T02:22:36.401716Z","end":"2026-02-24T02:22:36.635894Z","steps":["trace[516190584] 'agreement among raft nodes before linearized reading' (duration: 233.944212ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:36.831979Z","caller":"traceutil/trace.go:171","msg":"trace[184192027] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"192.146492ms","start":"2026-02-24T02:22:36.6398Z","end":"2026-02-24T02:22:36.831947Z","steps":["trace[184192027] 'process raft request' (duration: 103.851255ms)","trace[184192027] 'compare' (duration: 88.168996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:37.916694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.472582ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T02:22:38.410595Z","caller":"traceutil/trace.go:171","msg":"trace[1463571949] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"215.243797ms","start":"2026-02-24T02:22:38.195328Z","end":"2026-02-24T02:22:38.410572Z","steps":["trace[1463571949] 'process raft request' (duration: 215.085567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:38.796156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.143497ms","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-24T02:22:38.796309Z","caller":"traceutil/trace.go:171","msg":"trace[1599950881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"157.316667ms","start":"2026-02-24T02:22:38.638946Z","end":"2026-02-24T02:22:38.796262Z","steps":["trace[1599950881] 'range keys from in-memory index tree' (duration: 156.219934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:38.796176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.185506ms","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-24T02:22:38.796436Z","caller":"traceutil/trace.go:171","msg":"trace[211641464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"121.559347ms","start":"2026-02-24T02:22:38.674855Z","end":"2026-02-24T02:22:38.796414Z","steps":["trace[211641464] 'range keys from in-memory index tree' (duration: 120.231833ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:38.977778Z","caller":"traceutil/trace.go:171","msg":"trace[1039082725] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"176.986589ms","start":"2026-02-24T02:22:38.800774Z","end":"2026-02-24T02:22:38.97776Z","steps":["trace[1039082725] 'process raft request' (duration: 176.841289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:40.138902Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161194793s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T02:22:41.124207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000092359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T02:22:41.124314Z","caller":"traceutil/trace.go:171","msg":"trace[454568299] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000250849s","start":"2026-02-24T02:22:39.124048Z","end":"2026-02-24T02:22:41.124299Z","steps":["trace[454568299] 'agreement among raft nodes before linearized reading' (duration: 2.000123669s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:41.124387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:39.124028Z","time spent":"2.00034854s","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 02:22:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T02:22:42.140732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001540919s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:22:42.140854Z","caller":"traceutil/trace.go:171","msg":"trace[1604172279] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"3.339256728s","start":"2026-02-24T02:22:38.801563Z","end":"2026-02-24T02:22:42.14082Z","steps":["trace[1604172279] 'process raft request' (duration: 1.337543059s)","trace[1604172279] 'compare' (duration: 2.001215518s)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:42.140946Z","caller":"traceutil/trace.go:171","msg":"trace[2071296777] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"672.281167ms","start":"2026-02-24T02:22:41.468648Z","end":"2026-02-24T02:22:42.140929Z","steps":["trace[2071296777] 'process raft request' (duration: 672.183076ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:42.140973Z","caller":"traceutil/trace.go:171","msg":"trace[1175306276] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"3.306178246s","start":"2026-02-24T02:22:38.834777Z","end":"2026-02-24T02:22:42.140955Z","steps":["trace[1175306276] 'read index received' (duration: 142.879193ms)","trace[1175306276] 'applied index is now lower than readState.Index' (duration: 3.163297373s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:42.141019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:41.468622Z","time spent":"672.365357ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:22:42.141167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725975281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-24T02:22:42.141172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.306394726s","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-24T02:22:42.141199Z","caller":"traceutil/trace.go:171","msg":"trace[1057187341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"1.726052351s","start":"2026-02-24T02:22:40.415137Z","end":"2026-02-24T02:22:42.141189Z","steps":["trace[1057187341] 'agreement among raft nodes before linearized reading' (duration: 1.725912061s)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:42.141214Z","caller":"traceutil/trace.go:171","msg":"trace[790306845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"3.306473426s","start":"2026-02-24T02:22:38.834729Z","end":"2026-02-24T02:22:42.141203Z","steps":["trace[790306845] 'agreement among raft nodes before linearized reading' (duration: 3.306307726s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:42.141228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:40.415116Z","time spent":"1.726105331s","remote":"127.0.0.1:37602","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-24T02:22:42.141247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:38.834693Z","time spent":"3.306547627s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T02:22:42.141327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016882112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:22:42.141352Z","caller":"traceutil/trace.go:171","msg":"trace[545596811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.016934462s","start":"2026-02-24T02:22:41.12441Z","end":"2026-02-24T02:22:42.141344Z","steps":["trace[545596811] 'agreement among raft nodes before linearized reading' (duration: 1.016899162s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:42.141374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:41.124396Z","time spent":"1.016973052s","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T02:22:42.141407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159993771s","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-24T02:22:42.141442Z","caller":"traceutil/trace.go:171","msg":"trace[1057924113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"1.160029501s","start":"2026-02-24T02:22:40.981402Z","end":"2026-02-24T02:22:42.141431Z","steps":["trace[1057924113] 'agreement among raft nodes before linearized reading' (duration: 1.159912871s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:42.14147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:40.981347Z","time spent":"1.160116881s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T02:22:42.141019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:38.801536Z","time spent":"3.339389919s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:22:44.81928Z","caller":"traceutil/trace.go:171","msg":"trace[1513093428] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"643.700835ms","start":"2026-02-24T02:22:44.175557Z","end":"2026-02-24T02:22:44.819258Z","steps":["trace[1513093428] 'read index received' (duration: 61.984003ms)","trace[1513093428] 'applied index is now lower than readState.Index' (duration: 581.715892ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:44.819372Z","caller":"traceutil/trace.go:171","msg":"trace[396732307] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"603.395565ms","start":"2026-02-24T02:22:44.215956Z","end":"2026-02-24T02:22:44.819352Z","steps":["trace[396732307] 'process raft request' (duration: 603.26168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:44.819438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.866671ms","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-24T02:22:44.819464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:44.215937Z","time spent":"603.470589ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T02:22:44.81948Z","caller":"traceutil/trace.go:171","msg":"trace[791218968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"643.943084ms","start":"2026-02-24T02:22:44.175526Z","end":"2026-02-24T02:22:44.819469Z","steps":["trace[791218968] 'agreement among raft nodes before linearized reading' (duration: 643.8417ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:44.819459Z","caller":"traceutil/trace.go:171","msg":"trace[172189064] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"663.699093ms","start":"2026-02-24T02:22:44.155722Z","end":"2026-02-24T02:22:44.819421Z","steps":["trace[172189064] 'process raft request' (duration: 663.36799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:44.819518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:44.175512Z","time spent":"643.999286ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T02:22:44.81961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:44.155702Z","time spent":"663.834028ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T02:22:44.857215Z","caller":"traceutil/trace.go:171","msg":"trace[1452227814] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"439.322979ms","start":"2026-02-24T02:22:44.417872Z","end":"2026-02-24T02:22:44.857195Z","steps":["trace[1452227814] 'process raft request' (duration: 438.917993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:44.857426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:44.41784Z","time spent":"439.511706ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T02:22:44.857237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.431614ms","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-24T02:22:44.857547Z","caller":"traceutil/trace.go:171","msg":"trace[551946575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"680.794178ms","start":"2026-02-24T02:22:44.176734Z","end":"2026-02-24T02:22:44.857528Z","steps":["trace[551946575] 'agreement among raft nodes before linearized reading' (duration: 680.430894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:44.857597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:44.176709Z","time spent":"680.875351ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T02:22:44.857282Z","caller":"traceutil/trace.go:171","msg":"trace[730012050] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"241.487069ms","start":"2026-02-24T02:22:44.615708Z","end":"2026-02-24T02:22:44.857195Z","steps":["trace[730012050] 'process raft request' (duration: 241.383565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:44.857313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.269129ms","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-24T02:22:44.857794Z","caller":"traceutil/trace.go:171","msg":"trace[997709754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"679.753538ms","start":"2026-02-24T02:22:44.178018Z","end":"2026-02-24T02:22:44.857771Z","steps":["trace[997709754] 'agreement among raft nodes before linearized reading' (duration: 679.271779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:44.857866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:22:44.178009Z","time spent":"679.837791ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T02:22:45.144728Z","caller":"traceutil/trace.go:171","msg":"trace[1419775436] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"103.695064ms","start":"2026-02-24T02:22:45.041004Z","end":"2026-02-24T02:22:45.144699Z","steps":["trace[1419775436] 'process raft request' (duration: 85.976523ms)","trace[1419775436] 'compare' (duration: 17.44804ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:45.363248Z","caller":"traceutil/trace.go:171","msg":"trace[992745247] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"133.539482ms","start":"2026-02-24T02:22:45.229677Z","end":"2026-02-24T02:22:45.363224Z","steps":["trace[992745247] 'process raft request' (duration: 133.394846ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:45.392111Z","caller":"traceutil/trace.go:171","msg":"trace[87855603] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"158.901615ms","start":"2026-02-24T02:22:45.23318Z","end":"2026-02-24T02:22:45.392082Z","steps":["trace[87855603] 'process raft request' (duration: 158.76487ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:45.5021Z","caller":"traceutil/trace.go:171","msg":"trace[1763371386] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"103.650822ms","start":"2026-02-24T02:22:45.398428Z","end":"2026-02-24T02:22:45.502079Z","steps":["trace[1763371386] 'process raft request' (duration: 103.518347ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:45.698286Z","caller":"traceutil/trace.go:171","msg":"trace[836490479] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"181.783525ms","start":"2026-02-24T02:22:45.516476Z","end":"2026-02-24T02:22:45.69826Z","steps":["trace[836490479] 'process raft request' (duration: 86.478643ms)","trace[836490479] 'compare' (duration: 95.035992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:45.932117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.129058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:22:45.932228Z","caller":"traceutil/trace.go:171","msg":"trace[80590480] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"117.296296ms","start":"2026-02-24T02:22:45.814914Z","end":"2026-02-24T02:22:45.93221Z","steps":["trace[80590480] 'read index received' (duration: 10.953681ms)","trace[80590480] 'applied index is now lower than readState.Index' (duration: 106.341255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:45.932312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.39962ms","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-24T02:22:45.932345Z","caller":"traceutil/trace.go:171","msg":"trace[562532955] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"117.473013ms","start":"2026-02-24T02:22:45.814862Z","end":"2026-02-24T02:22:45.932335Z","steps":["trace[562532955] 'agreement among raft nodes before linearized reading' (duration: 117.39169ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:45.932513Z","caller":"traceutil/trace.go:171","msg":"trace[1283662862] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"221.615573ms","start":"2026-02-24T02:22:45.710866Z","end":"2026-02-24T02:22:45.932482Z","steps":["trace[1283662862] 'process raft request' (duration: 115.05387ms)","trace[1283662862] 'compare' (duration: 105.980511ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:47.094505Z","caller":"traceutil/trace.go:171","msg":"trace[1475685996] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"138.027472ms","start":"2026-02-24T02:22:46.956441Z","end":"2026-02-24T02:22:47.094468Z","steps":["trace[1475685996] 'process raft request' (duration: 92.159785ms)","trace[1475685996] 'compare' (duration: 45.724052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:22:47.58581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.784791ms","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-24T02:22:47.585914Z","caller":"traceutil/trace.go:171","msg":"trace[1714683813] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:589; }","duration":"171.923647ms","start":"2026-02-24T02:22:47.413955Z","end":"2026-02-24T02:22:47.585879Z","steps":["trace[1714683813] 'range keys from in-memory index tree' (duration: 171.570303ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:53.094577Z","caller":"traceutil/trace.go:171","msg":"trace[361282703] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"104.244551ms","start":"2026-02-24T02:22:52.990314Z","end":"2026-02-24T02:22:53.094559Z","steps":["trace[361282703] 'process raft request' (duration: 104.091156ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:53.143151Z","caller":"traceutil/trace.go:171","msg":"trace[1698854735] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"147.412536ms","start":"2026-02-24T02:22:52.995713Z","end":"2026-02-24T02:22:53.143126Z","steps":["trace[1698854735] 'read index received' (duration: 98.676046ms)","trace[1698854735] 'applied index is now lower than readState.Index' (duration: 48.73579ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:53.143298Z","caller":"traceutil/trace.go:171","msg":"trace[619609368] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"147.148876ms","start":"2026-02-24T02:22:52.996138Z","end":"2026-02-24T02:22:53.143287Z","steps":["trace[619609368] 'process raft request' (duration: 146.926469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:53.143538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.786199ms","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-24T02:22:53.143575Z","caller":"traceutil/trace.go:171","msg":"trace[619796809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"147.870861ms","start":"2026-02-24T02:22:52.995697Z","end":"2026-02-24T02:22:53.143567Z","steps":["trace[619796809] 'agreement among raft nodes before linearized reading' (duration: 147.717996ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:53.143682Z","caller":"traceutil/trace.go:171","msg":"trace[479418341] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"151.25898ms","start":"2026-02-24T02:22:52.992398Z","end":"2026-02-24T02:22:53.143657Z","steps":["trace[479418341] 'process raft request' (duration: 150.487494ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:54.335337Z","caller":"traceutil/trace.go:171","msg":"trace[2055267737] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"173.667575ms","start":"2026-02-24T02:22:54.16163Z","end":"2026-02-24T02:22:54.335297Z","steps":["trace[2055267737] 'process raft request' (duration: 173.409386ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:54.347194Z","caller":"traceutil/trace.go:171","msg":"trace[1295016350] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"170.682863ms","start":"2026-02-24T02:22:54.176477Z","end":"2026-02-24T02:22:54.34716Z","steps":["trace[1295016350] 'read index received' (duration: 158.711293ms)","trace[1295016350] 'applied index is now lower than readState.Index' (duration: 11.97056ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:22:54.347652Z","caller":"traceutil/trace.go:171","msg":"trace[1900523971] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"181.455423ms","start":"2026-02-24T02:22:54.16618Z","end":"2026-02-24T02:22:54.347635Z","steps":["trace[1900523971] 'process raft request' (duration: 180.735718ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:54.347816Z","caller":"traceutil/trace.go:171","msg":"trace[861855301] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"168.745387ms","start":"2026-02-24T02:22:54.179062Z","end":"2026-02-24T02:22:54.347808Z","steps":["trace[861855301] 'process raft request' (duration: 167.996992ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:22:54.347846Z","caller":"traceutil/trace.go:171","msg":"trace[1731891248] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"156.723025ms","start":"2026-02-24T02:22:54.191116Z","end":"2026-02-24T02:22:54.347839Z","steps":["trace[1731891248] 'process raft request' (duration: 155.997011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:22:54.348011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.507911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-24T02:22:54.349314Z","caller":"traceutil/trace.go:171","msg":"trace[2040516361] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:677; }","duration":"172.859688ms","start":"2026-02-24T02:22:54.176437Z","end":"2026-02-24T02:22:54.349296Z","steps":["trace[2040516361] 'agreement among raft nodes before linearized reading' (duration: 171.429589ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:23:01.45525Z","caller":"traceutil/trace.go:171","msg":"trace[2091997749] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"207.498778ms","start":"2026-02-24T02:23:01.247725Z","end":"2026-02-24T02:23:01.455224Z","steps":["trace[2091997749] 'process raft request' (duration: 207.349193ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:23:43.410986Z","caller":"traceutil/trace.go:171","msg":"trace[185039821] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"124.251678ms","start":"2026-02-24T02:23:43.286704Z","end":"2026-02-24T02:23:43.410956Z","steps":["trace[185039821] 'process raft request' (duration: 124.102695ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:23:59.850793Z","caller":"traceutil/trace.go:171","msg":"trace[78083358] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"155.7484ms","start":"2026-02-24T02:23:59.695009Z","end":"2026-02-24T02:23:59.850757Z","steps":["trace[78083358] 'process raft request' (duration: 152.036258ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:06.941432Z","caller":"traceutil/trace.go:171","msg":"trace[1897393232] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"249.142155ms","start":"2026-02-24T02:24:06.692274Z","end":"2026-02-24T02:24:06.941416Z","steps":["trace[1897393232] 'read index received' (duration: 249.000253ms)","trace[1897393232] 'applied index is now lower than readState.Index' (duration: 141.392µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:06.941499Z","caller":"traceutil/trace.go:171","msg":"trace[592712198] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"343.941864ms","start":"2026-02-24T02:24:06.597531Z","end":"2026-02-24T02:24:06.941473Z","steps":["trace[592712198] 'process raft request' (duration: 343.787772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:06.94165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:06.597514Z","time spent":"344.051017ms","remote":"127.0.0.1:37536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5040,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:06.941676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.275007ms","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-24T02:24:06.9417Z","caller":"traceutil/trace.go:171","msg":"trace[1577805376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"249.42722ms","start":"2026-02-24T02:24:06.692266Z","end":"2026-02-24T02:24:06.941693Z","steps":["trace[1577805376] 'agreement among raft nodes before linearized reading' (duration: 249.220827ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:14.271439Z","caller":"traceutil/trace.go:171","msg":"trace[1645049460] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"236.889468ms","start":"2026-02-24T02:24:14.034483Z","end":"2026-02-24T02:24:14.271373Z","steps":["trace[1645049460] 'read index received' (duration: 236.842537ms)","trace[1645049460] 'applied index is now lower than readState.Index' (duration: 45.581µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:14.271551Z","caller":"traceutil/trace.go:171","msg":"trace[577675038] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"239.541606ms","start":"2026-02-24T02:24:14.031977Z","end":"2026-02-24T02:24:14.271518Z","steps":["trace[577675038] 'process raft request' (duration: 239.263512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:14.271703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.160512ms","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-24T02:24:14.27175Z","caller":"traceutil/trace.go:171","msg":"trace[1174744891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"237.274433ms","start":"2026-02-24T02:24:14.034462Z","end":"2026-02-24T02:24:14.271736Z","steps":["trace[1174744891] 'agreement among raft nodes before linearized reading' (duration: 237.08165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:14.52419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.867801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:24:14.524414Z","caller":"traceutil/trace.go:171","msg":"trace[1433738447] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"489.25059ms","start":"2026-02-24T02:24:14.035124Z","end":"2026-02-24T02:24:14.524374Z","steps":["trace[1433738447] 'process raft request' (duration: 330.078294ms)","trace[1433738447] 'compare' (duration: 158.655538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:14.524586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:14.035113Z","time spent":"489.380592ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:24:14.525172Z","caller":"traceutil/trace.go:171","msg":"trace[1289872259] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"253.63674ms","start":"2026-02-24T02:24:14.271517Z","end":"2026-02-24T02:24:14.525154Z","steps":["trace[1289872259] 'read index received' (duration: 93.775264ms)","trace[1289872259] 'applied index is now lower than readState.Index' (duration: 159.860226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:14.525282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.03414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:14.525306Z","caller":"traceutil/trace.go:171","msg":"trace[186910821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"402.102221ms","start":"2026-02-24T02:24:14.123194Z","end":"2026-02-24T02:24:14.525296Z","steps":["trace[186910821] 'agreement among raft nodes before linearized reading' (duration: 402.01424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:14.525348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:14.123166Z","time spent":"402.176002ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T02:24:14.525536Z","caller":"traceutil/trace.go:171","msg":"trace[1084200356] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"289.785367ms","start":"2026-02-24T02:24:14.235732Z","end":"2026-02-24T02:24:14.525517Z","steps":["trace[1084200356] 'process raft request' (duration: 288.576659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:15.368641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765596447739067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T02:24:15.44332Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078055104s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T02:24:15.443615Z","caller":"traceutil/trace.go:171","msg":"trace[2026401449] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"575.996448ms","start":"2026-02-24T02:24:14.867597Z","end":"2026-02-24T02:24:15.443594Z","steps":["trace[2026401449] 'read index received' (duration: 575.816356ms)","trace[2026401449] 'applied index is now lower than readState.Index' (duration: 179.132µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:15.443932Z","caller":"traceutil/trace.go:171","msg":"trace[561588526] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.168014482s","start":"2026-02-24T02:24:14.275906Z","end":"2026-02-24T02:24:15.44392Z","steps":["trace[561588526] 'process raft request' (duration: 1.167574166s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:15.44404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:14.275883Z","time spent":"1.168086114s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:15.444235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.638467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:15.444279Z","caller":"traceutil/trace.go:171","msg":"trace[235932593] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:962; }","duration":"576.715668ms","start":"2026-02-24T02:24:14.86755Z","end":"2026-02-24T02:24:15.444266Z","steps":["trace[235932593] 'agreement among raft nodes before linearized reading' (duration: 576.655287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:15.444314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:14.867531Z","time spent":"576.77457ms","remote":"127.0.0.1:37502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-24T02:24:15.444485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.308817ms","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-24T02:24:15.444532Z","caller":"traceutil/trace.go:171","msg":"trace[2035337055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"465.384219ms","start":"2026-02-24T02:24:14.979134Z","end":"2026-02-24T02:24:15.444518Z","steps":["trace[2035337055] 'agreement among raft nodes before linearized reading' (duration: 465.226166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:15.444564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:14.979119Z","time spent":"465.437509ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T02:24:15.44479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.735023ms","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-24T02:24:15.444846Z","caller":"traceutil/trace.go:171","msg":"trace[151098434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"416.810324ms","start":"2026-02-24T02:24:15.028022Z","end":"2026-02-24T02:24:15.444833Z","steps":["trace[151098434] 'agreement among raft nodes before linearized reading' (duration: 416.665572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:15.44488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.459554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T02:24:15.444885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:15.028009Z","time spent":"416.865475ms","remote":"127.0.0.1:37602","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-24T02:24:15.444917Z","caller":"traceutil/trace.go:171","msg":"trace[789373183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"320.531605ms","start":"2026-02-24T02:24:15.124375Z","end":"2026-02-24T02:24:15.444907Z","steps":["trace[789373183] 'agreement among raft nodes before linearized reading' (duration: 320.471654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:15.444945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:15.124354Z","time spent":"320.584726ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T02:24:15.672709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.370149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:24:15.672878Z","caller":"traceutil/trace.go:171","msg":"trace[460915635] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"221.557707ms","start":"2026-02-24T02:24:15.451303Z","end":"2026-02-24T02:24:15.672861Z","steps":["trace[460915635] 'process raft request' (duration: 221.503526ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:15.672987Z","caller":"traceutil/trace.go:171","msg":"trace[1475722072] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"221.813881ms","start":"2026-02-24T02:24:15.451134Z","end":"2026-02-24T02:24:15.672948Z","steps":["trace[1475722072] 'read index received' (duration: 105.122757ms)","trace[1475722072] 'applied index is now lower than readState.Index' (duration: 116.686654ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:15.673032Z","caller":"traceutil/trace.go:171","msg":"trace[1351798925] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"222.914587ms","start":"2026-02-24T02:24:15.450101Z","end":"2026-02-24T02:24:15.673016Z","steps":["trace[1351798925] 'process raft request' (duration: 106.143032ms)","trace[1351798925] 'compare' (duration: 116.249157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:15.673101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.957533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:15.673291Z","caller":"traceutil/trace.go:171","msg":"trace[1966416500] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:964; }","duration":"222.154255ms","start":"2026-02-24T02:24:15.451113Z","end":"2026-02-24T02:24:15.673267Z","steps":["trace[1966416500] 'agreement among raft nodes before linearized reading' (duration: 221.947232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:17.019457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163971421s","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-24T02:24:17.019532Z","caller":"traceutil/trace.go:171","msg":"trace[760635591] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:964; }","duration":"1.164072393s","start":"2026-02-24T02:24:15.855443Z","end":"2026-02-24T02:24:17.019516Z","steps":["trace[760635591] 'count revisions from in-memory index tree' (duration: 1.16389185s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:17.019561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:15.855391Z","time spent":"1.164162574s","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:24:17.019452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.864423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:17.019702Z","caller":"traceutil/trace.go:171","msg":"trace[1947772838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"895.171498ms","start":"2026-02-24T02:24:16.124523Z","end":"2026-02-24T02:24:17.019695Z","steps":["trace[1947772838] 'range keys from in-memory index tree' (duration: 894.806503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:17.019732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:16.124507Z","time spent":"895.214868ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T02:24:17.019733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.523278ms","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-24T02:24:17.019822Z","caller":"traceutil/trace.go:171","msg":"trace[778285978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"743.64944ms","start":"2026-02-24T02:24:16.276149Z","end":"2026-02-24T02:24:17.019799Z","steps":["trace[778285978] 'range keys from in-memory index tree' (duration: 743.364206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:17.019943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:16.276135Z","time spent":"743.791021ms","remote":"127.0.0.1:37602","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-24T02:24:17.028255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765596447739076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T02:24:17.529432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765596447739076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T02:24:18.030568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765596447739076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T02:24:18.531293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975765596447739076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T02:24:18.883727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.409918862s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T02:24:18.883976Z","caller":"traceutil/trace.go:171","msg":"trace[1809840781] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"2.410247106s","start":"2026-02-24T02:24:16.473713Z","end":"2026-02-24T02:24:18.88396Z","steps":["trace[1809840781] 'process raft request' (duration: 2.410143015s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:18.884069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:16.473692Z","time spent":"2.410327447s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:19.021622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999846232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T02:24:19.021712Z","caller":"traceutil/trace.go:171","msg":"trace[850644122] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999999024s","start":"2026-02-24T02:24:17.02169Z","end":"2026-02-24T02:24:19.021689Z","steps":["trace[850644122] 'agreement among raft nodes before linearized reading' (duration: 1.999877893s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.021764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:17.02167Z","time spent":"2.000081855s","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 02:24:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T02:24:19.696833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.497977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:24:19.69732Z","caller":"traceutil/trace.go:171","msg":"trace[1700464480] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"2.672989169s","start":"2026-02-24T02:24:17.024307Z","end":"2026-02-24T02:24:19.697296Z","steps":["trace[1700464480] 'process raft request' (duration: 2.113910814s)","trace[1700464480] 'compare' (duration: 558.349645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:19.697425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:17.024292Z","time spent":"2.67307734s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:19.850651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.690346ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T02:24:19.850779Z","caller":"traceutil/trace.go:171","msg":"trace[1304232062] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1010; }","duration":"3.322561807s","start":"2026-02-24T02:24:16.528199Z","end":"2026-02-24T02:24:19.85076Z","steps":["trace[1304232062] 'read index received' (duration: 2.355710116s)","trace[1304232062] 'applied index is now lower than readState.Index' (duration: 966.849981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:19.850951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424303172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T02:24:19.850989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.322785371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T02:24:19.851025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674241379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T02:24:19.851032Z","caller":"traceutil/trace.go:171","msg":"trace[1316749695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"3.322869092s","start":"2026-02-24T02:24:16.528153Z","end":"2026-02-24T02:24:19.851022Z","steps":["trace[1316749695] 'agreement among raft nodes before linearized reading' (duration: 3.32270586s)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:19.851035Z","caller":"traceutil/trace.go:171","msg":"trace[197351933] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:966; }","duration":"1.424402263s","start":"2026-02-24T02:24:18.426614Z","end":"2026-02-24T02:24:19.851016Z","steps":["trace[197351933] 'agreement among raft nodes before linearized reading' (duration: 1.424267861s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.851024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.200643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18970d721c25443a\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-02-24T02:24:19.851091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:16.528135Z","time spent":"3.322943383s","remote":"127.0.0.1:37602","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-24T02:24:19.851091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:18.426555Z","time spent":"1.424527214s","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:24:19.850978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360002085s","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-24T02:24:19.851173Z","caller":"traceutil/trace.go:171","msg":"trace[2067401097] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:966; }","duration":"1.360210728s","start":"2026-02-24T02:24:18.490948Z","end":"2026-02-24T02:24:19.851159Z","steps":["trace[2067401097] 'agreement among raft nodes before linearized reading' (duration: 1.359965485s)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:19.851075Z","caller":"traceutil/trace.go:171","msg":"trace[1511810918] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:966; }","duration":"1.67432075s","start":"2026-02-24T02:24:18.176742Z","end":"2026-02-24T02:24:19.851063Z","steps":["trace[1511810918] 'agreement among raft nodes before linearized reading' (duration: 1.674252289s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.85126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.174017571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T02:24:19.85122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.232891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:19.851293Z","caller":"traceutil/trace.go:171","msg":"trace[370177778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"2.174091273s","start":"2026-02-24T02:24:17.677194Z","end":"2026-02-24T02:24:19.851285Z","steps":["trace[370177778] 'agreement among raft nodes before linearized reading' (duration: 2.173961881s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.851293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:18.17672Z","time spent":"1.674562324s","remote":"127.0.0.1:37470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:24:19.85133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:17.677164Z","time spent":"2.174159123s","remote":"127.0.0.1:37602","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-24T02:24:19.851126Z","caller":"traceutil/trace.go:171","msg":"trace[132172586] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18970d721c25443a; range_end:; response_count:1; response_revision:966; }","duration":"823.343424ms","start":"2026-02-24T02:24:19.027758Z","end":"2026-02-24T02:24:19.851101Z","steps":["trace[132172586] 'agreement among raft nodes before linearized reading' (duration: 823.129021ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:19.851327Z","caller":"traceutil/trace.go:171","msg":"trace[408104969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"820.358523ms","start":"2026-02-24T02:24:19.030946Z","end":"2026-02-24T02:24:19.851305Z","steps":["trace[408104969] 'agreement among raft nodes before linearized reading' (duration: 820.17169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.851342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:18.490892Z","time spent":"1.36036004s","remote":"127.0.0.1:37816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:24:19.8512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.377722993s","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-24T02:24:19.851493Z","caller":"traceutil/trace.go:171","msg":"trace[780437762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"2.378037577s","start":"2026-02-24T02:24:17.473444Z","end":"2026-02-24T02:24:19.851482Z","steps":["trace[780437762] 'agreement among raft nodes before linearized reading' (duration: 2.377652061s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.850952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.874927793s","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":"warn","ts":"2026-02-24T02:24:19.851545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:17.473431Z","time spent":"2.378104647s","remote":"127.0.0.1:37602","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-24T02:24:19.851569Z","caller":"traceutil/trace.go:171","msg":"trace[556447892] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:966; }","duration":"2.875603662s","start":"2026-02-24T02:24:16.975949Z","end":"2026-02-24T02:24:19.851553Z","steps":["trace[556447892] 'agreement among raft nodes before linearized reading' (duration: 2.874932132s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.851403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:19.027742Z","time spent":"823.641908ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18970d721c25443a\" "} {"level":"warn","ts":"2026-02-24T02:24:19.851624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:16.97593Z","time spent":"2.875677182s","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:24:19.851401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:19.03094Z","time spent":"820.450404ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T02:24:19.852043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.174858183s","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-24T02:24:19.852779Z","caller":"traceutil/trace.go:171","msg":"trace[1667014301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"2.175623064s","start":"2026-02-24T02:24:17.67714Z","end":"2026-02-24T02:24:19.852763Z","steps":["trace[1667014301] 'agreement among raft nodes before linearized reading' (duration: 2.17386212s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:19.852985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:17.677118Z","time spent":"2.175851747s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T02:24:19.979356Z","caller":"traceutil/trace.go:171","msg":"trace[1414102716] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"123.371792ms","start":"2026-02-24T02:24:19.855947Z","end":"2026-02-24T02:24:19.979318Z","steps":["trace[1414102716] 'process raft request' (duration: 123.16886ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:20.254226Z","caller":"traceutil/trace.go:171","msg":"trace[2085073677] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1014; }","duration":"129.22661ms","start":"2026-02-24T02:24:20.12498Z","end":"2026-02-24T02:24:20.254207Z","steps":["trace[2085073677] 'read index received' (duration: 128.884125ms)","trace[2085073677] 'applied index is now lower than readState.Index' (duration: 341.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:20.254259Z","caller":"traceutil/trace.go:171","msg":"trace[1386777817] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"397.065405ms","start":"2026-02-24T02:24:19.857161Z","end":"2026-02-24T02:24:20.254227Z","steps":["trace[1386777817] 'process raft request' (duration: 396.933673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:20.254346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.345512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:20.254307Z","caller":"traceutil/trace.go:171","msg":"trace[290384316] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"395.838658ms","start":"2026-02-24T02:24:19.858432Z","end":"2026-02-24T02:24:20.25427Z","steps":["trace[290384316] 'process raft request' (duration: 395.713956ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:20.25437Z","caller":"traceutil/trace.go:171","msg":"trace[1896157270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"129.407302ms","start":"2026-02-24T02:24:20.124956Z","end":"2026-02-24T02:24:20.254363Z","steps":["trace[1896157270] 'agreement among raft nodes before linearized reading' (duration: 129.329161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:20.254408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:19.857145Z","time spent":"397.178697ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:20.254494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:19.858419Z","time spent":"395.970639ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:24:20.254527Z","caller":"traceutil/trace.go:171","msg":"trace[810987571] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"398.300842ms","start":"2026-02-24T02:24:19.856216Z","end":"2026-02-24T02:24:20.254517Z","steps":["trace[810987571] 'process raft request' (duration: 397.748785ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:20.254562Z","caller":"traceutil/trace.go:171","msg":"trace[569048331] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"396.064601ms","start":"2026-02-24T02:24:19.858476Z","end":"2026-02-24T02:24:20.254541Z","steps":["trace[569048331] 'process raft request' (duration: 395.693985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:20.254576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:19.856199Z","time spent":"398.349583ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:20.254657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:19.858454Z","time spent":"396.151472ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:24:26.387382Z","caller":"traceutil/trace.go:171","msg":"trace[1823760374] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"107.157186ms","start":"2026-02-24T02:24:26.280205Z","end":"2026-02-24T02:24:26.387362Z","steps":["trace[1823760374] 'process raft request' (duration: 106.971863ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:26.897885Z","caller":"traceutil/trace.go:171","msg":"trace[1188943333] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"614.439705ms","start":"2026-02-24T02:24:26.283413Z","end":"2026-02-24T02:24:26.897852Z","steps":["trace[1188943333] 'read index received' (duration: 103.76965ms)","trace[1188943333] 'applied index is now lower than readState.Index' (duration: 510.669135ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:26.897965Z","caller":"traceutil/trace.go:171","msg":"trace[594225271] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"616.637524ms","start":"2026-02-24T02:24:26.281291Z","end":"2026-02-24T02:24:26.897929Z","steps":["trace[594225271] 'process raft request' (duration: 616.425441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:26.898045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.139168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T02:24:26.898083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.938329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:880"} {"level":"info","ts":"2026-02-24T02:24:26.8981Z","caller":"traceutil/trace.go:171","msg":"trace[848788720] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:991; }","duration":"501.22226ms","start":"2026-02-24T02:24:26.396866Z","end":"2026-02-24T02:24:26.898088Z","steps":["trace[848788720] 'agreement among raft nodes before linearized reading' (duration: 501.141899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:26.898113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:26.281283Z","time spent":"616.742255ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:26.898124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:26.396855Z","time spent":"501.26403ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-24T02:24:26.898118Z","caller":"traceutil/trace.go:171","msg":"trace[498615736] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:991; }","duration":"557.999079ms","start":"2026-02-24T02:24:26.34011Z","end":"2026-02-24T02:24:26.898109Z","steps":["trace[498615736] 'agreement among raft nodes before linearized reading' (duration: 557.922988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:26.898156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:26.340097Z","time spent":"558.053781ms","remote":"127.0.0.1:37690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":904,"request content":"key:\"/registry/clusterroles/system:controller:expand-controller\" "} {"level":"warn","ts":"2026-02-24T02:24:26.89813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.125125ms","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":"warn","ts":"2026-02-24T02:24:26.898211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.831544ms","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-24T02:24:26.898253Z","caller":"traceutil/trace.go:171","msg":"trace[1959166028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"613.017316ms","start":"2026-02-24T02:24:26.285223Z","end":"2026-02-24T02:24:26.898241Z","steps":["trace[1959166028] 'agreement among raft nodes before linearized reading' (duration: 612.758643ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:26.898233Z","caller":"traceutil/trace.go:171","msg":"trace[1981221699] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:991; }","duration":"422.259717ms","start":"2026-02-24T02:24:26.475956Z","end":"2026-02-24T02:24:26.898216Z","steps":["trace[1981221699] 'agreement among raft nodes before linearized reading' (duration: 422.061994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:26.898294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:26.285216Z","time spent":"613.067218ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T02:24:26.898426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.598428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-02-24T02:24:26.898457Z","caller":"traceutil/trace.go:171","msg":"trace[1041522772] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:991; }","duration":"615.058414ms","start":"2026-02-24T02:24:26.283388Z","end":"2026-02-24T02:24:26.898446Z","steps":["trace[1041522772] 'agreement among raft nodes before linearized reading' (duration: 614.574307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:26.898479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:26.283379Z","time spent":"615.094244ms","remote":"127.0.0.1:37700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":773,"request content":"key:\"/registry/clusterrolebindings/cilium\" "} {"level":"warn","ts":"2026-02-24T02:24:26.898298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:26.475912Z","time spent":"422.373709ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:24:27.080486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.187512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:880"} {"level":"warn","ts":"2026-02-24T02:24:27.080572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.376887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-02-24T02:24:27.080602Z","caller":"traceutil/trace.go:171","msg":"trace[1696327545] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:992; }","duration":"102.354384ms","start":"2026-02-24T02:24:26.978229Z","end":"2026-02-24T02:24:27.080583Z","steps":["trace[1696327545] 'range keys from in-memory index tree' (duration: 102.02592ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:27.080629Z","caller":"traceutil/trace.go:171","msg":"trace[52977779] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:992; }","duration":"106.435278ms","start":"2026-02-24T02:24:26.974181Z","end":"2026-02-24T02:24:27.080616Z","steps":["trace[52977779] 'range keys from in-memory index tree' (duration: 106.244606ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:27.778776Z","caller":"traceutil/trace.go:171","msg":"trace[959460715] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"176.158289ms","start":"2026-02-24T02:24:27.602587Z","end":"2026-02-24T02:24:27.778745Z","steps":["trace[959460715] 'read index received' (duration: 79.34412ms)","trace[959460715] 'applied index is now lower than readState.Index' (duration: 96.812659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:27.779053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.451373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:780"} {"level":"info","ts":"2026-02-24T02:24:27.779108Z","caller":"traceutil/trace.go:171","msg":"trace[55802449] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:992; }","duration":"176.557254ms","start":"2026-02-24T02:24:27.602535Z","end":"2026-02-24T02:24:27.779092Z","steps":["trace[55802449] 'agreement among raft nodes before linearized reading' (duration: 176.434762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:27.779138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.008715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-02-24T02:24:27.779183Z","caller":"traceutil/trace.go:171","msg":"trace[790913140] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:992; }","duration":"169.086046ms","start":"2026-02-24T02:24:27.610087Z","end":"2026-02-24T02:24:27.779173Z","steps":["trace[790913140] 'agreement among raft nodes before linearized reading' (duration: 168.998955ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:29.267909Z","caller":"traceutil/trace.go:171","msg":"trace[1498456618] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"304.609486ms","start":"2026-02-24T02:24:28.963279Z","end":"2026-02-24T02:24:29.267889Z","steps":["trace[1498456618] 'process raft request' (duration: 279.193938ms)","trace[1498456618] 'compare' (duration: 25.222766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:29.268037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:28.96326Z","time spent":"304.708468ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:29.268015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.262987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-24T02:24:29.267892Z","caller":"traceutil/trace.go:171","msg":"trace[1422040931] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"302.119824ms","start":"2026-02-24T02:24:28.965741Z","end":"2026-02-24T02:24:29.267861Z","steps":["trace[1422040931] 'read index received' (duration: 276.743367ms)","trace[1422040931] 'applied index is now lower than readState.Index' (duration: 25.375047ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:29.268138Z","caller":"traceutil/trace.go:171","msg":"trace[1564414241] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:997; }","duration":"302.418048ms","start":"2026-02-24T02:24:28.965702Z","end":"2026-02-24T02:24:29.26812Z","steps":["trace[1564414241] 'agreement among raft nodes before linearized reading' (duration: 302.244246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:29.268233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:28.965685Z","time spent":"302.53268ms","remote":"127.0.0.1:37700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":787,"request content":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" "} {"level":"warn","ts":"2026-02-24T02:24:29.327819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.931527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:728"} {"level":"info","ts":"2026-02-24T02:24:29.327904Z","caller":"traceutil/trace.go:171","msg":"trace[1989145881] range","detail":"{range_begin:/registry/clusterroles/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:998; }","duration":"362.088799ms","start":"2026-02-24T02:24:28.965794Z","end":"2026-02-24T02:24:29.327883Z","steps":["trace[1989145881] 'agreement among raft nodes before linearized reading' (duration: 361.942627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:29.327946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:28.965774Z","time spent":"362.164ms","remote":"127.0.0.1:37690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":752,"request content":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" "} {"level":"info","ts":"2026-02-24T02:24:29.328291Z","caller":"traceutil/trace.go:171","msg":"trace[32880925] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"292.0132ms","start":"2026-02-24T02:24:29.036266Z","end":"2026-02-24T02:24:29.32828Z","steps":["trace[32880925] 'process raft request' (duration: 291.348231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:29.328675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.849585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:29.328814Z","caller":"traceutil/trace.go:171","msg":"trace[1862904636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"205.029997ms","start":"2026-02-24T02:24:29.123763Z","end":"2026-02-24T02:24:29.328793Z","steps":["trace[1862904636] 'agreement among raft nodes before linearized reading' (duration: 204.798684ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:31.390138Z","caller":"traceutil/trace.go:171","msg":"trace[869313444] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"115.708962ms","start":"2026-02-24T02:24:31.274403Z","end":"2026-02-24T02:24:31.390112Z","steps":["trace[869313444] 'process raft request' (duration: 115.469319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:37.420667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.749171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:37.42075Z","caller":"traceutil/trace.go:171","msg":"trace[1549894669] range","detail":"{range_begin:/registry/ingress/default/; range_end:/registry/ingress/default0; response_count:0; response_revision:1019; }","duration":"106.884152ms","start":"2026-02-24T02:24:37.313846Z","end":"2026-02-24T02:24:37.42073Z","steps":["trace[1549894669] 'range keys from in-memory index tree' (duration: 106.66992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:37.420786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.729666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-02-24T02:24:37.420818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.879648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:37.420864Z","caller":"traceutil/trace.go:171","msg":"trace[599122865] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1019; }","duration":"104.845778ms","start":"2026-02-24T02:24:37.316Z","end":"2026-02-24T02:24:37.420846Z","steps":["trace[599122865] 'range keys from in-memory index tree' (duration: 104.595564ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:37.420887Z","caller":"traceutil/trace.go:171","msg":"trace[1486446343] range","detail":"{range_begin:/registry/ingress/kube-node-lease/; range_end:/registry/ingress/kube-node-lease0; response_count:0; response_revision:1019; }","duration":"104.961229ms","start":"2026-02-24T02:24:37.315896Z","end":"2026-02-24T02:24:37.420858Z","steps":["trace[1486446343] 'range keys from in-memory index tree' (duration: 104.826377ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:37.823046Z","caller":"traceutil/trace.go:171","msg":"trace[1354678840] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"141.332662ms","start":"2026-02-24T02:24:37.681693Z","end":"2026-02-24T02:24:37.823026Z","steps":["trace[1354678840] 'process raft request' (duration: 132.192111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:38.482448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.920362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T02:24:38.482477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.977028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:38.482508Z","caller":"traceutil/trace.go:171","msg":"trace[1670880416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"358.988912ms","start":"2026-02-24T02:24:38.123506Z","end":"2026-02-24T02:24:38.482494Z","steps":["trace[1670880416] 'range keys from in-memory index tree' (duration: 358.845752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:38.482541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:38.123448Z","time spent":"359.086834ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T02:24:38.48255Z","caller":"traceutil/trace.go:171","msg":"trace[1865585256] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1021; }","duration":"433.09778ms","start":"2026-02-24T02:24:38.049434Z","end":"2026-02-24T02:24:38.482532Z","steps":["trace[1865585256] 'range keys from in-memory index tree' (duration: 432.902677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:38.482608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:38.049415Z","time spent":"433.182891ms","remote":"127.0.0.1:37512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-24T02:24:38.483536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.411805ms","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-24T02:24:38.483626Z","caller":"traceutil/trace.go:171","msg":"trace[1414121608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"500.538857ms","start":"2026-02-24T02:24:37.98307Z","end":"2026-02-24T02:24:38.483609Z","steps":["trace[1414121608] 'range keys from in-memory index tree' (duration: 499.263601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:38.483411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.832745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T02:24:38.48368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:37.983054Z","time spent":"500.613318ms","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T02:24:38.483728Z","caller":"traceutil/trace.go:171","msg":"trace[1748207551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"213.217999ms","start":"2026-02-24T02:24:38.27049Z","end":"2026-02-24T02:24:38.483708Z","steps":["trace[1748207551] 'range keys from in-memory index tree' (duration: 211.64683ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:39.649715Z","caller":"traceutil/trace.go:171","msg":"trace[2114257776] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"250.708732ms","start":"2026-02-24T02:24:39.398982Z","end":"2026-02-24T02:24:39.649691Z","steps":["trace[2114257776] 'read index received' (duration: 250.473509ms)","trace[2114257776] 'applied index is now lower than readState.Index' (duration: 234.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:39.649773Z","caller":"traceutil/trace.go:171","msg":"trace[516368773] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"265.062264ms","start":"2026-02-24T02:24:39.384666Z","end":"2026-02-24T02:24:39.649728Z","steps":["trace[516368773] 'process raft request' (duration: 264.850872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:39.650002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.499067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:2 size:17215"} {"level":"warn","ts":"2026-02-24T02:24:39.649998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.152808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"warn","ts":"2026-02-24T02:24:39.650049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.368143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" limit:500 ","response":"range_response_count:2 size:8877"} {"level":"warn","ts":"2026-02-24T02:24:39.649986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.977616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-24T02:24:39.65005Z","caller":"traceutil/trace.go:171","msg":"trace[1110946039] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:2; response_revision:1026; }","duration":"239.597649ms","start":"2026-02-24T02:24:39.410438Z","end":"2026-02-24T02:24:39.650036Z","steps":["trace[1110946039] 'agreement among raft nodes before linearized reading' (duration: 239.398207ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:39.650064Z","caller":"traceutil/trace.go:171","msg":"trace[133320585] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1026; }","duration":"182.27626ms","start":"2026-02-24T02:24:39.467773Z","end":"2026-02-24T02:24:39.650049Z","steps":["trace[133320585] 'agreement among raft nodes before linearized reading' (duration: 182.120038ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:39.650073Z","caller":"traceutil/trace.go:171","msg":"trace[12756572] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:2; response_revision:1026; }","duration":"188.429845ms","start":"2026-02-24T02:24:39.461634Z","end":"2026-02-24T02:24:39.650064Z","steps":["trace[12756572] 'agreement among raft nodes before linearized reading' (duration: 188.303133ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:39.6501Z","caller":"traceutil/trace.go:171","msg":"trace[1373324441] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:1026; }","duration":"251.137108ms","start":"2026-02-24T02:24:39.398943Z","end":"2026-02-24T02:24:39.65008Z","steps":["trace[1373324441] 'agreement among raft nodes before linearized reading' (duration: 250.886765ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:39.774812Z","caller":"traceutil/trace.go:171","msg":"trace[1414152152] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"111.038414ms","start":"2026-02-24T02:24:39.663745Z","end":"2026-02-24T02:24:39.774784Z","steps":["trace[1414152152] 'process raft request' (duration: 81.275366ms)","trace[1414152152] 'compare' (duration: 29.587096ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:42.080814Z","caller":"traceutil/trace.go:171","msg":"trace[583774081] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"288.996798ms","start":"2026-02-24T02:24:41.791797Z","end":"2026-02-24T02:24:42.080794Z","steps":["trace[583774081] 'read index received' (duration: 288.758525ms)","trace[583774081] 'applied index is now lower than readState.Index' (duration: 237.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:42.080916Z","caller":"traceutil/trace.go:171","msg":"trace[1887628306] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"299.141548ms","start":"2026-02-24T02:24:41.781761Z","end":"2026-02-24T02:24:42.080903Z","steps":["trace[1887628306] 'process raft request' (duration: 298.852135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:42.081355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.544636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:71 size:52476"} {"level":"warn","ts":"2026-02-24T02:24:42.08136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.581717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:42.081382Z","caller":"traceutil/trace.go:171","msg":"trace[716244329] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:71; response_revision:1032; }","duration":"289.587656ms","start":"2026-02-24T02:24:41.791785Z","end":"2026-02-24T02:24:42.081373Z","steps":["trace[716244329] 'agreement among raft nodes before linearized reading' (duration: 289.235722ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:42.081416Z","caller":"traceutil/trace.go:171","msg":"trace[1059825562] range","detail":"{range_begin:/registry/rolebindings/local-path-storage/; range_end:/registry/rolebindings/local-path-storage0; response_count:0; response_revision:1032; }","duration":"279.685668ms","start":"2026-02-24T02:24:41.801714Z","end":"2026-02-24T02:24:42.081399Z","steps":["trace[1059825562] 'agreement among raft nodes before linearized reading' (duration: 279.581397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:42.081774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.910713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4064"} {"level":"info","ts":"2026-02-24T02:24:42.081795Z","caller":"traceutil/trace.go:171","msg":"trace[1766409159] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:1032; }","duration":"237.951784ms","start":"2026-02-24T02:24:41.843837Z","end":"2026-02-24T02:24:42.081789Z","steps":["trace[1766409159] 'agreement among raft nodes before linearized reading' (duration: 237.908343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:42.081905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.667279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T02:24:42.081923Z","caller":"traceutil/trace.go:171","msg":"trace[910704777] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1032; }","duration":"101.687179ms","start":"2026-02-24T02:24:41.980231Z","end":"2026-02-24T02:24:42.081918Z","steps":["trace[910704777] 'agreement among raft nodes before linearized reading' (duration: 101.653179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:42.08201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.823116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/local-path-storage/\" range_end:\"/registry/roles/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:42.082028Z","caller":"traceutil/trace.go:171","msg":"trace[502333122] range","detail":"{range_begin:/registry/roles/local-path-storage/; range_end:/registry/roles/local-path-storage0; response_count:0; response_revision:1032; }","duration":"234.891398ms","start":"2026-02-24T02:24:41.84713Z","end":"2026-02-24T02:24:42.082022Z","steps":["trace[502333122] 'agreement among raft nodes before linearized reading' (duration: 234.863647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:45.751282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.664659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-24T02:24:45.751405Z","caller":"traceutil/trace.go:171","msg":"trace[421232446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1043; }","duration":"111.820441ms","start":"2026-02-24T02:24:45.639539Z","end":"2026-02-24T02:24:45.751359Z","steps":["trace[421232446] 'range keys from in-memory index tree' (duration: 111.527517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:50.280251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.050664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:24:50.280427Z","caller":"traceutil/trace.go:171","msg":"trace[810859752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"156.287977ms","start":"2026-02-24T02:24:50.124118Z","end":"2026-02-24T02:24:50.280406Z","steps":["trace[810859752] 'range keys from in-memory index tree' (duration: 155.949463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:52.338609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.501883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w95zf\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-24T02:24:52.338738Z","caller":"traceutil/trace.go:171","msg":"trace[1070648451] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w95zf; range_end:; response_count:1; response_revision:1060; }","duration":"135.483116ms","start":"2026-02-24T02:24:52.2032Z","end":"2026-02-24T02:24:52.338683Z","steps":["trace[1070648451] 'range keys from in-memory index tree' (duration: 133.249841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:52.585667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.149052ms","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-24T02:24:52.585759Z","caller":"traceutil/trace.go:171","msg":"trace[976229361] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1061; }","duration":"107.292295ms","start":"2026-02-24T02:24:52.478443Z","end":"2026-02-24T02:24:52.585735Z","steps":["trace[976229361] 'count revisions from in-memory index tree' (duration: 107.032821ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:53.033927Z","caller":"traceutil/trace.go:171","msg":"trace[1264228557] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"165.456085ms","start":"2026-02-24T02:24:52.868448Z","end":"2026-02-24T02:24:53.033904Z","steps":["trace[1264228557] 'read index received' (duration: 165.303563ms)","trace[1264228557] 'applied index is now lower than readState.Index' (duration: 151.312µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:53.034044Z","caller":"traceutil/trace.go:171","msg":"trace[1506949884] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"202.046428ms","start":"2026-02-24T02:24:52.831987Z","end":"2026-02-24T02:24:53.034034Z","steps":["trace[1506949884] 'process raft request' (duration: 201.759875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:53.034276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.588596ms","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-24T02:24:53.034373Z","caller":"traceutil/trace.go:171","msg":"trace[1313886410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"165.688698ms","start":"2026-02-24T02:24:52.868645Z","end":"2026-02-24T02:24:53.034333Z","steps":["trace[1313886410] 'agreement among raft nodes before linearized reading' (duration: 165.532186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:53.03461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.149233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w95zf\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-24T02:24:53.034664Z","caller":"traceutil/trace.go:171","msg":"trace[1016841866] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w95zf; range_end:; response_count:1; response_revision:1062; }","duration":"166.261804ms","start":"2026-02-24T02:24:52.86839Z","end":"2026-02-24T02:24:53.034651Z","steps":["trace[1016841866] 'agreement among raft nodes before linearized reading' (duration: 166.073342ms)"],"step_count":1}