{"level":"warn","ts":"2026-02-25T02:14:22.847634Z","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-25T02:14:22.847715Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.247: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.247:2380","--initial-cluster=instance=https://199.19.213.247:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.247:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.247: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-25T02:14:22.847805Z","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-25T02:14:22.84782Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.247:2380"]} {"level":"info","ts":"2026-02-25T02:14:22.847836Z","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-25T02:14:22.848458Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.247:2379"]} {"level":"info","ts":"2026-02-25T02:14:22.848554Z","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.247:2380"],"listen-peer-urls":["https://199.19.213.247:2380"],"advertise-client-urls":["https://199.19.213.247:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.247:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.247: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-25T02:14:22.853032Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.226093ms"} {"level":"info","ts":"2026-02-25T02:14:22.86104Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8ec24054f9d92918","cluster-id":"f45e237da85474b5"} {"level":"info","ts":"2026-02-25T02:14:22.861131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:14:22.861166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became follower at term 0"} {"level":"info","ts":"2026-02-25T02:14:22.861186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8ec24054f9d92918 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:14:22.861239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became follower at term 1"} {"level":"info","ts":"2026-02-25T02:14:22.861323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 switched to configuration voters=(10286855232580823320)"} {"level":"warn","ts":"2026-02-25T02:14:22.87423Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:14:22.875709Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:14:22.876894Z","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-25T02:14:22.879201Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8ec24054f9d92918","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:14:22.879404Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8ec24054f9d92918","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:14:22.879674Z","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-25T02:14:22.879708Z","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-25T02:14:22.879718Z","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-25T02:14:22.882043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 switched to configuration voters=(10286855232580823320)"} {"level":"info","ts":"2026-02-25T02:14:22.882195Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f45e237da85474b5","local-member-id":"8ec24054f9d92918","added-peer-id":"8ec24054f9d92918","added-peer-peer-urls":["https://199.19.213.247:2380"]} {"level":"info","ts":"2026-02-25T02:14:22.88428Z","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-25T02:14:22.884399Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.247:2380"} {"level":"info","ts":"2026-02-25T02:14:22.884437Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.247:2380"} {"level":"info","ts":"2026-02-25T02:14:22.884684Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8ec24054f9d92918","initial-advertise-peer-urls":["https://199.19.213.247:2380"],"listen-peer-urls":["https://199.19.213.247:2380"],"advertise-client-urls":["https://199.19.213.247:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.247:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:14:22.884723Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:14:23.162404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:14:23.162552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:14:23.162643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 received MsgPreVoteResp from 8ec24054f9d92918 at term 1"} {"level":"info","ts":"2026-02-25T02:14:23.162709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:14:23.162786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 received MsgVoteResp from 8ec24054f9d92918 at term 2"} {"level":"info","ts":"2026-02-25T02:14:23.162848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became leader at term 2"} {"level":"info","ts":"2026-02-25T02:14:23.162917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8ec24054f9d92918 elected leader 8ec24054f9d92918 at term 2"} {"level":"info","ts":"2026-02-25T02:14:23.164099Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8ec24054f9d92918","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.247:2379]}","request-path":"/0/members/8ec24054f9d92918/attributes","cluster-id":"f45e237da85474b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:14:23.164125Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:14:23.16417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:14:23.164138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:14:23.165208Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f45e237da85474b5","local-member-id":"8ec24054f9d92918","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:14:23.165293Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:14:23.165339Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:14:23.165408Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:14:23.165454Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:14:23.166651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:14:23.168533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.247:2379"} {"level":"info","ts":"2026-02-25T02:14:46.878099Z","caller":"traceutil/trace.go:171","msg":"trace[1155588828] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"126.383541ms","start":"2026-02-25T02:14:46.751684Z","end":"2026-02-25T02:14:46.878067Z","steps":["trace[1155588828] 'process raft request' (duration: 126.209697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:46.900131Z","caller":"traceutil/trace.go:171","msg":"trace[1623956940] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"146.372204ms","start":"2026-02-25T02:14:46.753729Z","end":"2026-02-25T02:14:46.900101Z","steps":["trace[1623956940] 'process raft request' (duration: 146.23529ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:48.025012Z","caller":"traceutil/trace.go:171","msg":"trace[1067109644] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"114.245738ms","start":"2026-02-25T02:14:47.91074Z","end":"2026-02-25T02:14:48.024986Z","steps":["trace[1067109644] 'process raft request' (duration: 114.089184ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:49.098729Z","caller":"traceutil/trace.go:171","msg":"trace[1956946893] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"193.411103ms","start":"2026-02-25T02:14:48.905291Z","end":"2026-02-25T02:14:49.098702Z","steps":["trace[1956946893] 'read index received' (duration: 193.275459ms)","trace[1956946893] 'applied index is now lower than readState.Index' (duration: 134.484µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:49.098799Z","caller":"traceutil/trace.go:171","msg":"trace[1871024929] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"213.064036ms","start":"2026-02-25T02:14:48.885705Z","end":"2026-02-25T02:14:49.098769Z","steps":["trace[1871024929] 'process raft request' (duration: 212.84222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:49.098977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.614789ms","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-25T02:14:49.099132Z","caller":"traceutil/trace.go:171","msg":"trace[1787481350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"193.848155ms","start":"2026-02-25T02:14:48.90523Z","end":"2026-02-25T02:14:49.099078Z","steps":["trace[1787481350] 'agreement among raft nodes before linearized reading' (duration: 193.597458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.78558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231903525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"warn","ts":"2026-02-25T02:14:54.785633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.877733ms","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-25T02:14:54.785712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670883661s","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-25T02:14:54.785712Z","caller":"traceutil/trace.go:171","msg":"trace[477057033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"662.002427ms","start":"2026-02-25T02:14:54.123691Z","end":"2026-02-25T02:14:54.785693Z","steps":["trace[477057033] 'range keys from in-memory index tree' (duration: 661.797612ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:54.785732Z","caller":"traceutil/trace.go:171","msg":"trace[140101382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"1.670939312s","start":"2026-02-25T02:14:53.114786Z","end":"2026-02-25T02:14:54.785725Z","steps":["trace[140101382] 'range keys from in-memory index tree' (duration: 1.670766008s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.785761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:53.114764Z","time spent":"1.670990453s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:14:54.785755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.123666Z","time spent":"662.079039ms","remote":"127.0.0.1:52416","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-25T02:14:54.785771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670041778s","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-25T02:14:54.785844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880388933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:54.785886Z","caller":"traceutil/trace.go:171","msg":"trace[1053980577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"1.880459835s","start":"2026-02-25T02:14:52.905409Z","end":"2026-02-25T02:14:54.785869Z","steps":["trace[1053980577] 'range keys from in-memory index tree' (duration: 1.880326561s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.785927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:52.905387Z","time spent":"1.880529637s","remote":"127.0.0.1:52142","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-25T02:14:54.785771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675076432s","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-25T02:14:54.786145Z","caller":"traceutil/trace.go:171","msg":"trace[2135381417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"1.675498453s","start":"2026-02-25T02:14:53.110632Z","end":"2026-02-25T02:14:54.78613Z","steps":["trace[2135381417] 'range keys from in-memory index tree' (duration: 1.674907558s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:54.785665Z","caller":"traceutil/trace.go:171","msg":"trace[295223071] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"1.232034568s","start":"2026-02-25T02:14:53.553616Z","end":"2026-02-25T02:14:54.785651Z","steps":["trace[295223071] 'range keys from in-memory index tree' (duration: 1.231818543s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.78618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:53.110611Z","time spent":"1.675558795s","remote":"127.0.0.1:52416","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-25T02:14:54.786195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:53.553599Z","time spent":"1.232582133s","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4045,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T02:14:54.785885Z","caller":"traceutil/trace.go:171","msg":"trace[1347104391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"1.670152551s","start":"2026-02-25T02:14:53.115688Z","end":"2026-02-25T02:14:54.78584Z","steps":["trace[1347104391] 'range keys from in-memory index tree' (duration: 1.669931825s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.786261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:53.115675Z","time spent":"1.670574432s","remote":"127.0.0.1:52416","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-25T02:14:55.219949Z","caller":"traceutil/trace.go:171","msg":"trace[75844896] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"429.394891ms","start":"2026-02-25T02:14:54.790534Z","end":"2026-02-25T02:14:55.219929Z","steps":["trace[75844896] 'process raft request' (duration: 429.232467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:55.221014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.790513Z","time spent":"429.522384ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:55.371335Z","caller":"traceutil/trace.go:171","msg":"trace[1540470297] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:525; }","duration":"574.957693ms","start":"2026-02-25T02:14:54.796352Z","end":"2026-02-25T02:14:55.37131Z","steps":["trace[1540470297] 'read index received' (duration: 423.517805ms)","trace[1540470297] 'applied index is now lower than readState.Index' (duration: 151.439068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:55.371479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.102757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:55.371474Z","caller":"traceutil/trace.go:171","msg":"trace[73307685] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"579.066532ms","start":"2026-02-25T02:14:54.792376Z","end":"2026-02-25T02:14:55.371442Z","steps":["trace[73307685] 'process raft request' (duration: 578.866187ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:55.371553Z","caller":"traceutil/trace.go:171","msg":"trace[282861381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"575.215699ms","start":"2026-02-25T02:14:54.796323Z","end":"2026-02-25T02:14:55.371539Z","steps":["trace[282861381] 'agreement among raft nodes before linearized reading' (duration: 575.081645ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:55.371533Z","caller":"traceutil/trace.go:171","msg":"trace[1142006439] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"576.400421ms","start":"2026-02-25T02:14:54.795073Z","end":"2026-02-25T02:14:55.371474Z","steps":["trace[1142006439] 'process raft request' (duration: 576.200026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:55.371598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.796315Z","time spent":"575.273101ms","remote":"127.0.0.1:52142","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-25T02:14:55.3717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.792365Z","time spent":"579.205896ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:55.37173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.795063Z","time spent":"576.559615ms","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:55.372007Z","caller":"traceutil/trace.go:171","msg":"trace[2015338091] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"581.015294ms","start":"2026-02-25T02:14:54.790973Z","end":"2026-02-25T02:14:55.371988Z","steps":["trace[2015338091] 'process raft request' (duration: 580.1068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:55.372191Z","caller":"traceutil/trace.go:171","msg":"trace[713006154] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"579.430832ms","start":"2026-02-25T02:14:54.791963Z","end":"2026-02-25T02:14:55.371394Z","steps":["trace[713006154] 'process raft request' (duration: 579.246497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:55.372335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.79194Z","time spent":"580.349156ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:55.37229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.79096Z","time spent":"581.160737ms","remote":"127.0.0.1:52416","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-25T02:15:01.898352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:02.295533Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.044312215s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:15:02.295942Z","caller":"traceutil/trace.go:171","msg":"trace[848742081] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.044792209s","start":"2026-02-25T02:15:01.251121Z","end":"2026-02-25T02:15:02.295913Z","steps":["trace[848742081] 'process raft request' (duration: 1.044622104s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:02.296106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:01.251105Z","time spent":"1.044915962s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:02.444584Z","caller":"traceutil/trace.go:171","msg":"trace[1428495808] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"1.04937493s","start":"2026-02-25T02:15:01.395186Z","end":"2026-02-25T02:15:02.444561Z","steps":["trace[1428495808] 'read index received' (duration: 900.539962ms)","trace[1428495808] 'applied index is now lower than readState.Index' (duration: 148.833448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:02.444741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048352033s","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-25T02:15:02.444742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049544705s","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-25T02:15:02.44478Z","caller":"traceutil/trace.go:171","msg":"trace[1088177604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.048430936s","start":"2026-02-25T02:15:01.396339Z","end":"2026-02-25T02:15:02.44477Z","steps":["trace[1088177604] 'agreement among raft nodes before linearized reading' (duration: 1.048351393s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:02.444818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:01.396317Z","time spent":"1.048492507s","remote":"127.0.0.1:52416","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-25T02:15:02.444847Z","caller":"traceutil/trace.go:171","msg":"trace[893103292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"1.049656078s","start":"2026-02-25T02:15:01.395128Z","end":"2026-02-25T02:15:02.444784Z","steps":["trace[893103292] 'agreement among raft nodes before linearized reading' (duration: 1.049529095s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:02.444924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045937339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:15:02.444973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:01.395098Z","time spent":"1.049830963s","remote":"127.0.0.1:52416","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-25T02:15:02.44501Z","caller":"traceutil/trace.go:171","msg":"trace[1748250267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.046056402s","start":"2026-02-25T02:15:01.398932Z","end":"2026-02-25T02:15:02.444988Z","steps":["trace[1748250267] 'agreement among raft nodes before linearized reading' (duration: 1.045890428s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:02.445061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:01.398921Z","time spent":"1.046129804s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:15:03.431526Z","caller":"traceutil/trace.go:171","msg":"trace[1887416357] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"421.540551ms","start":"2026-02-25T02:15:03.009959Z","end":"2026-02-25T02:15:03.431499Z","steps":["trace[1887416357] 'process raft request' (duration: 421.432198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:03.431629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:03.009931Z","time spent":"421.643334ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:04.452612Z","caller":"traceutil/trace.go:171","msg":"trace[1903029932] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"147.538825ms","start":"2026-02-25T02:15:04.30482Z","end":"2026-02-25T02:15:04.452359Z","steps":["trace[1903029932] 'process raft request' (duration: 147.34988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.273385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613057873s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:06.273864Z","caller":"traceutil/trace.go:171","msg":"trace[1178140571] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"255.437404ms","start":"2026-02-25T02:15:06.018409Z","end":"2026-02-25T02:15:06.273847Z","steps":["trace[1178140571] 'process raft request' (duration: 255.368832ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:06.274056Z","caller":"traceutil/trace.go:171","msg":"trace[321310989] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.78631187s","start":"2026-02-25T02:15:04.487732Z","end":"2026-02-25T02:15:06.274044Z","steps":["trace[321310989] 'process raft request' (duration: 171.957093ms)","trace[321310989] 'compare' (duration: 1.61295328s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:06.274123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:04.487688Z","time spent":"1.786398643s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:06.274237Z","caller":"traceutil/trace.go:171","msg":"trace[22988578] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"1.744780716s","start":"2026-02-25T02:15:04.529447Z","end":"2026-02-25T02:15:06.274228Z","steps":["trace[22988578] 'read index received' (duration: 130.176862ms)","trace[22988578] 'applied index is now lower than readState.Index' (duration: 1.614602714s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:06.274489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.566815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.247\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:15:06.274527Z","caller":"traceutil/trace.go:171","msg":"trace[443366944] range","detail":"{range_begin:/registry/masterleases/199.19.213.247; range_end:; response_count:1; response_revision:535; }","duration":"141.633467ms","start":"2026-02-25T02:15:06.132884Z","end":"2026-02-25T02:15:06.274517Z","steps":["trace[443366944] 'agreement among raft nodes before linearized reading' (duration: 141.572155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.274625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.745130145s","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-25T02:15:06.274707Z","caller":"traceutil/trace.go:171","msg":"trace[2109643106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"1.745298449s","start":"2026-02-25T02:15:04.529391Z","end":"2026-02-25T02:15:06.274689Z","steps":["trace[2109643106] 'agreement among raft nodes before linearized reading' (duration: 1.745005192s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.274754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:04.529367Z","time spent":"1.745376682s","remote":"127.0.0.1:52416","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-25T02:15:06.274779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744405187s","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-25T02:15:06.274811Z","caller":"traceutil/trace.go:171","msg":"trace[806699626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"1.744455687s","start":"2026-02-25T02:15:04.530346Z","end":"2026-02-25T02:15:06.274802Z","steps":["trace[806699626] 'agreement among raft nodes before linearized reading' (duration: 1.744052577s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.274848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:04.530337Z","time spent":"1.744504048s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:15:06.274847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.388191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:06.274936Z","caller":"traceutil/trace.go:171","msg":"trace[61042336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"370.516974ms","start":"2026-02-25T02:15:05.904398Z","end":"2026-02-25T02:15:06.274915Z","steps":["trace[61042336] 'agreement among raft nodes before linearized reading' (duration: 370.384701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.274992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:05.904378Z","time spent":"370.601607ms","remote":"127.0.0.1:52142","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-25T02:15:06.916821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:06.27591Z","time spent":"640.902185ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T02:15:06.9208Z","caller":"traceutil/trace.go:171","msg":"trace[712344156] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"641.050879ms","start":"2026-02-25T02:15:06.279709Z","end":"2026-02-25T02:15:06.92076Z","steps":["trace[712344156] 'process raft request' (duration: 637.792722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.920927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:06.279691Z","time spent":"641.169932ms","remote":"127.0.0.1:52416","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-25T02:15:06.92132Z","caller":"traceutil/trace.go:171","msg":"trace[273165995] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"641.312316ms","start":"2026-02-25T02:15:06.279993Z","end":"2026-02-25T02:15:06.921306Z","steps":["trace[273165995] 'process raft request' (duration: 640.71251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.921395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:06.279978Z","time spent":"641.373187ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:06.92145Z","caller":"traceutil/trace.go:171","msg":"trace[1527876105] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:554; }","duration":"463.961949ms","start":"2026-02-25T02:15:06.45746Z","end":"2026-02-25T02:15:06.921422Z","steps":["trace[1527876105] 'read index received' (duration: 374.954453ms)","trace[1527876105] 'applied index is now lower than readState.Index' (duration: 89.005586ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:06.921615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.169584ms","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-25T02:15:06.921643Z","caller":"traceutil/trace.go:171","msg":"trace[1559386417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"464.236476ms","start":"2026-02-25T02:15:06.457399Z","end":"2026-02-25T02:15:06.921636Z","steps":["trace[1559386417] 'agreement among raft nodes before linearized reading' (duration: 464.164604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:06.921662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:06.457369Z","time spent":"464.289088ms","remote":"127.0.0.1:52416","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-25T02:15:08.208201Z","caller":"traceutil/trace.go:171","msg":"trace[833131001] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"191.908144ms","start":"2026-02-25T02:15:08.016268Z","end":"2026-02-25T02:15:08.208176Z","steps":["trace[833131001] 'process raft request' (duration: 191.766121ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:08.21677Z","caller":"traceutil/trace.go:171","msg":"trace[177668049] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"187.414755ms","start":"2026-02-25T02:15:08.029336Z","end":"2026-02-25T02:15:08.216751Z","steps":["trace[177668049] 'process raft request' (duration: 187.311752ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:08.92208Z","caller":"traceutil/trace.go:171","msg":"trace[1209552542] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"163.226012ms","start":"2026-02-25T02:15:08.758829Z","end":"2026-02-25T02:15:08.922055Z","steps":["trace[1209552542] 'process raft request' (duration: 105.442585ms)","trace[1209552542] 'compare' (duration: 57.494389ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:13.147595Z","caller":"traceutil/trace.go:171","msg":"trace[367249510] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"248.651694ms","start":"2026-02-25T02:15:12.898916Z","end":"2026-02-25T02:15:13.147567Z","steps":["trace[367249510] 'process raft request' (duration: 247.605136ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.147616Z","caller":"traceutil/trace.go:171","msg":"trace[1899716640] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"197.824121ms","start":"2026-02-25T02:15:12.949553Z","end":"2026-02-25T02:15:13.147377Z","steps":["trace[1899716640] 'read index received' (duration: 196.2625ms)","trace[1899716640] 'applied index is now lower than readState.Index' (duration: 1.559801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:13.147701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.14091ms","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-25T02:15:13.147756Z","caller":"traceutil/trace.go:171","msg":"trace[1813978442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"198.238402ms","start":"2026-02-25T02:15:12.949504Z","end":"2026-02-25T02:15:13.147742Z","steps":["trace[1813978442] 'agreement among raft nodes before linearized reading' (duration: 198.102749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:13.14787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.245593ms","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-25T02:15:13.147916Z","caller":"traceutil/trace.go:171","msg":"trace[290491673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"198.348766ms","start":"2026-02-25T02:15:12.949556Z","end":"2026-02-25T02:15:13.147905Z","steps":["trace[290491673] 'agreement among raft nodes before linearized reading' (duration: 198.176181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:13.148013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.635043ms","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-25T02:15:13.148041Z","caller":"traceutil/trace.go:171","msg":"trace[260839986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"192.684845ms","start":"2026-02-25T02:15:12.955349Z","end":"2026-02-25T02:15:13.148034Z","steps":["trace[260839986] 'agreement among raft nodes before linearized reading' (duration: 192.640434ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.273696Z","caller":"traceutil/trace.go:171","msg":"trace[792953395] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"120.682259ms","start":"2026-02-25T02:15:13.152983Z","end":"2026-02-25T02:15:13.273666Z","steps":["trace[792953395] 'process raft request' (duration: 118.500271ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.273838Z","caller":"traceutil/trace.go:171","msg":"trace[2052031823] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"120.521015ms","start":"2026-02-25T02:15:13.153295Z","end":"2026-02-25T02:15:13.273816Z","steps":["trace[2052031823] 'process raft request' (duration: 120.32774ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.274163Z","caller":"traceutil/trace.go:171","msg":"trace[723643887] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:653; }","duration":"120.755641ms","start":"2026-02-25T02:15:13.153384Z","end":"2026-02-25T02:15:13.274139Z","steps":["trace[723643887] 'read index received' (duration: 8.67466ms)","trace[723643887] 'applied index is now lower than readState.Index' (duration: 112.080011ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:13.274411Z","caller":"traceutil/trace.go:171","msg":"trace[2117496265] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"114.447064ms","start":"2026-02-25T02:15:13.159953Z","end":"2026-02-25T02:15:13.2744Z","steps":["trace[2117496265] 'process raft request' (duration: 114.098145ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.274586Z","caller":"traceutil/trace.go:171","msg":"trace[572499484] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"120.927856ms","start":"2026-02-25T02:15:13.153636Z","end":"2026-02-25T02:15:13.274564Z","steps":["trace[572499484] 'process raft request' (duration: 120.134645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:13.274617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.217624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-tlwbp\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-25T02:15:13.274653Z","caller":"traceutil/trace.go:171","msg":"trace[443706706] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-tlwbp; range_end:; response_count:1; response_revision:636; }","duration":"121.283975ms","start":"2026-02-25T02:15:13.153356Z","end":"2026-02-25T02:15:13.27464Z","steps":["trace[443706706] 'agreement among raft nodes before linearized reading' (duration: 121.162502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:13.274795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.376008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-dpntk\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-25T02:15:13.274865Z","caller":"traceutil/trace.go:171","msg":"trace[1162700071] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-dpntk; range_end:; response_count:1; response_revision:636; }","duration":"121.475661ms","start":"2026-02-25T02:15:13.153376Z","end":"2026-02-25T02:15:13.274852Z","steps":["trace[1162700071] 'agreement among raft nodes before linearized reading' (duration: 121.223164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:18.612408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.767924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T02:15:18.612371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.650335ms","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-25T02:15:18.61249Z","caller":"traceutil/trace.go:171","msg":"trace[162214825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"250.879097ms","start":"2026-02-25T02:15:18.36159Z","end":"2026-02-25T02:15:18.612469Z","steps":["trace[162214825] 'range keys from in-memory index tree' (duration: 250.61817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:18.612513Z","caller":"traceutil/trace.go:171","msg":"trace[2111851751] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:663; }","duration":"709.808769ms","start":"2026-02-25T02:15:17.902685Z","end":"2026-02-25T02:15:18.612494Z","steps":["trace[2111851751] 'range keys from in-memory index tree' (duration: 709.632484ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.24688Z","caller":"traceutil/trace.go:171","msg":"trace[1038498578] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"123.528827ms","start":"2026-02-25T02:15:25.123335Z","end":"2026-02-25T02:15:25.246864Z","steps":["trace[1038498578] 'read index received' (duration: 123.381403ms)","trace[1038498578] 'applied index is now lower than readState.Index' (duration: 146.824µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.24696Z","caller":"traceutil/trace.go:171","msg":"trace[846141688] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"599.61777ms","start":"2026-02-25T02:15:24.647335Z","end":"2026-02-25T02:15:25.246953Z","steps":["trace[846141688] 'process raft request' (duration: 599.428355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.247043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.647319Z","time spent":"599.670862ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:25.247143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.806105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:25.247169Z","caller":"traceutil/trace.go:171","msg":"trace[856808808] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:716; }","duration":"123.859386ms","start":"2026-02-25T02:15:25.123302Z","end":"2026-02-25T02:15:25.247162Z","steps":["trace[856808808] 'agreement among raft nodes before linearized reading' (duration: 123.820185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.920476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:26.935849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.702971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:26.936016Z","caller":"traceutil/trace.go:171","msg":"trace[537974902] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"140.141995ms","start":"2026-02-25T02:15:26.795858Z","end":"2026-02-25T02:15:26.936Z","steps":["trace[537974902] 'process raft request' (duration: 140.086814ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.936122Z","caller":"traceutil/trace.go:171","msg":"trace[363476749] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"1.51621632s","start":"2026-02-25T02:15:25.419873Z","end":"2026-02-25T02:15:26.93609Z","steps":["trace[363476749] 'read index received' (duration: 638.20262ms)","trace[363476749] 'applied index is now lower than readState.Index' (duration: 878.00942ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:26.936278Z","caller":"traceutil/trace.go:171","msg":"trace[509101203] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"1.684339839s","start":"2026-02-25T02:15:25.251918Z","end":"2026-02-25T02:15:26.936258Z","steps":["trace[509101203] 'process raft request' (duration: 806.149935ms)","trace[509101203] 'compare' (duration: 877.610579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:26.936405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.251898Z","time spent":"1.684418321s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:26.936673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511971536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T02:15:26.936692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516815177s","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-25T02:15:26.936753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.547315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:26.936754Z","caller":"traceutil/trace.go:171","msg":"trace[1539646662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.516910479s","start":"2026-02-25T02:15:25.419827Z","end":"2026-02-25T02:15:26.936738Z","steps":["trace[1539646662] 'agreement among raft nodes before linearized reading' (duration: 1.516724824s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.93678Z","caller":"traceutil/trace.go:171","msg":"trace[1850834310] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:718; }","duration":"669.601666ms","start":"2026-02-25T02:15:26.267171Z","end":"2026-02-25T02:15:26.936772Z","steps":["trace[1850834310] 'agreement among raft nodes before linearized reading' (duration: 669.562155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.936817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.267153Z","time spent":"669.654647ms","remote":"127.0.0.1:52460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:15:26.936815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.419803Z","time spent":"1.516997891s","remote":"127.0.0.1:52416","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-25T02:15:26.936919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.81219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:15:26.936965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034261848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:26.936997Z","caller":"traceutil/trace.go:171","msg":"trace[4188386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"1.03432463s","start":"2026-02-25T02:15:25.902664Z","end":"2026-02-25T02:15:26.936988Z","steps":["trace[4188386] 'agreement among raft nodes before linearized reading' (duration: 1.034281109s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.936994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517108555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:15:26.937078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089606949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:26.937082Z","caller":"traceutil/trace.go:171","msg":"trace[1957750780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.517236598s","start":"2026-02-25T02:15:25.419825Z","end":"2026-02-25T02:15:26.937062Z","steps":["trace[1957750780] 'agreement among raft nodes before linearized reading' (duration: 1.517009362s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.937104Z","caller":"traceutil/trace.go:171","msg":"trace[949477339] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:718; }","duration":"1.089669131s","start":"2026-02-25T02:15:25.847428Z","end":"2026-02-25T02:15:26.937097Z","steps":["trace[949477339] 'agreement among raft nodes before linearized reading' (duration: 1.08962968s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.937132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.847406Z","time spent":"1.089720093s","remote":"127.0.0.1:52552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:15:26.936711Z","caller":"traceutil/trace.go:171","msg":"trace[282171642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.512040787s","start":"2026-02-25T02:15:25.424658Z","end":"2026-02-25T02:15:26.936699Z","steps":["trace[282171642] 'agreement among raft nodes before linearized reading' (duration: 1.511915234s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.937156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.098972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.247\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T02:15:26.937194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.424641Z","time spent":"1.512536651s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:15:26.937202Z","caller":"traceutil/trace.go:171","msg":"trace[13423950] range","detail":"{range_begin:/registry/masterleases/199.19.213.247; range_end:; response_count:1; response_revision:718; }","duration":"803.173064ms","start":"2026-02-25T02:15:26.134016Z","end":"2026-02-25T02:15:26.937189Z","steps":["trace[13423950] 'agreement among raft nodes before linearized reading' (duration: 803.044441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.937254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.133997Z","time spent":"803.245376ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.247\" "} {"level":"warn","ts":"2026-02-25T02:15:26.93713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.419803Z","time spent":"1.51731619s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:15:26.937038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.902644Z","time spent":"1.034385412s","remote":"127.0.0.1:52142","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-25T02:15:26.936973Z","caller":"traceutil/trace.go:171","msg":"trace[1559013896] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:718; }","duration":"947.911122ms","start":"2026-02-25T02:15:25.989045Z","end":"2026-02-25T02:15:26.936956Z","steps":["trace[1559013896] 'agreement among raft nodes before linearized reading' (duration: 947.81998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.937535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.989023Z","time spent":"948.496108ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:15:27.439667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:28.363988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.50773ms","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-25T02:15:28.36412Z","caller":"traceutil/trace.go:171","msg":"trace[904260049] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:718; }","duration":"461.646664ms","start":"2026-02-25T02:15:27.902455Z","end":"2026-02-25T02:15:28.364101Z","steps":["trace[904260049] 'range keys from in-memory index tree' (duration: 461.494059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.364776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.537604ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-25T02:15:28.364858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.938279Z","time spent":"1.426574694s","remote":"127.0.0.1:52194","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T02:15:28.384029Z","caller":"traceutil/trace.go:171","msg":"trace[886349991] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"1.442731549s","start":"2026-02-25T02:15:26.941278Z","end":"2026-02-25T02:15:28.384009Z","steps":["trace[886349991] 'process raft request' (duration: 1.442467062s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:28.384095Z","caller":"traceutil/trace.go:171","msg":"trace[740389820] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"1.441840686s","start":"2026-02-25T02:15:26.94223Z","end":"2026-02-25T02:15:28.38407Z","steps":["trace[740389820] 'process raft request' (duration: 1.441735793s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:28.384083Z","caller":"traceutil/trace.go:171","msg":"trace[643653842] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.441915628s","start":"2026-02-25T02:15:26.942139Z","end":"2026-02-25T02:15:28.384055Z","steps":["trace[643653842] 'process raft request' (duration: 1.441776604s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.384177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44498889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:15:28.38419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.941228Z","time spent":"1.442858163s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:28.384231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.942221Z","time spent":"1.441940038s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:28.384026Z","caller":"traceutil/trace.go:171","msg":"trace[952662703] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:743; }","duration":"1.444868827s","start":"2026-02-25T02:15:26.939135Z","end":"2026-02-25T02:15:28.384004Z","steps":["trace[952662703] 'read index received' (duration: 828.087485ms)","trace[952662703] 'applied index is now lower than readState.Index' (duration: 616.780382ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:28.384229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.942121Z","time spent":"1.442042002s","remote":"127.0.0.1:52416","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-25T02:15:28.384236Z","caller":"traceutil/trace.go:171","msg":"trace[1068528030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"1.445114854s","start":"2026-02-25T02:15:26.939106Z","end":"2026-02-25T02:15:28.384221Z","steps":["trace[1068528030] 'agreement among raft nodes before linearized reading' (duration: 1.44496163s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.384297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.939092Z","time spent":"1.445192826s","remote":"127.0.0.1:52158","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-25T02:15:28.396079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.267183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:15:28.396121Z","caller":"traceutil/trace.go:171","msg":"trace[272606036] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:722; }","duration":"148.352515ms","start":"2026-02-25T02:15:28.247758Z","end":"2026-02-25T02:15:28.39611Z","steps":["trace[272606036] 'agreement among raft nodes before linearized reading' (duration: 148.255443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.396075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.910056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:28.396148Z","caller":"traceutil/trace.go:171","msg":"trace[1389989955] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:722; }","duration":"516.040099ms","start":"2026-02-25T02:15:27.880101Z","end":"2026-02-25T02:15:28.396141Z","steps":["trace[1389989955] 'agreement among raft nodes before linearized reading' (duration: 515.915986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.396171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:27.880076Z","time spent":"516.08638ms","remote":"127.0.0.1:52472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:15:28.396183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144937018s","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-25T02:15:28.396214Z","caller":"traceutil/trace.go:171","msg":"trace[2006088722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:722; }","duration":"1.14499495s","start":"2026-02-25T02:15:27.251211Z","end":"2026-02-25T02:15:28.396206Z","steps":["trace[2006088722] 'agreement among raft nodes before linearized reading' (duration: 1.144881017s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.396235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:27.251196Z","time spent":"1.145034491s","remote":"127.0.0.1:52416","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-25T02:15:28.64203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.286636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:28.642139Z","caller":"traceutil/trace.go:171","msg":"trace[1601553714] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"242.938014ms","start":"2026-02-25T02:15:28.399178Z","end":"2026-02-25T02:15:28.642116Z","steps":["trace[1601553714] 'process raft request' (duration: 65.437943ms)","trace[1601553714] 'compare' (duration: 176.961796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:30.405764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084404,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:30.673246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73190339s","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-25T02:15:30.673341Z","caller":"traceutil/trace.go:171","msg":"trace[250327973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"1.732047424s","start":"2026-02-25T02:15:28.94127Z","end":"2026-02-25T02:15:30.673318Z","steps":["trace[250327973] 'range keys from in-memory index tree' (duration: 1.731651993s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.673399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:28.94125Z","time spent":"1.732133536s","remote":"127.0.0.1:52416","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-25T02:15:30.673596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.060454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:30.673686Z","caller":"traceutil/trace.go:171","msg":"trace[352388849] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.106713071s","start":"2026-02-25T02:15:29.566954Z","end":"2026-02-25T02:15:30.673667Z","steps":["trace[352388849] 'process raft request' (duration: 999.508503ms)","trace[352388849] 'compare' (duration: 106.18155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:30.67376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:29.566938Z","time spent":"1.106783482s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:31.111332Z","caller":"traceutil/trace.go:171","msg":"trace[1928397749] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"1.206228521s","start":"2026-02-25T02:15:29.905087Z","end":"2026-02-25T02:15:31.111315Z","steps":["trace[1928397749] 'read index received' (duration: 661.347664ms)","trace[1928397749] 'applied index is now lower than readState.Index' (duration: 544.880167ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:31.111478Z","caller":"traceutil/trace.go:171","msg":"trace[1977113810] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"1.506822877s","start":"2026-02-25T02:15:29.604626Z","end":"2026-02-25T02:15:31.111449Z","steps":["trace[1977113810] 'process raft request' (duration: 1.506588961s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.111558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206459287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:31.111614Z","caller":"traceutil/trace.go:171","msg":"trace[178187729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"1.206555409s","start":"2026-02-25T02:15:29.905044Z","end":"2026-02-25T02:15:31.111599Z","steps":["trace[178187729] 'agreement among raft nodes before linearized reading' (duration: 1.206431666s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.111657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:29.905023Z","time spent":"1.206625781s","remote":"127.0.0.1:52142","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-25T02:15:31.112657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:29.604604Z","time spent":"1.506968971s","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:31.351037Z","caller":"traceutil/trace.go:171","msg":"trace[355998909] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"239.631034ms","start":"2026-02-25T02:15:31.111382Z","end":"2026-02-25T02:15:31.351013Z","steps":["trace[355998909] 'read index received' (duration: 239.416869ms)","trace[355998909] 'applied index is now lower than readState.Index' (duration: 213.305µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:31.351173Z","caller":"traceutil/trace.go:171","msg":"trace[1852989858] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"674.107138ms","start":"2026-02-25T02:15:30.677026Z","end":"2026-02-25T02:15:31.351133Z","steps":["trace[1852989858] 'process raft request' (duration: 673.82402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.351275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.497328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:15:31.351311Z","caller":"traceutil/trace.go:171","msg":"trace[1860937432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"961.580481ms","start":"2026-02-25T02:15:30.389719Z","end":"2026-02-25T02:15:31.3513Z","steps":["trace[1860937432] 'agreement among raft nodes before linearized reading' (duration: 961.419767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.351337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.677006Z","time spent":"674.244281ms","remote":"127.0.0.1:52416","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-25T02:15:31.351358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.389707Z","time spent":"961.639352ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:15:31.351363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.67435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:15:31.351356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.37734ms","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-25T02:15:31.351439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.259668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:31.351479Z","caller":"traceutil/trace.go:171","msg":"trace[124970313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"238.32512ms","start":"2026-02-25T02:15:31.113144Z","end":"2026-02-25T02:15:31.351469Z","steps":["trace[124970313] 'agreement among raft nodes before linearized reading' (duration: 238.276258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.351575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.858295ms","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-25T02:15:31.351617Z","caller":"traceutil/trace.go:171","msg":"trace[630238225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"962.914246ms","start":"2026-02-25T02:15:30.388694Z","end":"2026-02-25T02:15:31.351608Z","steps":["trace[630238225] 'agreement among raft nodes before linearized reading' (duration: 962.742242ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:31.351477Z","caller":"traceutil/trace.go:171","msg":"trace[384804173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"705.524374ms","start":"2026-02-25T02:15:30.645927Z","end":"2026-02-25T02:15:31.351452Z","steps":["trace[384804173] 'agreement among raft nodes before linearized reading' (duration: 705.105893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.351654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.388688Z","time spent":"962.958188ms","remote":"127.0.0.1:52416","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-25T02:15:31.35172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.645881Z","time spent":"705.824812ms","remote":"127.0.0.1:52416","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":"info","ts":"2026-02-25T02:15:31.351413Z","caller":"traceutil/trace.go:171","msg":"trace[1289611399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"962.750803ms","start":"2026-02-25T02:15:30.388647Z","end":"2026-02-25T02:15:31.351397Z","steps":["trace[1289611399] 'agreement among raft nodes before linearized reading' (duration: 962.627639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.351855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.388625Z","time spent":"963.221175ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:15:33.270622Z","caller":"traceutil/trace.go:171","msg":"trace[1563829642] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"117.494355ms","start":"2026-02-25T02:15:33.153104Z","end":"2026-02-25T02:15:33.270598Z","steps":["trace[1563829642] 'process raft request' (duration: 117.34253ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:45.711145Z","caller":"traceutil/trace.go:171","msg":"trace[545979678] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"105.868552ms","start":"2026-02-25T02:15:45.605255Z","end":"2026-02-25T02:15:45.711124Z","steps":["trace[545979678] 'process raft request' (duration: 105.829441ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:45.711216Z","caller":"traceutil/trace.go:171","msg":"trace[1319712159] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"106.463428ms","start":"2026-02-25T02:15:45.604722Z","end":"2026-02-25T02:15:45.711185Z","steps":["trace[1319712159] 'process raft request' (duration: 106.254742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:49.746552Z","caller":"traceutil/trace.go:171","msg":"trace[1725894009] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"176.905185ms","start":"2026-02-25T02:15:49.569627Z","end":"2026-02-25T02:15:49.746532Z","steps":["trace[1725894009] 'read index received' (duration: 176.816263ms)","trace[1725894009] 'applied index is now lower than readState.Index' (duration: 88.202µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:49.746737Z","caller":"traceutil/trace.go:171","msg":"trace[1227642248] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"235.3899ms","start":"2026-02-25T02:15:49.511322Z","end":"2026-02-25T02:15:49.746712Z","steps":["trace[1227642248] 'process raft request' (duration: 235.097072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:49.746839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.205583ms","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-25T02:15:49.74706Z","caller":"traceutil/trace.go:171","msg":"trace[1213000535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"177.46574ms","start":"2026-02-25T02:15:49.569583Z","end":"2026-02-25T02:15:49.747049Z","steps":["trace[1213000535] 'agreement among raft nodes before linearized reading' (duration: 177.056669ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:49.909507Z","caller":"traceutil/trace.go:171","msg":"trace[1342125955] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"158.362864ms","start":"2026-02-25T02:15:49.751117Z","end":"2026-02-25T02:15:49.90948Z","steps":["trace[1342125955] 'process raft request' (duration: 131.529462ms)","trace[1342125955] 'compare' (duration: 26.719079ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:50.025148Z","caller":"traceutil/trace.go:171","msg":"trace[608843307] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"273.920887ms","start":"2026-02-25T02:15:49.751204Z","end":"2026-02-25T02:15:50.025125Z","steps":["trace[608843307] 'process raft request' (duration: 269.328643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:50.025421Z","caller":"traceutil/trace.go:171","msg":"trace[1597019262] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:812; }","duration":"273.809205ms","start":"2026-02-25T02:15:49.75159Z","end":"2026-02-25T02:15:50.025399Z","steps":["trace[1597019262] 'read index received' (duration: 131.07134ms)","trace[1597019262] 'applied index is now lower than readState.Index' (duration: 142.736865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:50.025619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.00879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:15:50.025602Z","caller":"traceutil/trace.go:171","msg":"trace[969061295] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"274.268437ms","start":"2026-02-25T02:15:49.751294Z","end":"2026-02-25T02:15:50.025562Z","steps":["trace[969061295] 'process raft request' (duration: 273.99564ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:50.025674Z","caller":"traceutil/trace.go:171","msg":"trace[647794309] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:785; }","duration":"274.100202ms","start":"2026-02-25T02:15:49.751559Z","end":"2026-02-25T02:15:50.025659Z","steps":["trace[647794309] 'agreement among raft nodes before linearized reading' (duration: 273.997919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:50.025731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.123645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:50.025809Z","caller":"traceutil/trace.go:171","msg":"trace[561099088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"120.30388ms","start":"2026-02-25T02:15:49.905494Z","end":"2026-02-25T02:15:50.025798Z","steps":["trace[561099088] 'agreement among raft nodes before linearized reading' (duration: 120.125526ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:50.025746Z","caller":"traceutil/trace.go:171","msg":"trace[1156527619] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"274.39792ms","start":"2026-02-25T02:15:49.751267Z","end":"2026-02-25T02:15:50.025665Z","steps":["trace[1156527619] 'process raft request' (duration: 273.819044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:51.865348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.771859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:15:51.865849Z","caller":"traceutil/trace.go:171","msg":"trace[1562936903] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"115.098861ms","start":"2026-02-25T02:15:51.750725Z","end":"2026-02-25T02:15:51.865824Z","steps":["trace[1562936903] 'read index received' (duration: 48.602µs)","trace[1562936903] 'applied index is now lower than readState.Index' (duration: 115.047508ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:51.866603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.792749ms","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-25T02:15:51.866679Z","caller":"traceutil/trace.go:171","msg":"trace[355519571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"115.975944ms","start":"2026-02-25T02:15:51.750676Z","end":"2026-02-25T02:15:51.866652Z","steps":["trace[355519571] 'agreement among raft nodes before linearized reading' (duration: 115.223774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:52.405433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:52.906583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:53.406943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:53.904735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999813545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:15:53.904815Z","caller":"traceutil/trace.go:171","msg":"trace[2010529609] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999959879s","start":"2026-02-25T02:15:51.904838Z","end":"2026-02-25T02:15:53.904797Z","steps":["trace[2010529609] 'agreement among raft nodes before linearized reading' (duration: 1.999848046s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:53.904866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:51.904806Z","time spent":"2.000049461s","remote":"127.0.0.1:52158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:15:53.907513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:54.374384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000127423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:15:54.374471Z","caller":"traceutil/trace.go:171","msg":"trace[1800084970] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000270507s","start":"2026-02-25T02:15:52.374181Z","end":"2026-02-25T02:15:54.374451Z","steps":["trace[1800084970] 'agreement among raft nodes before linearized reading' (duration: 2.000161204s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.374514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:52.374158Z","time spent":"2.000345079s","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:15:54.408572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:54.587145Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.715905203s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:15:54.587784Z","caller":"traceutil/trace.go:171","msg":"trace[1256737323] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"2.716602302s","start":"2026-02-25T02:15:51.871145Z","end":"2026-02-25T02:15:54.587747Z","steps":["trace[1256737323] 'process raft request' (duration: 2.716423277s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.588109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:51.871122Z","time spent":"2.716800698s","remote":"127.0.0.1:52416","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-25T02:15:55.253162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.630068ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-02-25T02:15:55.253282Z","caller":"traceutil/trace.go:171","msg":"trace[291391035] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"3.348367169s","start":"2026-02-25T02:15:51.90489Z","end":"2026-02-25T02:15:55.253257Z","steps":["trace[291391035] 'read index received' (duration: 2.682648887s)","trace[291391035] 'applied index is now lower than readState.Index' (duration: 665.715942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:55.254048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:53.908798Z","time spent":"1.345246075s","remote":"127.0.0.1:52252","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:15:55.25456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.889466208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:15:55.254613Z","caller":"traceutil/trace.go:171","msg":"trace[645371613] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:787; }","duration":"2.8895323s","start":"2026-02-25T02:15:52.365069Z","end":"2026-02-25T02:15:55.254601Z","steps":["trace[645371613] 'agreement among raft nodes before linearized reading' (duration: 2.889432607s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.254645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:52.365027Z","time spent":"2.889609982s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:15:55.254672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.340897838s","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-25T02:15:55.254771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.867037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:15:55.255133Z","caller":"traceutil/trace.go:171","msg":"trace[847235400] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:787; }","duration":"228.264308ms","start":"2026-02-25T02:15:55.026859Z","end":"2026-02-25T02:15:55.255124Z","steps":["trace[847235400] 'agreement among raft nodes before linearized reading' (duration: 227.890968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.255149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.225813148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T02:15:55.254662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.225250643s","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-25T02:15:55.255238Z","caller":"traceutil/trace.go:171","msg":"trace[704642427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"3.225828158s","start":"2026-02-25T02:15:52.02937Z","end":"2026-02-25T02:15:55.255199Z","steps":["trace[704642427] 'agreement among raft nodes before linearized reading' (duration: 3.225191281s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:55.255202Z","caller":"traceutil/trace.go:171","msg":"trace[707837131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"3.225900291s","start":"2026-02-25T02:15:52.029287Z","end":"2026-02-25T02:15:55.255187Z","steps":["trace[707837131] 'agreement among raft nodes before linearized reading' (duration: 3.225675085s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:55.254923Z","caller":"traceutil/trace.go:171","msg":"trace[2278648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"3.341052022s","start":"2026-02-25T02:15:51.913712Z","end":"2026-02-25T02:15:55.254764Z","steps":["trace[2278648] 'agreement among raft nodes before linearized reading' (duration: 3.340784585s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.255305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:52.029359Z","time spent":"3.225927861s","remote":"127.0.0.1:52416","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-25T02:15:55.255308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:52.029268Z","time spent":"3.226025183s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:15:55.255372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:51.913693Z","time spent":"3.341643178s","remote":"127.0.0.1:52416","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-25T02:15:55.254943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342216643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:55.255453Z","caller":"traceutil/trace.go:171","msg":"trace[591199732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"1.342738457s","start":"2026-02-25T02:15:53.912707Z","end":"2026-02-25T02:15:55.255446Z","steps":["trace[591199732] 'agreement among raft nodes before linearized reading' (duration: 1.342165421s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.25554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:53.912658Z","time spent":"1.342873421s","remote":"127.0.0.1:52142","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-25T02:15:55.255691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.226080115s","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-25T02:15:55.255758Z","caller":"traceutil/trace.go:171","msg":"trace[1715222253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"3.226170427s","start":"2026-02-25T02:15:52.02956Z","end":"2026-02-25T02:15:55.255731Z","steps":["trace[1715222253] 'agreement among raft nodes before linearized reading' (duration: 3.225997183s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.255819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:52.029538Z","time spent":"3.22626582s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:15:55.829645Z","caller":"traceutil/trace.go:171","msg":"trace[1426882301] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"563.670073ms","start":"2026-02-25T02:15:55.265943Z","end":"2026-02-25T02:15:55.829613Z","steps":["trace[1426882301] 'process raft request' (duration: 563.324544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.829873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.265927Z","time spent":"563.858058ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:55.830444Z","caller":"traceutil/trace.go:171","msg":"trace[125697095] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"564.416233ms","start":"2026-02-25T02:15:55.265995Z","end":"2026-02-25T02:15:55.830411Z","steps":["trace[125697095] 'process raft request' (duration: 563.545119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.830555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.265992Z","time spent":"564.509645ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:55.830573Z","caller":"traceutil/trace.go:171","msg":"trace[316187767] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"564.417773ms","start":"2026-02-25T02:15:55.266136Z","end":"2026-02-25T02:15:55.830554Z","steps":["trace[316187767] 'process raft request' (duration: 564.109834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.830703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.266128Z","time spent":"564.522076ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:55.830997Z","caller":"traceutil/trace.go:171","msg":"trace[1771598233] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"564.453444ms","start":"2026-02-25T02:15:55.266527Z","end":"2026-02-25T02:15:55.830981Z","steps":["trace[1771598233] 'process raft request' (duration: 564.371712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.831086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.266521Z","time spent":"564.515476ms","remote":"127.0.0.1:52416","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-25T02:15:55.874789Z","caller":"traceutil/trace.go:171","msg":"trace[1443728798] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:821; }","duration":"514.671734ms","start":"2026-02-25T02:15:55.360097Z","end":"2026-02-25T02:15:55.874769Z","steps":["trace[1443728798] 'read index received' (duration: 469.266ms)","trace[1443728798] 'applied index is now lower than readState.Index' (duration: 45.404824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:55.875007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.879029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:55.875121Z","caller":"traceutil/trace.go:171","msg":"trace[2083237257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"515.036203ms","start":"2026-02-25T02:15:55.360055Z","end":"2026-02-25T02:15:55.875091Z","steps":["trace[2083237257] 'agreement among raft nodes before linearized reading' (duration: 514.787857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.875172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.360043Z","time spent":"515.116845ms","remote":"127.0.0.1:52142","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-25T02:15:55.875768Z","caller":"traceutil/trace.go:171","msg":"trace[1618487636] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"519.917725ms","start":"2026-02-25T02:15:55.355825Z","end":"2026-02-25T02:15:55.875743Z","steps":["trace[1618487636] 'process raft request' (duration: 518.849516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.875884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.3558Z","time spent":"520.025537ms","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:15:56.104232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.160814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:56.104316Z","caller":"traceutil/trace.go:171","msg":"trace[2092734015] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:793; }","duration":"108.308177ms","start":"2026-02-25T02:15:55.995991Z","end":"2026-02-25T02:15:56.104299Z","steps":["trace[2092734015] 'count revisions from in-memory index tree' (duration: 108.0449ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.305058Z","caller":"traceutil/trace.go:171","msg":"trace[912229629] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"190.868101ms","start":"2026-02-25T02:16:05.114165Z","end":"2026-02-25T02:16:05.305033Z","steps":["trace[912229629] 'process raft request' (duration: 190.720407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.496694Z","caller":"traceutil/trace.go:171","msg":"trace[1190934270] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"185.289511ms","start":"2026-02-25T02:16:05.311382Z","end":"2026-02-25T02:16:05.496671Z","steps":["trace[1190934270] 'process raft request' (duration: 185.169327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.504103Z","caller":"traceutil/trace.go:171","msg":"trace[53010959] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"191.429965ms","start":"2026-02-25T02:16:05.312654Z","end":"2026-02-25T02:16:05.504084Z","steps":["trace[53010959] 'process raft request' (duration: 191.303162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:05.675364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.686924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:05.675483Z","caller":"traceutil/trace.go:171","msg":"trace[882663131] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"167.906623ms","start":"2026-02-25T02:16:05.507551Z","end":"2026-02-25T02:16:05.675458Z","steps":["trace[882663131] 'process raft request' (duration: 48.047704ms)","trace[882663131] 'compare' (duration: 119.53526ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:05.79308Z","caller":"traceutil/trace.go:171","msg":"trace[125544020] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"111.659748ms","start":"2026-02-25T02:16:05.681397Z","end":"2026-02-25T02:16:05.793057Z","steps":["trace[125544020] 'process raft request' (duration: 111.618176ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.79314Z","caller":"traceutil/trace.go:171","msg":"trace[1085643127] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"112.021388ms","start":"2026-02-25T02:16:05.681073Z","end":"2026-02-25T02:16:05.793095Z","steps":["trace[1085643127] 'process raft request' (duration: 110.651291ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.971116Z","caller":"traceutil/trace.go:171","msg":"trace[719053591] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"136.877527ms","start":"2026-02-25T02:16:05.834199Z","end":"2026-02-25T02:16:05.971077Z","steps":["trace[719053591] 'process raft request' (duration: 60.184921ms)","trace[719053591] 'compare' (duration: 76.322146ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:08.143386Z","caller":"traceutil/trace.go:171","msg":"trace[661636527] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"105.545123ms","start":"2026-02-25T02:16:08.037816Z","end":"2026-02-25T02:16:08.143361Z","steps":["trace[661636527] 'process raft request' (duration: 105.505762ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:08.143753Z","caller":"traceutil/trace.go:171","msg":"trace[1511358533] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"107.633239ms","start":"2026-02-25T02:16:08.036095Z","end":"2026-02-25T02:16:08.143728Z","steps":["trace[1511358533] 'process raft request' (duration: 107.186447ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:08.143916Z","caller":"traceutil/trace.go:171","msg":"trace[91233571] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"108.365499ms","start":"2026-02-25T02:16:08.035539Z","end":"2026-02-25T02:16:08.143905Z","steps":["trace[91233571] 'process raft request' (duration: 25.004133ms)","trace[91233571] 'compare' (duration: 82.569754ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:08.9631Z","caller":"traceutil/trace.go:171","msg":"trace[478120530] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"255.81832ms","start":"2026-02-25T02:16:08.707236Z","end":"2026-02-25T02:16:08.963054Z","steps":["trace[478120530] 'process raft request' (duration: 255.699227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:09.946679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.09211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:09.946738Z","caller":"traceutil/trace.go:171","msg":"trace[1563815786] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:880; }","duration":"464.212044ms","start":"2026-02-25T02:16:09.482512Z","end":"2026-02-25T02:16:09.946724Z","steps":["trace[1563815786] 'count revisions from in-memory index tree' (duration: 464.002158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:09.946768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:09.482486Z","time spent":"464.269405ms","remote":"127.0.0.1:52388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:16:09.946968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.951016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:09.947052Z","caller":"traceutil/trace.go:171","msg":"trace[1025987224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"869.072059ms","start":"2026-02-25T02:16:09.077954Z","end":"2026-02-25T02:16:09.947026Z","steps":["trace[1025987224] 'range keys from in-memory index tree' (duration: 868.883314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:09.947093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:09.077933Z","time spent":"869.149401ms","remote":"127.0.0.1:52142","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-25T02:16:10.624838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:11.125559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:11.626777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:11.841974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.801987265s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:16:11.842551Z","caller":"traceutil/trace.go:171","msg":"trace[1849802459] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"1.802622661s","start":"2026-02-25T02:16:10.039885Z","end":"2026-02-25T02:16:11.842508Z","steps":["trace[1849802459] 'process raft request' (duration: 1.802468907s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.842798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.039871Z","time spent":"1.802757816s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:12.903058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999907997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:16:12.9032Z","caller":"traceutil/trace.go:171","msg":"trace[165163427] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000082702s","start":"2026-02-25T02:16:10.903096Z","end":"2026-02-25T02:16:12.903179Z","steps":["trace[165163427] 'agreement among raft nodes before linearized reading' (duration: 1.999904757s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.903366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.903035Z","time spent":"2.000316928s","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:16:12 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:16:13.019326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098313044s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:16:13.019876Z","caller":"traceutil/trace.go:171","msg":"trace[1263342190] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"1.427923302s","start":"2026-02-25T02:16:11.591924Z","end":"2026-02-25T02:16:13.019848Z","steps":["trace[1263342190] 'process raft request' (duration: 1.427493101s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:13.019863Z","caller":"traceutil/trace.go:171","msg":"trace[921503709] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:917; }","duration":"2.895770667s","start":"2026-02-25T02:16:10.124065Z","end":"2026-02-25T02:16:13.019835Z","steps":["trace[921503709] 'read index received' (duration: 1.718101265s)","trace[921503709] 'applied index is now lower than readState.Index' (duration: 1.177667462s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:13.02011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:11.591895Z","time spent":"1.428093987s","remote":"127.0.0.1:52416","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-25T02:16:13.023673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.302531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:16:13.023685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.875422058s","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-25T02:16:13.023718Z","caller":"traceutil/trace.go:171","msg":"trace[396166474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"120.387373ms","start":"2026-02-25T02:16:12.903315Z","end":"2026-02-25T02:16:13.023702Z","steps":["trace[396166474] 'agreement among raft nodes before linearized reading' (duration: 116.859938ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:13.023721Z","caller":"traceutil/trace.go:171","msg":"trace[711309728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"2.87549415s","start":"2026-02-25T02:16:10.148217Z","end":"2026-02-25T02:16:13.023711Z","steps":["trace[711309728] 'agreement among raft nodes before linearized reading' (duration: 2.871990266s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:13.02375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.148194Z","time spent":"2.875546702s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:13.023883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.948743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:16:13.02389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.876228551s","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-25T02:16:13.023912Z","caller":"traceutil/trace.go:171","msg":"trace[2036953769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"2.876286552s","start":"2026-02-25T02:16:10.147619Z","end":"2026-02-25T02:16:13.023906Z","steps":["trace[2036953769] 'agreement among raft nodes before linearized reading' (duration: 2.87250531s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:13.023913Z","caller":"traceutil/trace.go:171","msg":"trace[138528274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"648.019666ms","start":"2026-02-25T02:16:12.375885Z","end":"2026-02-25T02:16:13.023904Z","steps":["trace[138528274] 'agreement among raft nodes before linearized reading' (duration: 644.298805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:13.023929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.147599Z","time spent":"2.876324953s","remote":"127.0.0.1:52416","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-25T02:16:13.023911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.899804825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:13.024007Z","caller":"traceutil/trace.go:171","msg":"trace[1391820966] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:882; }","duration":"2.89996432s","start":"2026-02-25T02:16:10.124023Z","end":"2026-02-25T02:16:13.023987Z","steps":["trace[1391820966] 'agreement among raft nodes before linearized reading' (duration: 2.895895s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:13.024019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056529943s","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-25T02:16:13.024049Z","caller":"traceutil/trace.go:171","msg":"trace[1010237699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"2.056598964s","start":"2026-02-25T02:16:10.967442Z","end":"2026-02-25T02:16:13.024041Z","steps":["trace[1010237699] 'agreement among raft nodes before linearized reading' (duration: 2.052749901s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:13.024055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.124004Z","time spent":"2.900039382s","remote":"127.0.0.1:52278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:16:13.024074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.967419Z","time spent":"2.056648046s","remote":"127.0.0.1:52416","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-25T02:16:13.024199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.875717506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T02:16:13.024221Z","caller":"traceutil/trace.go:171","msg":"trace[1303575320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:882; }","duration":"2.875748858s","start":"2026-02-25T02:16:10.148466Z","end":"2026-02-25T02:16:13.024214Z","steps":["trace[1303575320] 'agreement among raft nodes before linearized reading' (duration: 2.87173319s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:13.02424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.14844Z","time spent":"2.875794389s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:13.023953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.375864Z","time spent":"648.077437ms","remote":"127.0.0.1:52142","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-25T02:16:30.277252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2961288908349084942,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:30.545431Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.399041753s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T02:16:30.712529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.835619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:30.712654Z","caller":"traceutil/trace.go:171","msg":"trace[935551004] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"936.177963ms","start":"2026-02-25T02:16:29.776456Z","end":"2026-02-25T02:16:30.712634Z","steps":["trace[935551004] 'read index received' (duration: 769.132139ms)","trace[935551004] 'applied index is now lower than readState.Index' (duration: 167.043974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:30.712824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.366079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:30.712868Z","caller":"traceutil/trace.go:171","msg":"trace[2146463979] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:932; }","duration":"936.447401ms","start":"2026-02-25T02:16:29.776408Z","end":"2026-02-25T02:16:30.712855Z","steps":["trace[2146463979] 'agreement among raft nodes before linearized reading' (duration: 936.347128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.712905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.776385Z","time spent":"936.509512ms","remote":"127.0.0.1:52378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:16:30.712798Z","caller":"traceutil/trace.go:171","msg":"trace[955303514] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.566475978s","start":"2026-02-25T02:16:29.14629Z","end":"2026-02-25T02:16:30.712766Z","steps":["trace[955303514] 'process raft request' (duration: 1.3993064s)","trace[955303514] 'compare' (duration: 166.383657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:30.713157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.146273Z","time spent":"1.566848407s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:30.713273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.048944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:16:30.713252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.181768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:30.713355Z","caller":"traceutil/trace.go:171","msg":"trace[287699363] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:932; }","duration":"366.150297ms","start":"2026-02-25T02:16:30.347165Z","end":"2026-02-25T02:16:30.713315Z","steps":["trace[287699363] 'agreement among raft nodes before linearized reading' (duration: 366.023404ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:30.713354Z","caller":"traceutil/trace.go:171","msg":"trace[757057951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"809.327731ms","start":"2026-02-25T02:16:29.904004Z","end":"2026-02-25T02:16:30.713332Z","steps":["trace[757057951] 'agreement among raft nodes before linearized reading' (duration: 809.135176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.713406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:30.347144Z","time spent":"366.24823ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:16:30.713449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.903946Z","time spent":"809.490766ms","remote":"127.0.0.1:52142","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-25T02:16:30.713587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.699944ms","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-25T02:16:30.713662Z","caller":"traceutil/trace.go:171","msg":"trace[738201444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"714.829888ms","start":"2026-02-25T02:16:29.998813Z","end":"2026-02-25T02:16:30.713643Z","steps":["trace[738201444] 'agreement among raft nodes before linearized reading' (duration: 714.495309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.713738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.998787Z","time spent":"714.939441ms","remote":"127.0.0.1:52416","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-25T02:16:31.282722Z","caller":"traceutil/trace.go:171","msg":"trace[1228768893] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"377.137165ms","start":"2026-02-25T02:16:30.905561Z","end":"2026-02-25T02:16:31.282698Z","steps":["trace[1228768893] 'read index received' (duration: 376.94022ms)","trace[1228768893] 'applied index is now lower than readState.Index' (duration: 195.885µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:31.282884Z","caller":"traceutil/trace.go:171","msg":"trace[2077046076] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"565.056045ms","start":"2026-02-25T02:16:30.717816Z","end":"2026-02-25T02:16:31.282872Z","steps":["trace[2077046076] 'process raft request' (duration: 564.750147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:31.282993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:30.717795Z","time spent":"565.123246ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:31.283034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.478773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:31.283062Z","caller":"traceutil/trace.go:171","msg":"trace[464403043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"377.502374ms","start":"2026-02-25T02:16:30.905552Z","end":"2026-02-25T02:16:31.283054Z","steps":["trace[464403043] 'agreement among raft nodes before linearized reading' (duration: 377.470883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:31.283086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:30.905502Z","time spent":"377.579067ms","remote":"127.0.0.1:52142","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-25T02:16:31.283002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.351745ms","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-25T02:16:31.283201Z","caller":"traceutil/trace.go:171","msg":"trace[2073251221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"153.581551ms","start":"2026-02-25T02:16:31.129608Z","end":"2026-02-25T02:16:31.283189Z","steps":["trace[2073251221] 'agreement among raft nodes before linearized reading' (duration: 153.312464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:31.283255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.917455ms","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-25T02:16:31.283277Z","caller":"traceutil/trace.go:171","msg":"trace[1965111825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"136.966287ms","start":"2026-02-25T02:16:31.146305Z","end":"2026-02-25T02:16:31.283271Z","steps":["trace[1965111825] 'agreement among raft nodes before linearized reading' (duration: 136.920655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:31.283215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.648658ms","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-25T02:16:31.283347Z","caller":"traceutil/trace.go:171","msg":"trace[741061070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"136.790921ms","start":"2026-02-25T02:16:31.146548Z","end":"2026-02-25T02:16:31.283339Z","steps":["trace[741061070] 'agreement among raft nodes before linearized reading' (duration: 136.627337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:32.201104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.165586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:32.201327Z","caller":"traceutil/trace.go:171","msg":"trace[1865606530] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"913.1245ms","start":"2026-02-25T02:16:31.288171Z","end":"2026-02-25T02:16:32.201295Z","steps":["trace[1865606530] 'process raft request' (duration: 197.682066ms)","trace[1865606530] 'compare' (duration: 714.784456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:32.201463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:31.288151Z","time spent":"913.237292ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:32.201561Z","caller":"traceutil/trace.go:171","msg":"trace[1194888838] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"912.886693ms","start":"2026-02-25T02:16:31.288649Z","end":"2026-02-25T02:16:32.201536Z","steps":["trace[1194888838] 'process raft request' (duration: 912.611646ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:32.201408Z","caller":"traceutil/trace.go:171","msg":"trace[400010680] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"912.849703ms","start":"2026-02-25T02:16:31.288525Z","end":"2026-02-25T02:16:32.201375Z","steps":["trace[400010680] 'process raft request' (duration: 912.678158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:32.201881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:31.288623Z","time spent":"913.209562ms","remote":"127.0.0.1:52416","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-25T02:16:32.20206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:31.288517Z","time spent":"913.372986ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:32.20347Z","caller":"traceutil/trace.go:171","msg":"trace[196103305] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:974; }","duration":"300.328104ms","start":"2026-02-25T02:16:31.903127Z","end":"2026-02-25T02:16:32.203455Z","steps":["trace[196103305] 'read index received' (duration: 294.507382ms)","trace[196103305] 'applied index is now lower than readState.Index' (duration: 5.819622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:32.203569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.433187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:32.203596Z","caller":"traceutil/trace.go:171","msg":"trace[1906480421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"300.492329ms","start":"2026-02-25T02:16:31.903095Z","end":"2026-02-25T02:16:32.203587Z","steps":["trace[1906480421] 'agreement among raft nodes before linearized reading' (duration: 300.441367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:32.203624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:31.903077Z","time spent":"300.54111ms","remote":"127.0.0.1:52158","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-25T02:16:37.115864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.395854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:37.11621Z","caller":"traceutil/trace.go:171","msg":"trace[441594880] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"894.277815ms","start":"2026-02-25T02:16:36.221902Z","end":"2026-02-25T02:16:37.11618Z","steps":["trace[441594880] 'process raft request' (duration: 721.48528ms)","trace[441594880] 'compare' (duration: 172.23529ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:37.11621Z","caller":"traceutil/trace.go:171","msg":"trace[1265395284] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1009; }","duration":"479.856304ms","start":"2026-02-25T02:16:36.636335Z","end":"2026-02-25T02:16:37.116191Z","steps":["trace[1265395284] 'read index received' (duration: 306.853524ms)","trace[1265395284] 'applied index is now lower than readState.Index' (duration: 173.0014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:37.116352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:36.221878Z","time spent":"894.400987ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:37.116358Z","caller":"traceutil/trace.go:171","msg":"trace[1477242805] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"894.370657ms","start":"2026-02-25T02:16:36.221916Z","end":"2026-02-25T02:16:37.116287Z","steps":["trace[1477242805] 'process raft request' (duration: 894.052598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:37.116662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.311843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:16:37.116659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:36.2219Z","time spent":"894.632954ms","remote":"127.0.0.1:52416","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-25T02:16:37.116688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.650643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:37.116711Z","caller":"traceutil/trace.go:171","msg":"trace[1379588616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"211.392665ms","start":"2026-02-25T02:16:36.905303Z","end":"2026-02-25T02:16:37.116695Z","steps":["trace[1379588616] 'agreement among raft nodes before linearized reading' (duration: 211.322393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:37.116769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.974001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:16:37.116842Z","caller":"traceutil/trace.go:171","msg":"trace[2093758833] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:968; }","duration":"292.829408ms","start":"2026-02-25T02:16:36.823994Z","end":"2026-02-25T02:16:37.116823Z","steps":["trace[2093758833] 'agreement among raft nodes before linearized reading' (duration: 292.634803ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:37.116845Z","caller":"traceutil/trace.go:171","msg":"trace[77012151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"382.103505ms","start":"2026-02-25T02:16:36.734725Z","end":"2026-02-25T02:16:37.116828Z","steps":["trace[77012151] 'agreement among raft nodes before linearized reading' (duration: 381.863519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:37.116904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:36.734698Z","time spent":"382.193417ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:37.117054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.684467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:37.11745Z","caller":"traceutil/trace.go:171","msg":"trace[1079625058] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:968; }","duration":"481.078607ms","start":"2026-02-25T02:16:36.63633Z","end":"2026-02-25T02:16:37.117408Z","steps":["trace[1079625058] 'agreement among raft nodes before linearized reading' (duration: 480.04796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:37.117831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:36.636283Z","time spent":"481.528128ms","remote":"127.0.0.1:60970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:16:37.627083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.719346ms","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-25T02:16:37.627178Z","caller":"traceutil/trace.go:171","msg":"trace[1195554488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"323.921412ms","start":"2026-02-25T02:16:37.303234Z","end":"2026-02-25T02:16:37.627155Z","steps":["trace[1195554488] 'range keys from in-memory index tree' (duration: 323.524511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:37.627251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:37.303207Z","time spent":"324.025314ms","remote":"127.0.0.1:52416","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-25T02:16:38.271991Z","caller":"traceutil/trace.go:171","msg":"trace[970940948] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"101.328023ms","start":"2026-02-25T02:16:38.170636Z","end":"2026-02-25T02:16:38.271964Z","steps":["trace[970940948] 'read index received' (duration: 40.694665ms)","trace[970940948] 'applied index is now lower than readState.Index' (duration: 60.630318ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:38.272013Z","caller":"traceutil/trace.go:171","msg":"trace[1747359747] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"102.544775ms","start":"2026-02-25T02:16:38.169423Z","end":"2026-02-25T02:16:38.271968Z","steps":["trace[1747359747] 'process raft request' (duration: 41.930208ms)","trace[1747359747] 'compare' (duration: 60.34337ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:38.272183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.527578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:38.272228Z","caller":"traceutil/trace.go:171","msg":"trace[177198035] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:975; }","duration":"101.622582ms","start":"2026-02-25T02:16:38.17059Z","end":"2026-02-25T02:16:38.272213Z","steps":["trace[177198035] 'agreement among raft nodes before linearized reading' (duration: 101.513709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.976551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.000374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:38.976721Z","caller":"traceutil/trace.go:171","msg":"trace[692580470] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"668.137536ms","start":"2026-02-25T02:16:38.308567Z","end":"2026-02-25T02:16:38.976704Z","steps":["trace[692580470] 'process raft request' (duration: 668.081194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.976819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:38.308547Z","time spent":"668.233658ms","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:38.976866Z","caller":"traceutil/trace.go:171","msg":"trace[1816816123] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"670.350463ms","start":"2026-02-25T02:16:38.306497Z","end":"2026-02-25T02:16:38.976847Z","steps":["trace[1816816123] 'read index received' (duration: 67.95996ms)","trace[1816816123] 'applied index is now lower than readState.Index' (duration: 602.388163ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:38.976867Z","caller":"traceutil/trace.go:171","msg":"trace[1873203652] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"700.658837ms","start":"2026-02-25T02:16:38.276171Z","end":"2026-02-25T02:16:38.97683Z","steps":["trace[1873203652] 'process raft request' (duration: 98.312664ms)","trace[1873203652] 'compare' (duration: 601.883481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:38.977012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:38.276146Z","time spent":"700.792191ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:38.977246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.728283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-25T02:16:38.977354Z","caller":"traceutil/trace.go:171","msg":"trace[201317301] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:978; }","duration":"670.888567ms","start":"2026-02-25T02:16:38.306445Z","end":"2026-02-25T02:16:38.977334Z","steps":["trace[201317301] 'agreement among raft nodes before linearized reading' (duration: 670.513817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.977445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:38.306423Z","time spent":"671.008741ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T02:16:39.53493Z","caller":"traceutil/trace.go:171","msg":"trace[1123885876] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"412.950633ms","start":"2026-02-25T02:16:39.121954Z","end":"2026-02-25T02:16:39.534904Z","steps":["trace[1123885876] 'read index received' (duration: 409.606035ms)","trace[1123885876] 'applied index is now lower than readState.Index' (duration: 3.343178ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:39.535075Z","caller":"traceutil/trace.go:171","msg":"trace[1262584062] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"549.96186ms","start":"2026-02-25T02:16:38.985081Z","end":"2026-02-25T02:16:39.535043Z","steps":["trace[1262584062] 'process raft request' (duration: 546.434638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:39.535253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:38.985062Z","time spent":"550.094164ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:39.53546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.381635ms","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-25T02:16:39.535477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.511648ms","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-25T02:16:39.535556Z","caller":"traceutil/trace.go:171","msg":"trace[12538441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"413.505977ms","start":"2026-02-25T02:16:39.122031Z","end":"2026-02-25T02:16:39.535537Z","steps":["trace[12538441] 'agreement among raft nodes before linearized reading' (duration: 413.274681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:39.535611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.122021Z","time spent":"413.57733ms","remote":"127.0.0.1:52416","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-25T02:16:39.535687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.701528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:16:39.535566Z","caller":"traceutil/trace.go:171","msg":"trace[143080118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"413.661342ms","start":"2026-02-25T02:16:39.121888Z","end":"2026-02-25T02:16:39.535549Z","steps":["trace[143080118] 'agreement among raft nodes before linearized reading' (duration: 413.157388ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:39.535766Z","caller":"traceutil/trace.go:171","msg":"trace[1924322139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"362.830201ms","start":"2026-02-25T02:16:39.172918Z","end":"2026-02-25T02:16:39.535748Z","steps":["trace[1924322139] 'agreement among raft nodes before linearized reading' (duration: 362.623485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:39.535823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.172886Z","time spent":"362.926384ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:39.535881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.12186Z","time spent":"413.899038ms","remote":"127.0.0.1:52416","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-25T02:16:40.217201Z","caller":"traceutil/trace.go:171","msg":"trace[1598767982] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1022; }","duration":"631.290938ms","start":"2026-02-25T02:16:39.585885Z","end":"2026-02-25T02:16:40.217176Z","steps":["trace[1598767982] 'read index received' (duration: 41.984759ms)","trace[1598767982] 'applied index is now lower than readState.Index' (duration: 589.304829ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:40.217326Z","caller":"traceutil/trace.go:171","msg":"trace[1638739363] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"677.259202ms","start":"2026-02-25T02:16:39.540034Z","end":"2026-02-25T02:16:40.217294Z","steps":["trace[1638739363] 'process raft request' (duration: 676.941304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:40.21738Z","caller":"traceutil/trace.go:171","msg":"trace[1934700491] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"676.181284ms","start":"2026-02-25T02:16:39.541152Z","end":"2026-02-25T02:16:40.217334Z","steps":["trace[1934700491] 'process raft request' (duration: 675.958138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:40.217437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.529415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:40.217479Z","caller":"traceutil/trace.go:171","msg":"trace[1188095105] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:982; }","duration":"631.595287ms","start":"2026-02-25T02:16:39.585871Z","end":"2026-02-25T02:16:40.217466Z","steps":["trace[1188095105] 'agreement among raft nodes before linearized reading' (duration: 631.464113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:40.217486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.540001Z","time spent":"677.407816ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:40.217538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.585828Z","time spent":"631.687829ms","remote":"127.0.0.1:52494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:16:40.217583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.541137Z","time spent":"676.329448ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:40.217929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.513124ms","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-25T02:16:40.217981Z","caller":"traceutil/trace.go:171","msg":"trace[1966862352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"534.611307ms","start":"2026-02-25T02:16:39.683353Z","end":"2026-02-25T02:16:40.217964Z","steps":["trace[1966862352] 'agreement among raft nodes before linearized reading' (duration: 534.412832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:40.218018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.683332Z","time spent":"534.677369ms","remote":"127.0.0.1:52416","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-25T02:16:40.218031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.134324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:40.218071Z","caller":"traceutil/trace.go:171","msg":"trace[1974921043] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:982; }","duration":"225.244597ms","start":"2026-02-25T02:16:39.992815Z","end":"2026-02-25T02:16:40.218059Z","steps":["trace[1974921043] 'agreement among raft nodes before linearized reading' (duration: 225.182196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:40.218254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.382514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:40.218293Z","caller":"traceutil/trace.go:171","msg":"trace[297504653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"313.467097ms","start":"2026-02-25T02:16:39.904815Z","end":"2026-02-25T02:16:40.218282Z","steps":["trace[297504653] 'agreement among raft nodes before linearized reading' (duration: 313.411646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:40.218322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:39.904789Z","time spent":"313.527188ms","remote":"127.0.0.1:52142","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-25T02:16:41.813197Z","caller":"traceutil/trace.go:171","msg":"trace[718515879] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"180.856716ms","start":"2026-02-25T02:16:41.632316Z","end":"2026-02-25T02:16:41.813173Z","steps":["trace[718515879] 'read index received' (duration: 166.279304ms)","trace[718515879] 'applied index is now lower than readState.Index' (duration: 14.576062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:41.813399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.068051ms","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-25T02:16:41.813437Z","caller":"traceutil/trace.go:171","msg":"trace[384858555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"181.159624ms","start":"2026-02-25T02:16:41.632268Z","end":"2026-02-25T02:16:41.813427Z","steps":["trace[384858555] 'agreement among raft nodes before linearized reading' (duration: 181.000179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.423091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.762418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:42.423184Z","caller":"traceutil/trace.go:171","msg":"trace[26061392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"517.897002ms","start":"2026-02-25T02:16:41.90527Z","end":"2026-02-25T02:16:42.423167Z","steps":["trace[26061392] 'range keys from in-memory index tree' (duration: 517.640815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.423224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:41.90525Z","time spent":"517.964143ms","remote":"127.0.0.1:52142","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-25T02:16:42.423724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.732311ms","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-25T02:16:42.423768Z","caller":"traceutil/trace.go:171","msg":"trace[926915875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"183.816483ms","start":"2026-02-25T02:16:42.239941Z","end":"2026-02-25T02:16:42.423758Z","steps":["trace[926915875] 'range keys from in-memory index tree' (duration: 183.530156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.423908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.721462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T02:16:42.423978Z","caller":"traceutil/trace.go:171","msg":"trace[1161941206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"201.839216ms","start":"2026-02-25T02:16:42.222121Z","end":"2026-02-25T02:16:42.42396Z","steps":["trace[1161941206] 'range keys from in-memory index tree' (duration: 201.560758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.424142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.026554ms","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-25T02:16:42.424179Z","caller":"traceutil/trace.go:171","msg":"trace[181706709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"201.078945ms","start":"2026-02-25T02:16:42.223089Z","end":"2026-02-25T02:16:42.424168Z","steps":["trace[181706709] 'range keys from in-memory index tree' (duration: 200.939152ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:42.668667Z","caller":"traceutil/trace.go:171","msg":"trace[1532418527] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"239.023988ms","start":"2026-02-25T02:16:42.429583Z","end":"2026-02-25T02:16:42.668607Z","steps":["trace[1532418527] 'process raft request' (duration: 238.955327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:42.668664Z","caller":"traceutil/trace.go:171","msg":"trace[1826934546] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"240.189969ms","start":"2026-02-25T02:16:42.428442Z","end":"2026-02-25T02:16:42.668632Z","steps":["trace[1826934546] 'process raft request' (duration: 239.871911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:44.146241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.729902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:44.146371Z","caller":"traceutil/trace.go:171","msg":"trace[1882064495] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"308.57275ms","start":"2026-02-25T02:16:43.83778Z","end":"2026-02-25T02:16:44.146352Z","steps":["trace[1882064495] 'read index received' (duration: 70.442µs)","trace[1882064495] 'applied index is now lower than readState.Index' (duration: 308.500848ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:44.146447Z","caller":"traceutil/trace.go:171","msg":"trace[1336942930] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"437.71083ms","start":"2026-02-25T02:16:43.708705Z","end":"2026-02-25T02:16:44.146415Z","steps":["trace[1336942930] 'process raft request' (duration: 70.715111ms)","trace[1336942930] 'compare' (duration: 366.512217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:44.146526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.908867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:44.146593Z","caller":"traceutil/trace.go:171","msg":"trace[1487893429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"241.042352ms","start":"2026-02-25T02:16:43.905538Z","end":"2026-02-25T02:16:44.14658Z","steps":["trace[1487893429] 'agreement among raft nodes before linearized reading' (duration: 240.915598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:44.146635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:43.70867Z","time spent":"437.840734ms","remote":"127.0.0.1:52416","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-25T02:16:44.146626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.836656ms","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-25T02:16:44.146699Z","caller":"traceutil/trace.go:171","msg":"trace[309652649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"308.960139ms","start":"2026-02-25T02:16:43.837721Z","end":"2026-02-25T02:16:44.146681Z","steps":["trace[309652649] 'agreement among raft nodes before linearized reading' (duration: 308.715783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:44.146741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:43.837689Z","time spent":"309.041422ms","remote":"127.0.0.1:52416","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-25T02:16:46.624717Z","caller":"traceutil/trace.go:171","msg":"trace[193556602] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"103.718406ms","start":"2026-02-25T02:16:46.52097Z","end":"2026-02-25T02:16:46.624689Z","steps":["trace[193556602] 'process raft request' (duration: 103.570292ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:59.02902Z","caller":"traceutil/trace.go:171","msg":"trace[1408247221] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"285.166117ms","start":"2026-02-25T02:16:58.743827Z","end":"2026-02-25T02:16:59.028993Z","steps":["trace[1408247221] 'read index received' (duration: 285.034773ms)","trace[1408247221] 'applied index is now lower than readState.Index' (duration: 129.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:59.029159Z","caller":"traceutil/trace.go:171","msg":"trace[777864683] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"335.643549ms","start":"2026-02-25T02:16:58.693474Z","end":"2026-02-25T02:16:59.029118Z","steps":["trace[777864683] 'process raft request' (duration: 335.379742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:59.029263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.410953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T02:16:59.02931Z","caller":"traceutil/trace.go:171","msg":"trace[483127522] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1074; }","duration":"285.536256ms","start":"2026-02-25T02:16:58.743758Z","end":"2026-02-25T02:16:59.029294Z","steps":["trace[483127522] 'agreement among raft nodes before linearized reading' (duration: 285.364502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:59.029365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:58.693465Z","time spent":"335.792482ms","remote":"127.0.0.1:52416","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-25T02:16:59.030009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.256403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:16:59.030065Z","caller":"traceutil/trace.go:171","msg":"trace[138572162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1075; }","duration":"263.329095ms","start":"2026-02-25T02:16:58.766723Z","end":"2026-02-25T02:16:59.030052Z","steps":["trace[138572162] 'agreement among raft nodes before linearized reading' (duration: 263.12101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:59.030289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.185157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:59.030334Z","caller":"traceutil/trace.go:171","msg":"trace[26129271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"125.264159ms","start":"2026-02-25T02:16:58.90506Z","end":"2026-02-25T02:16:59.030324Z","steps":["trace[26129271] 'agreement among raft nodes before linearized reading' (duration: 125.201238ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:59.030544Z","caller":"traceutil/trace.go:171","msg":"trace[597385870] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"194.825081ms","start":"2026-02-25T02:16:58.835707Z","end":"2026-02-25T02:16:59.030532Z","steps":["trace[597385870] 'process raft request' (duration: 194.01996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:59.030861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.925008ms","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-25T02:16:59.030975Z","caller":"traceutil/trace.go:171","msg":"trace[1109062701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1075; }","duration":"220.051172ms","start":"2026-02-25T02:16:58.810892Z","end":"2026-02-25T02:16:59.030943Z","steps":["trace[1109062701] 'agreement among raft nodes before linearized reading' (duration: 219.762874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.31534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.968768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:17:03.315586Z","caller":"traceutil/trace.go:171","msg":"trace[410706896] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"220.071513ms","start":"2026-02-25T02:17:03.095484Z","end":"2026-02-25T02:17:03.315556Z","steps":["trace[410706896] 'process raft request' (duration: 106.795647ms)","trace[410706896] 'compare' (duration: 112.865426ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:19:49.492826Z","caller":"traceutil/trace.go:171","msg":"trace[75803131] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"122.595414ms","start":"2026-02-25T02:19:49.370185Z","end":"2026-02-25T02:19:49.492781Z","steps":["trace[75803131] 'process raft request' (duration: 122.44277ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:19:55.238948Z","caller":"traceutil/trace.go:171","msg":"trace[523468572] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"206.287592ms","start":"2026-02-25T02:19:55.032634Z","end":"2026-02-25T02:19:55.238922Z","steps":["trace[523468572] 'read index received' (duration: 206.123368ms)","trace[523468572] 'applied index is now lower than readState.Index' (duration: 163.074µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:19:55.239196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.54685ms","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-25T02:19:55.23924Z","caller":"traceutil/trace.go:171","msg":"trace[1194872406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1826; }","duration":"206.642742ms","start":"2026-02-25T02:19:55.032585Z","end":"2026-02-25T02:19:55.239228Z","steps":["trace[1194872406] 'agreement among raft nodes before linearized reading' (duration: 206.447087ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:19:55.239339Z","caller":"traceutil/trace.go:171","msg":"trace[304794613] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"258.081596ms","start":"2026-02-25T02:19:54.981229Z","end":"2026-02-25T02:19:55.23931Z","steps":["trace[304794613] 'process raft request' (duration: 257.518442ms)"],"step_count":1}