{"level":"warn","ts":"2026-05-15T00:49:20.945188Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T00:49:20.945293Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-05-15T00:49:20.94537Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T00:49:20.945388Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-15T00:49:20.945414Z","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-05-15T00:49:20.946123Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-05-15T00:49:20.946203Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-05-15T00:49:20.956869Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.492099ms"} {"level":"info","ts":"2026-05-15T00:49:20.970104Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-05-15T00:49:20.970203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T00:49:20.970233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-05-15T00:49:20.970255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T00:49:20.970281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-05-15T00:49:20.970341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-05-15T00:49:20.979298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T00:49:20.980793Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T00:49:20.98202Z","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-05-15T00:49:20.98463Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T00:49:20.984935Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T00:49:20.985015Z","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-05-15T00:49:20.98509Z","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-05-15T00:49:20.985101Z","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-05-15T00:49:20.994411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-05-15T00:49:20.995036Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-15T00:49:20.999865Z","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-05-15T00:49:21.00003Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-15T00:49:21.000076Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-15T00:49:21.000341Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T00:49:21.000413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T00:49:21.871037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T00:49:21.871101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T00:49:21.871141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-05-15T00:49:21.871172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-05-15T00:49:21.871184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-15T00:49:21.871202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-05-15T00:49:21.871216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-15T00:49:21.872198Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T00:49:21.872884Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T00:49:21.872902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T00:49:21.872922Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T00:49:21.873228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T00:49:21.873273Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T00:49:21.87461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T00:49:21.874784Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T00:49:21.874841Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T00:49:21.875267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-05-15T00:49:21.876299Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-15T00:49:25.409122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.442883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:49:25.409297Z","caller":"traceutil/trace.go:171","msg":"trace[1276119350] linearizableReadLoop","detail":"{readStateIndex:186; appliedIndex:185; }","duration":"177.707174ms","start":"2026-05-15T00:49:25.23157Z","end":"2026-05-15T00:49:25.409277Z","steps":["trace[1276119350] 'read index received' (duration: 128.374µs)","trace[1276119350] 'applied index is now lower than readState.Index' (duration: 177.57726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:25.409402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.824097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:49:25.409438Z","caller":"traceutil/trace.go:171","msg":"trace[1246129082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:182; }","duration":"177.89973ms","start":"2026-05-15T00:49:25.231528Z","end":"2026-05-15T00:49:25.409428Z","steps":["trace[1246129082] 'agreement among raft nodes before linearized reading' (duration: 177.811227ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:25.409589Z","caller":"traceutil/trace.go:171","msg":"trace[1694327438] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"493.832229ms","start":"2026-05-15T00:49:24.915661Z","end":"2026-05-15T00:49:25.409494Z","steps":["trace[1694327438] 'process raft request' (duration: 131.431219ms)","trace[1694327438] 'compare' (duration: 361.256869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:25.409847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:24.915642Z","time spent":"494.041276ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":655,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T00:49:31.192443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.660977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-15T00:49:31.192561Z","caller":"traceutil/trace.go:171","msg":"trace[356405304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"106.847692ms","start":"2026-05-15T00:49:31.085685Z","end":"2026-05-15T00:49:31.192533Z","steps":["trace[356405304] 'range keys from in-memory index tree' (duration: 106.530504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:31.19285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.779407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-15T00:49:31.192952Z","caller":"traceutil/trace.go:171","msg":"trace[775413440] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:333; }","duration":"603.917181ms","start":"2026-05-15T00:49:30.589011Z","end":"2026-05-15T00:49:31.192928Z","steps":["trace[775413440] 'range keys from in-memory index tree' (duration: 603.651815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:31.192848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.882637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-05-15T00:49:31.192952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.333139ms","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-05-15T00:49:31.193006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:30.588937Z","time spent":"604.056495ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-15T00:49:31.193045Z","caller":"traceutil/trace.go:171","msg":"trace[724760219] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:333; }","duration":"829.167684ms","start":"2026-05-15T00:49:30.363856Z","end":"2026-05-15T00:49:31.193023Z","steps":["trace[724760219] 'range keys from in-memory index tree' (duration: 828.749103ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:31.193074Z","caller":"traceutil/trace.go:171","msg":"trace[228954817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"500.527954ms","start":"2026-05-15T00:49:30.692512Z","end":"2026-05-15T00:49:31.19304Z","steps":["trace[228954817] 'range keys from in-memory index tree' (duration: 500.170904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:31.193097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:30.36383Z","time spent":"829.252397ms","remote":"127.0.0.1:53356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-15T00:49:31.193127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:30.692481Z","time spent":"500.635037ms","remote":"127.0.0.1:53500","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-05-15T00:49:32.194813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954711589637378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T00:49:32.277383Z","caller":"traceutil/trace.go:171","msg":"trace[1769780380] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"1.078993435s","start":"2026-05-15T00:49:31.198328Z","end":"2026-05-15T00:49:32.277321Z","steps":["trace[1769780380] 'process raft request' (duration: 1.028712241s)","trace[1769780380] 'compare' (duration: 50.015117ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:49:32.277432Z","caller":"traceutil/trace.go:171","msg":"trace[385061635] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.079032255s","start":"2026-05-15T00:49:31.198365Z","end":"2026-05-15T00:49:32.277397Z","steps":["trace[385061635] 'process raft request' (duration: 1.078873911s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:32.277551Z","caller":"traceutil/trace.go:171","msg":"trace[1696830706] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"1.075417136s","start":"2026-05-15T00:49:31.202116Z","end":"2026-05-15T00:49:32.277533Z","steps":["trace[1696830706] 'process raft request' (duration: 1.07519594s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:32.277789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:31.198318Z","time spent":"1.07917955s","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T00:49:32.277804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.383726ms","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-05-15T00:49:32.27796Z","caller":"traceutil/trace.go:171","msg":"trace[272377713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:337; }","duration":"583.580762ms","start":"2026-05-15T00:49:31.694329Z","end":"2026-05-15T00:49:32.277909Z","steps":["trace[272377713] 'agreement among raft nodes before linearized reading' (duration: 583.288063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:32.278022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:31.6943Z","time spent":"583.705715ms","remote":"127.0.0.1:53500","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-05-15T00:49:32.278064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:31.198344Z","time spent":"1.079172199s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:32.277997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:31.202091Z","time spent":"1.075529699s","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:49:32.27739Z","caller":"traceutil/trace.go:171","msg":"trace[252611226] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:345; }","duration":"582.983705ms","start":"2026-05-15T00:49:31.694377Z","end":"2026-05-15T00:49:32.277361Z","steps":["trace[252611226] 'read index received' (duration: 532.7322ms)","trace[252611226] 'applied index is now lower than readState.Index' (duration: 50.250375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:32.279062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.825488ms","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-05-15T00:49:32.279135Z","caller":"traceutil/trace.go:171","msg":"trace[1586113432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:337; }","duration":"392.958013ms","start":"2026-05-15T00:49:31.886162Z","end":"2026-05-15T00:49:32.27912Z","steps":["trace[1586113432] 'agreement among raft nodes before linearized reading' (duration: 392.762807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:32.279272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:31.886134Z","time spent":"393.088096ms","remote":"127.0.0.1:53500","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-05-15T00:49:33.523938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033827522s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:49:33.524238Z","caller":"traceutil/trace.go:171","msg":"trace[338932985] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:348; }","duration":"1.239560516s","start":"2026-05-15T00:49:32.284652Z","end":"2026-05-15T00:49:33.524213Z","steps":["trace[338932985] 'read index received' (duration: 205.328974ms)","trace[338932985] 'applied index is now lower than readState.Index' (duration: 1.034227112s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:33.52459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239907367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-15T00:49:33.524706Z","caller":"traceutil/trace.go:171","msg":"trace[1640566488] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:339; }","duration":"1.240033401s","start":"2026-05-15T00:49:32.284641Z","end":"2026-05-15T00:49:33.524674Z","steps":["trace[1640566488] 'agreement among raft nodes before linearized reading' (duration: 1.239734193s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:33.524757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:32.284601Z","time spent":"1.240147362s","remote":"127.0.0.1:53356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-15T00:49:33.524897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.062254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-15T00:49:33.524967Z","caller":"traceutil/trace.go:171","msg":"trace[758597328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:339; }","duration":"243.134865ms","start":"2026-05-15T00:49:33.281814Z","end":"2026-05-15T00:49:33.524949Z","steps":["trace[758597328] 'agreement among raft nodes before linearized reading' (duration: 242.973391ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:33.525048Z","caller":"traceutil/trace.go:171","msg":"trace[2128931917] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"1.242194708s","start":"2026-05-15T00:49:32.282835Z","end":"2026-05-15T00:49:33.52503Z","steps":["trace[2128931917] 'process raft request' (duration: 207.131243ms)","trace[2128931917] 'compare' (duration: 1.032888546s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:33.525183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:32.282811Z","time spent":"1.242272472s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:49:33.525219Z","caller":"traceutil/trace.go:171","msg":"trace[1443462724] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"1.241024428s","start":"2026-05-15T00:49:32.284171Z","end":"2026-05-15T00:49:33.525195Z","steps":["trace[1443462724] 'process raft request' (duration: 1.239918746s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:33.525337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:32.284159Z","time spent":"1.241120899s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:49:39.190879Z","caller":"traceutil/trace.go:171","msg":"trace[1117136413] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"233.192442ms","start":"2026-05-15T00:49:38.957662Z","end":"2026-05-15T00:49:39.190855Z","steps":["trace[1117136413] 'process raft request' (duration: 209.289214ms)","trace[1117136413] 'compare' (duration: 23.682732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:58.977235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.606706Z","time spent":"370.519044ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-15T00:49:58.977975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.715111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:49:58.978337Z","caller":"traceutil/trace.go:171","msg":"trace[2052856431] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"590.660966ms","start":"2026-05-15T00:49:58.387645Z","end":"2026-05-15T00:49:58.978306Z","steps":["trace[2052856431] 'process raft request' (duration: 435.465102ms)","trace[2052856431] 'compare' (duration: 154.334271ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:49:58.978395Z","caller":"traceutil/trace.go:171","msg":"trace[320379736] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"586.926014ms","start":"2026-05-15T00:49:58.39143Z","end":"2026-05-15T00:49:58.978356Z","steps":["trace[320379736] 'process raft request' (duration: 586.716648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.978484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.387638Z","time spent":"590.765389ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T00:49:58.978561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.391412Z","time spent":"587.059037ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:49:58.978584Z","caller":"traceutil/trace.go:171","msg":"trace[900627998] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"587.076219ms","start":"2026-05-15T00:49:58.391483Z","end":"2026-05-15T00:49:58.97856Z","steps":["trace[900627998] 'process raft request' (duration: 586.788891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.978666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.391476Z","time spent":"587.16086ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:58.978802Z","caller":"traceutil/trace.go:171","msg":"trace[877446227] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"587.271153ms","start":"2026-05-15T00:49:58.39152Z","end":"2026-05-15T00:49:58.978791Z","steps":["trace[877446227] 'process raft request' (duration: 586.812651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.978853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.391495Z","time spent":"587.334144ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:58.979082Z","caller":"traceutil/trace.go:171","msg":"trace[186236768] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"579.774906ms","start":"2026-05-15T00:49:58.399279Z","end":"2026-05-15T00:49:58.979054Z","steps":["trace[186236768] 'process raft request' (duration: 579.303313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.979161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.399258Z","time spent":"579.871629ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:58.979098Z","caller":"traceutil/trace.go:171","msg":"trace[1326801010] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"575.750577ms","start":"2026-05-15T00:49:58.403336Z","end":"2026-05-15T00:49:58.979087Z","steps":["trace[1326801010] 'process raft request' (duration: 575.377646ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:58.979334Z","caller":"traceutil/trace.go:171","msg":"trace[24528817] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"572.258239ms","start":"2026-05-15T00:49:58.40701Z","end":"2026-05-15T00:49:58.979268Z","steps":["trace[24528817] 'process raft request' (duration: 571.887729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.979365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.403321Z","time spent":"575.973832ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:58.979518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.406969Z","time spent":"572.461756ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:58.980083Z","caller":"traceutil/trace.go:171","msg":"trace[1404815475] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:550; }","duration":"561.589706ms","start":"2026-05-15T00:49:58.418477Z","end":"2026-05-15T00:49:58.980067Z","steps":["trace[1404815475] 'read index received' (duration: 404.702316ms)","trace[1404815475] 'applied index is now lower than readState.Index' (duration: 156.88628ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:58.980273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.782891ms","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-05-15T00:49:58.980325Z","caller":"traceutil/trace.go:171","msg":"trace[272394447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"561.870044ms","start":"2026-05-15T00:49:58.41843Z","end":"2026-05-15T00:49:58.9803Z","steps":["trace[272394447] 'agreement among raft nodes before linearized reading' (duration: 561.699609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.980352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.418409Z","time spent":"561.937756ms","remote":"127.0.0.1:53500","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-05-15T00:49:58.98049Z","caller":"traceutil/trace.go:171","msg":"trace[1056804540] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"573.249547ms","start":"2026-05-15T00:49:58.407229Z","end":"2026-05-15T00:49:58.980479Z","steps":["trace[1056804540] 'process raft request' (duration: 571.856028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:58.98055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.407196Z","time spent":"573.319629ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:49:59.608463Z","caller":"traceutil/trace.go:171","msg":"trace[1532800872] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.176406677s","start":"2026-05-15T00:49:58.431998Z","end":"2026-05-15T00:49:59.608405Z","steps":["trace[1532800872] 'process raft request' (duration: 1.17578571s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:59.608907Z","caller":"traceutil/trace.go:171","msg":"trace[1107304795] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.165120345s","start":"2026-05-15T00:49:58.443745Z","end":"2026-05-15T00:49:59.608865Z","steps":["trace[1107304795] 'process raft request' (duration: 1.16417776s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:59.608595Z","caller":"traceutil/trace.go:171","msg":"trace[566513186] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.183874303s","start":"2026-05-15T00:49:58.424676Z","end":"2026-05-15T00:49:59.60855Z","steps":["trace[566513186] 'process raft request' (duration: 1.159664617s)","trace[566513186] 'compare' (duration: 23.171548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:49:59.609103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.443713Z","time spent":"1.165285992s","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:59.609159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.424654Z","time spent":"1.184394938s","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:59.608656Z","caller":"traceutil/trace.go:171","msg":"trace[1985332629] transaction","detail":"{read_only:false; number_of_response:1; response_revision:540; }","duration":"1.169186448s","start":"2026-05-15T00:49:58.439451Z","end":"2026-05-15T00:49:59.608637Z","steps":["trace[1985332629] 'process raft request' (duration: 1.168383027s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:59.609454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.439427Z","time spent":"1.169827276s","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:59.609626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.431972Z","time spent":"1.176939082s","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:59.609944Z","caller":"traceutil/trace.go:171","msg":"trace[1104921335] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"964.037929ms","start":"2026-05-15T00:49:58.645888Z","end":"2026-05-15T00:49:59.609926Z","steps":["trace[1104921335] 'process raft request' (duration: 963.969098ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:59.609959Z","caller":"traceutil/trace.go:171","msg":"trace[27321362] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.037621486s","start":"2026-05-15T00:49:58.57231Z","end":"2026-05-15T00:49:59.609932Z","steps":["trace[27321362] 'process raft request' (duration: 1.037439282s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:59.610033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.645848Z","time spent":"964.144872ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:59.610092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.572277Z","time spent":"1.037737898s","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:49:59.611033Z","caller":"traceutil/trace.go:171","msg":"trace[1392947922] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:558; }","duration":"263.440065ms","start":"2026-05-15T00:49:59.347535Z","end":"2026-05-15T00:49:59.610975Z","steps":["trace[1392947922] 'read index received' (duration: 236.655067ms)","trace[1392947922] 'applied index is now lower than readState.Index' (duration: 26.783918ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:49:59.611082Z","caller":"traceutil/trace.go:171","msg":"trace[1884118418] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"618.40303ms","start":"2026-05-15T00:49:58.992653Z","end":"2026-05-15T00:49:59.611056Z","steps":["trace[1884118418] 'process raft request' (duration: 618.057951ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:49:59.610951Z","caller":"traceutil/trace.go:171","msg":"trace[196080745] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"624.647572ms","start":"2026-05-15T00:49:58.986233Z","end":"2026-05-15T00:49:59.610881Z","steps":["trace[196080745] 'process raft request' (duration: 624.368505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:59.611579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.98621Z","time spent":"625.240979ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:59.611214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:58.992641Z","time spent":"618.498894ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:49:59.611239Z","caller":"traceutil/trace.go:171","msg":"trace[2103681891] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"600.008993ms","start":"2026-05-15T00:49:59.01121Z","end":"2026-05-15T00:49:59.611219Z","steps":["trace[2103681891] 'process raft request' (duration: 599.688395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:49:59.612004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:49:59.011173Z","time spent":"600.778765ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:49:59.611321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.787015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:49:59.61221Z","caller":"traceutil/trace.go:171","msg":"trace[2032949235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"264.71169ms","start":"2026-05-15T00:49:59.347483Z","end":"2026-05-15T00:49:59.612195Z","steps":["trace[2032949235] 'agreement among raft nodes before linearized reading' (duration: 263.60336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.152605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.872575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:50:06.152883Z","caller":"traceutil/trace.go:171","msg":"trace[1162665469] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"565.571765ms","start":"2026-05-15T00:50:05.587228Z","end":"2026-05-15T00:50:06.1528Z","steps":["trace[1162665469] 'process raft request' (duration: 388.89482ms)","trace[1162665469] 'compare' (duration: 171.199835ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:06.153029Z","caller":"traceutil/trace.go:171","msg":"trace[1434309434] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"375.002937ms","start":"2026-05-15T00:50:05.777998Z","end":"2026-05-15T00:50:06.153001Z","steps":["trace[1434309434] 'process raft request' (duration: 374.773551ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:06.153013Z","caller":"traceutil/trace.go:171","msg":"trace[1998504860] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"492.301538ms","start":"2026-05-15T00:50:05.66062Z","end":"2026-05-15T00:50:06.152921Z","steps":["trace[1998504860] 'read index received' (duration: 116.251401ms)","trace[1998504860] 'applied index is now lower than readState.Index' (duration: 376.043747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:50:06.153133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.587194Z","time spent":"565.809052ms","remote":"127.0.0.1:53342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:06.153181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.777977Z","time spent":"375.12798ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:06.153447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.418801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T00:50:06.154725Z","caller":"traceutil/trace.go:171","msg":"trace[171242533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"371.768788ms","start":"2026-05-15T00:50:05.78293Z","end":"2026-05-15T00:50:06.154699Z","steps":["trace[171242533] 'agreement among raft nodes before linearized reading' (duration: 370.317298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.154793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.782906Z","time spent":"371.874532ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T00:50:06.153652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.388425ms","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-05-15T00:50:06.155011Z","caller":"traceutil/trace.go:171","msg":"trace[302745183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"414.750012ms","start":"2026-05-15T00:50:05.740205Z","end":"2026-05-15T00:50:06.154955Z","steps":["trace[302745183] 'agreement among raft nodes before linearized reading' (duration: 413.339133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.155092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.740181Z","time spent":"414.867034ms","remote":"127.0.0.1:53500","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-05-15T00:50:06.153751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.510487ms","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-05-15T00:50:06.15543Z","caller":"traceutil/trace.go:171","msg":"trace[1352973058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"407.4409ms","start":"2026-05-15T00:50:05.747981Z","end":"2026-05-15T00:50:06.155422Z","steps":["trace[1352973058] 'agreement among raft nodes before linearized reading' (duration: 405.24796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.155476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.747948Z","time spent":"407.498673ms","remote":"127.0.0.1:53500","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-05-15T00:50:06.15403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.388306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2st5v\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-15T00:50:06.155643Z","caller":"traceutil/trace.go:171","msg":"trace[587659586] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2st5v; range_end:; response_count:1; response_revision:628; }","duration":"455.02485ms","start":"2026-05-15T00:50:05.70061Z","end":"2026-05-15T00:50:06.155634Z","steps":["trace[587659586] 'agreement among raft nodes before linearized reading' (duration: 452.606074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.155689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.700599Z","time spent":"455.082043ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-2st5v\" "} {"level":"warn","ts":"2026-05-15T00:50:06.154421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.802339ms","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-05-15T00:50:06.15595Z","caller":"traceutil/trace.go:171","msg":"trace[1258437510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"495.368343ms","start":"2026-05-15T00:50:05.660568Z","end":"2026-05-15T00:50:06.155936Z","steps":["trace[1258437510] 'agreement among raft nodes before linearized reading' (duration: 492.593706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.156002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:05.660552Z","time spent":"495.433305ms","remote":"127.0.0.1:53500","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-05-15T00:50:06.505676Z","caller":"traceutil/trace.go:171","msg":"trace[1587424440] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"344.507128ms","start":"2026-05-15T00:50:06.161125Z","end":"2026-05-15T00:50:06.505632Z","steps":["trace[1587424440] 'process raft request' (duration: 344.423546ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:06.505701Z","caller":"traceutil/trace.go:171","msg":"trace[718754838] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"346.411111ms","start":"2026-05-15T00:50:06.159256Z","end":"2026-05-15T00:50:06.505667Z","steps":["trace[718754838] 'process raft request' (duration: 346.084792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.5059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.159236Z","time spent":"346.548934ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:06.505929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.161117Z","time spent":"344.648402ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:06.506183Z","caller":"traceutil/trace.go:171","msg":"trace[1613683811] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"342.684088ms","start":"2026-05-15T00:50:06.163482Z","end":"2026-05-15T00:50:06.506166Z","steps":["trace[1613683811] 'process raft request' (duration: 342.112332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.506276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.163475Z","time spent":"342.75429ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:06.506488Z","caller":"traceutil/trace.go:171","msg":"trace[808951524] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"343.135599ms","start":"2026-05-15T00:50:06.163336Z","end":"2026-05-15T00:50:06.506471Z","steps":["trace[808951524] 'read index received' (duration: 27.193728ms)","trace[808951524] 'applied index is now lower than readState.Index' (duration: 315.940791ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:06.506629Z","caller":"traceutil/trace.go:171","msg":"trace[1004238640] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"337.945516ms","start":"2026-05-15T00:50:06.168591Z","end":"2026-05-15T00:50:06.506536Z","steps":["trace[1004238640] 'process raft request' (duration: 337.71947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.506756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.408477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-15T00:50:06.506839Z","caller":"traceutil/trace.go:171","msg":"trace[640858690] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:633; }","duration":"343.50878ms","start":"2026-05-15T00:50:06.163316Z","end":"2026-05-15T00:50:06.506825Z","steps":["trace[640858690] 'agreement among raft nodes before linearized reading' (duration: 343.338266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.50691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.16331Z","time spent":"343.588452ms","remote":"127.0.0.1:53510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-15T00:50:06.507789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.168583Z","time spent":"338.169532ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:06.949911Z","caller":"traceutil/trace.go:171","msg":"trace[192795792] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"443.308228ms","start":"2026-05-15T00:50:06.506572Z","end":"2026-05-15T00:50:06.94988Z","steps":["trace[192795792] 'read index received' (duration: 442.966699ms)","trace[192795792] 'applied index is now lower than readState.Index' (duration: 340.179µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:06.950082Z","caller":"traceutil/trace.go:171","msg":"trace[120506258] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"741.10173ms","start":"2026-05-15T00:50:06.208933Z","end":"2026-05-15T00:50:06.950035Z","steps":["trace[120506258] 'process raft request' (duration: 740.771761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.950128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.428118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:50:06.950209Z","caller":"traceutil/trace.go:171","msg":"trace[876836417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"603.572022ms","start":"2026-05-15T00:50:06.346623Z","end":"2026-05-15T00:50:06.950195Z","steps":["trace[876836417] 'agreement among raft nodes before linearized reading' (duration: 603.400118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:06.950326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.208911Z","time spent":"741.301804ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T00:50:06.950341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:06.346597Z","time spent":"603.728886ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T00:50:07.847378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954711589638009,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T00:50:08.290367Z","caller":"traceutil/trace.go:171","msg":"trace[957221323] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"943.814518ms","start":"2026-05-15T00:50:07.346525Z","end":"2026-05-15T00:50:08.290339Z","steps":["trace[957221323] 'read index received' (duration: 942.290346ms)","trace[957221323] 'applied index is now lower than readState.Index' (duration: 1.523062ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:08.290605Z","caller":"traceutil/trace.go:171","msg":"trace[2066356302] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"944.669832ms","start":"2026-05-15T00:50:07.345924Z","end":"2026-05-15T00:50:08.290594Z","steps":["trace[2066356302] 'process raft request' (duration: 942.596134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:08.29075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:07.345897Z","time spent":"944.769144ms","remote":"127.0.0.1:53342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:08.29113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.59696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:50:08.291226Z","caller":"traceutil/trace.go:171","msg":"trace[629886017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"944.711083ms","start":"2026-05-15T00:50:07.346494Z","end":"2026-05-15T00:50:08.291205Z","steps":["trace[629886017] 'agreement among raft nodes before linearized reading' (duration: 944.59568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:08.291593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:07.346483Z","time spent":"945.090074ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T00:50:08.836757Z","caller":"traceutil/trace.go:171","msg":"trace[1710117195] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"112.952757ms","start":"2026-05-15T00:50:08.723772Z","end":"2026-05-15T00:50:08.836725Z","steps":["trace[1710117195] 'process raft request' (duration: 112.782952ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:12.671437Z","caller":"traceutil/trace.go:171","msg":"trace[1810512710] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"136.908642ms","start":"2026-05-15T00:50:12.534499Z","end":"2026-05-15T00:50:12.671407Z","steps":["trace[1810512710] 'process raft request' (duration: 136.758917ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:12.687175Z","caller":"traceutil/trace.go:171","msg":"trace[1537985716] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"150.809248ms","start":"2026-05-15T00:50:12.536336Z","end":"2026-05-15T00:50:12.687145Z","steps":["trace[1537985716] 'process raft request' (duration: 150.590002ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:12.687383Z","caller":"traceutil/trace.go:171","msg":"trace[327860102] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"150.975252ms","start":"2026-05-15T00:50:12.536385Z","end":"2026-05-15T00:50:12.68736Z","steps":["trace[327860102] 'process raft request' (duration: 150.663394ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:16.718533Z","caller":"traceutil/trace.go:171","msg":"trace[1787611422] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"119.554489ms","start":"2026-05-15T00:50:16.59896Z","end":"2026-05-15T00:50:16.718515Z","steps":["trace[1787611422] 'process raft request' (duration: 119.403766ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:18.929592Z","caller":"traceutil/trace.go:171","msg":"trace[988274128] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"123.103088ms","start":"2026-05-15T00:50:18.806457Z","end":"2026-05-15T00:50:18.92956Z","steps":["trace[988274128] 'process raft request' (duration: 122.049918ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:18.929887Z","caller":"traceutil/trace.go:171","msg":"trace[2133082745] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"122.320116ms","start":"2026-05-15T00:50:18.807548Z","end":"2026-05-15T00:50:18.929869Z","steps":["trace[2133082745] 'process raft request' (duration: 121.272917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:24.605827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.622225ms","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-05-15T00:50:24.605919Z","caller":"traceutil/trace.go:171","msg":"trace[761000390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"198.753178ms","start":"2026-05-15T00:50:24.407147Z","end":"2026-05-15T00:50:24.6059Z","steps":["trace[761000390] 'range keys from in-memory index tree' (duration: 198.43892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:24.605975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.756826ms","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-05-15T00:50:24.606013Z","caller":"traceutil/trace.go:171","msg":"trace[747918097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"284.791338ms","start":"2026-05-15T00:50:24.32121Z","end":"2026-05-15T00:50:24.606001Z","steps":["trace[747918097] 'range keys from in-memory index tree' (duration: 284.620082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:24.605827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.902181ms","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-05-15T00:50:24.606101Z","caller":"traceutil/trace.go:171","msg":"trace[1847191562] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:795; }","duration":"142.26834ms","start":"2026-05-15T00:50:24.463824Z","end":"2026-05-15T00:50:24.606093Z","steps":["trace[1847191562] 'count revisions from in-memory index tree' (duration: 141.765937ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:25.119745Z","caller":"traceutil/trace.go:171","msg":"trace[520984992] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"509.404395ms","start":"2026-05-15T00:50:24.610314Z","end":"2026-05-15T00:50:25.119718Z","steps":["trace[520984992] 'process raft request' (duration: 509.169039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:25.120032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:24.610295Z","time spent":"509.538419ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:25.129302Z","caller":"traceutil/trace.go:171","msg":"trace[1149757605] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:822; }","duration":"227.547529ms","start":"2026-05-15T00:50:24.901733Z","end":"2026-05-15T00:50:25.12928Z","steps":["trace[1149757605] 'read index received' (duration: 217.838349ms)","trace[1149757605] 'applied index is now lower than readState.Index' (duration: 9.70776ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:25.129521Z","caller":"traceutil/trace.go:171","msg":"trace[759846367] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"388.041736ms","start":"2026-05-15T00:50:24.741408Z","end":"2026-05-15T00:50:25.12945Z","steps":["trace[759846367] 'process raft request' (duration: 387.800519ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:25.129314Z","caller":"traceutil/trace.go:171","msg":"trace[928001881] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"518.386334ms","start":"2026-05-15T00:50:24.610908Z","end":"2026-05-15T00:50:25.129294Z","steps":["trace[928001881] 'process raft request' (duration: 518.166358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:25.129645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.892318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T00:50:25.129703Z","caller":"traceutil/trace.go:171","msg":"trace[1177948442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"227.978461ms","start":"2026-05-15T00:50:24.901714Z","end":"2026-05-15T00:50:25.129693Z","steps":["trace[1177948442] 'agreement among raft nodes before linearized reading' (duration: 227.767995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:25.129648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:24.610899Z","time spent":"518.684743ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:25.129411Z","caller":"traceutil/trace.go:171","msg":"trace[646253927] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"305.819793ms","start":"2026-05-15T00:50:24.823561Z","end":"2026-05-15T00:50:25.129381Z","steps":["trace[646253927] 'process raft request' (duration: 305.682239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:25.129814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.065981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-15T00:50:25.129874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:24.823537Z","time spent":"306.311296ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:25.129848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.833787ms","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-05-15T00:50:25.129893Z","caller":"traceutil/trace.go:171","msg":"trace[1625839552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"175.204485ms","start":"2026-05-15T00:50:24.954668Z","end":"2026-05-15T00:50:25.129872Z","steps":["trace[1625839552] 'agreement among raft nodes before linearized reading' (duration: 174.918347ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:25.129926Z","caller":"traceutil/trace.go:171","msg":"trace[107069960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"173.982672ms","start":"2026-05-15T00:50:24.955928Z","end":"2026-05-15T00:50:25.129911Z","steps":["trace[107069960] 'agreement among raft nodes before linearized reading' (duration: 173.700033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:25.130486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:24.741379Z","time spent":"388.1813ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:39.530238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954711589638590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T00:50:40.030583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954711589638590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T00:50:40.530958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954711589638590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T00:50:40.838556Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.836771024s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T00:50:40.839194Z","caller":"traceutil/trace.go:171","msg":"trace[226600039] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.837519475s","start":"2026-05-15T00:50:39.001647Z","end":"2026-05-15T00:50:40.839167Z","steps":["trace[226600039] 'process raft request' (duration: 1.837291838s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:40.839463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.001617Z","time spent":"1.837671588s","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:41.529056Z","caller":"traceutil/trace.go:171","msg":"trace[1631972983] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.82906764s","start":"2026-05-15T00:50:39.69995Z","end":"2026-05-15T00:50:41.529017Z","steps":["trace[1631972983] 'process raft request' (duration: 1.828041361s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.529347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.699914Z","time spent":"1.829232105s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:41.529388Z","caller":"traceutil/trace.go:171","msg":"trace[116904764] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.241248218s","start":"2026-05-15T00:50:40.288111Z","end":"2026-05-15T00:50:41.529359Z","steps":["trace[116904764] 'process raft request' (duration: 1.240966709s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.529578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.288083Z","time spent":"1.241443153s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:41.719261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.989246ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-15T00:50:41.719582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.690323386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T00:50:41.719686Z","caller":"traceutil/trace.go:171","msg":"trace[176247956] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:935; }","duration":"2.690491951s","start":"2026-05-15T00:50:39.02917Z","end":"2026-05-15T00:50:41.719662Z","steps":["trace[176247956] 'agreement among raft nodes before linearized reading' (duration: 2.690286365s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.719738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.029144Z","time spent":"2.690582854s","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:50:41.71965Z","caller":"traceutil/trace.go:171","msg":"trace[655498148] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:963; }","duration":"2.69014193s","start":"2026-05-15T00:50:39.029232Z","end":"2026-05-15T00:50:41.719374Z","steps":["trace[655498148] 'read index received' (duration: 1.809556479s)","trace[655498148] 'applied index is now lower than readState.Index' (duration: 880.583101ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:41.862034Z","caller":"traceutil/trace.go:171","msg":"trace[66441479] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"1.015838379s","start":"2026-05-15T00:50:40.846159Z","end":"2026-05-15T00:50:41.861997Z","steps":["trace[66441479] 'process raft request' (duration: 1.01551742s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:41.862061Z","caller":"traceutil/trace.go:171","msg":"trace[1195281755] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"1.015868469s","start":"2026-05-15T00:50:40.846167Z","end":"2026-05-15T00:50:41.862035Z","steps":["trace[1195281755] 'process raft request' (duration: 1.015713685s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:41.862201Z","caller":"traceutil/trace.go:171","msg":"trace[1133803370] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:967; }","duration":"141.807668ms","start":"2026-05-15T00:50:41.720352Z","end":"2026-05-15T00:50:41.86216Z","steps":["trace[1133803370] 'read index received' (duration: 141.306013ms)","trace[1133803370] 'applied index is now lower than readState.Index' (duration: 498.815µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:50:41.862286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.846145Z","time spent":"1.016032993s","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:41.862401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.846133Z","time spent":"1.015989973s","remote":"127.0.0.1:53510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:41.862398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.326028ms","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":"info","ts":"2026-05-15T00:50:41.86249Z","caller":"traceutil/trace.go:171","msg":"trace[354927947] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:938; }","duration":"395.533213ms","start":"2026-05-15T00:50:41.466936Z","end":"2026-05-15T00:50:41.862469Z","steps":["trace[354927947] 'agreement among raft nodes before linearized reading' (duration: 395.356848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.862512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.63573636s","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":"warn","ts":"2026-05-15T00:50:41.862534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.466895Z","time spent":"395.627946ms","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:50:41.862569Z","caller":"traceutil/trace.go:171","msg":"trace[27533513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"2.635862883s","start":"2026-05-15T00:50:39.226692Z","end":"2026-05-15T00:50:41.862554Z","steps":["trace[27533513] 'agreement among raft nodes before linearized reading' (duration: 2.635604845s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.862617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.226667Z","time spent":"2.635937336s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T00:50:41.862707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.592735326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-15T00:50:41.862751Z","caller":"traceutil/trace.go:171","msg":"trace[1419149116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:938; }","duration":"2.592799008s","start":"2026-05-15T00:50:39.269939Z","end":"2026-05-15T00:50:41.862738Z","steps":["trace[1419149116] 'agreement among raft nodes before linearized reading' (duration: 2.592637513s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:41.86273Z","caller":"traceutil/trace.go:171","msg":"trace[196514159] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.013267837s","start":"2026-05-15T00:50:40.84942Z","end":"2026-05-15T00:50:41.862688Z","steps":["trace[196514159] 'process raft request' (duration: 1.012537167s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.86282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.673691766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:50:41.862848Z","caller":"traceutil/trace.go:171","msg":"trace[130645667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"1.673716966s","start":"2026-05-15T00:50:40.189123Z","end":"2026-05-15T00:50:41.86284Z","steps":["trace[130645667] 'agreement among raft nodes before linearized reading' (duration: 1.673680005s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.862871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.188927Z","time spent":"1.673939314s","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T00:50:41.862873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.595122673s","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-05-15T00:50:41.862907Z","caller":"traceutil/trace.go:171","msg":"trace[1815882687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"2.595185474s","start":"2026-05-15T00:50:39.267711Z","end":"2026-05-15T00:50:41.862897Z","steps":["trace[1815882687] 'agreement among raft nodes before linearized reading' (duration: 2.595084591s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.862937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.267697Z","time spent":"2.595232956s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-15T00:50:41.862931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.849414Z","time spent":"1.013405911s","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:41.862942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.594465474s","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-05-15T00:50:41.863017Z","caller":"traceutil/trace.go:171","msg":"trace[1639628131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"2.594540527s","start":"2026-05-15T00:50:39.268443Z","end":"2026-05-15T00:50:41.862983Z","steps":["trace[1639628131] 'agreement among raft nodes before linearized reading' (duration: 2.594373952s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.26843Z","time spent":"2.594610398s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-15T00:50:41.86306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.623445689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-15T00:50:41.862784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.269929Z","time spent":"2.592847339s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-15T00:50:41.863101Z","caller":"traceutil/trace.go:171","msg":"trace[824553890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"2.623509311s","start":"2026-05-15T00:50:39.239581Z","end":"2026-05-15T00:50:41.86309Z","steps":["trace[824553890] 'agreement among raft nodes before linearized reading' (duration: 2.623363806s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381746067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-15T00:50:41.86313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.23957Z","time spent":"2.623553721s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T00:50:41.863139Z","caller":"traceutil/trace.go:171","msg":"trace[1760059577] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:938; }","duration":"2.381806889s","start":"2026-05-15T00:50:39.481324Z","end":"2026-05-15T00:50:41.863131Z","steps":["trace[1760059577] 'agreement among raft nodes before linearized reading' (duration: 2.381762238s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.481302Z","time spent":"2.381863059s","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-15T00:50:41.863179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.821233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-15T00:50:41.863065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.132103674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T00:50:41.86321Z","caller":"traceutil/trace.go:171","msg":"trace[688493629] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:938; }","duration":"905.919836ms","start":"2026-05-15T00:50:40.957281Z","end":"2026-05-15T00:50:41.8632Z","steps":["trace[688493629] 'agreement among raft nodes before linearized reading' (duration: 905.838863ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:41.863215Z","caller":"traceutil/trace.go:171","msg":"trace[525894225] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:938; }","duration":"2.132315241s","start":"2026-05-15T00:50:39.730891Z","end":"2026-05-15T00:50:41.863206Z","steps":["trace[525894225] 'agreement among raft nodes before linearized reading' (duration: 2.132142146s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515824162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T00:50:41.863235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.957244Z","time spent":"905.983687ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-15T00:50:41.863248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:39.730858Z","time spent":"2.132378352s","remote":"127.0.0.1:53592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:50:41.863257Z","caller":"traceutil/trace.go:171","msg":"trace[1790517469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"1.515913925s","start":"2026-05-15T00:50:40.34733Z","end":"2026-05-15T00:50:41.863244Z","steps":["trace[1790517469] 'agreement among raft nodes before linearized reading' (duration: 1.515832682s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.347302Z","time spent":"1.515986356s","remote":"127.0.0.1:53200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T00:50:41.863384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.751444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:50:41.863413Z","caller":"traceutil/trace.go:171","msg":"trace[930591024] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:938; }","duration":"711.834397ms","start":"2026-05-15T00:50:41.151569Z","end":"2026-05-15T00:50:41.863404Z","steps":["trace[930591024] 'agreement among raft nodes before linearized reading' (duration: 711.789145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.151538Z","time spent":"711.892258ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-15T00:50:41.863866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595364431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:50:41.863937Z","caller":"traceutil/trace.go:171","msg":"trace[1691498657] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:938; }","duration":"1.595625128s","start":"2026-05-15T00:50:40.268296Z","end":"2026-05-15T00:50:41.863922Z","steps":["trace[1691498657] 'agreement among raft nodes before linearized reading' (duration: 1.593977743s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:41.863977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:40.268263Z","time spent":"1.59570324s","remote":"127.0.0.1:53614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:50:42.16276Z","caller":"traceutil/trace.go:171","msg":"trace[184471870] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"295.036193ms","start":"2026-05-15T00:50:41.867674Z","end":"2026-05-15T00:50:42.16271Z","steps":["trace[184471870] 'process raft request' (duration: 201.559578ms)","trace[184471870] 'compare' (duration: 93.27454ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:42.30906Z","caller":"traceutil/trace.go:171","msg":"trace[1894419628] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:970; }","duration":"437.373006ms","start":"2026-05-15T00:50:41.871672Z","end":"2026-05-15T00:50:42.309045Z","steps":["trace[1894419628] 'read index received' (duration: 197.586557ms)","trace[1894419628] 'applied index is now lower than readState.Index' (duration: 239.785539ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:42.309129Z","caller":"traceutil/trace.go:171","msg":"trace[432781590] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"439.942886ms","start":"2026-05-15T00:50:41.869175Z","end":"2026-05-15T00:50:42.309118Z","steps":["trace[432781590] 'process raft request' (duration: 439.71524ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:42.309139Z","caller":"traceutil/trace.go:171","msg":"trace[289608238] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"439.73321ms","start":"2026-05-15T00:50:41.869362Z","end":"2026-05-15T00:50:42.309096Z","steps":["trace[289608238] 'process raft request' (duration: 439.596817ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:42.309309Z","caller":"traceutil/trace.go:171","msg":"trace[1566076842] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"439.907775ms","start":"2026-05-15T00:50:41.869375Z","end":"2026-05-15T00:50:42.309283Z","steps":["trace[1566076842] 'process raft request' (duration: 439.551966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:42.309293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.869161Z","time spent":"440.013648ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:42.309369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.869337Z","time spent":"439.918466ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:42.309401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.869367Z","time spent":"439.987878ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:42.309495Z","caller":"traceutil/trace.go:171","msg":"trace[1180597245] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"438.227298ms","start":"2026-05-15T00:50:41.871256Z","end":"2026-05-15T00:50:42.309483Z","steps":["trace[1180597245] 'process raft request' (duration: 437.740074ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:42.309039Z","caller":"traceutil/trace.go:171","msg":"trace[1622510162] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"440.486321ms","start":"2026-05-15T00:50:41.86851Z","end":"2026-05-15T00:50:42.308996Z","steps":["trace[1622510162] 'process raft request' (duration: 427.627965ms)","trace[1622510162] 'compare' (duration: 12.6114ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:50:42.309561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.871227Z","time spent":"438.294961ms","remote":"127.0.0.1:53684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:50:42.309595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.920721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T00:50:42.309609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.868501Z","time spent":"441.063858ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:42.309703Z","caller":"traceutil/trace.go:171","msg":"trace[170478265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"437.993592ms","start":"2026-05-15T00:50:41.871637Z","end":"2026-05-15T00:50:42.309631Z","steps":["trace[170478265] 'agreement among raft nodes before linearized reading' (duration: 437.893419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:42.309749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.87162Z","time spent":"438.116816ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T00:50:42.309721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.882018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-zr6jt\" ","response":"range_response_count:1 size:2273"} {"level":"info","ts":"2026-05-15T00:50:42.30982Z","caller":"traceutil/trace.go:171","msg":"trace[1202043244] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-zr6jt; range_end:; response_count:1; response_revision:944; }","duration":"358.027672ms","start":"2026-05-15T00:50:41.951767Z","end":"2026-05-15T00:50:42.309794Z","steps":["trace[1202043244] 'agreement among raft nodes before linearized reading' (duration: 357.778674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:42.309842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.688754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-zr6jt\" ","response":"range_response_count:1 size:4883"} {"level":"warn","ts":"2026-05-15T00:50:42.309869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:41.951748Z","time spent":"358.110774ms","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":2296,"request content":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-zr6jt\" "} {"level":"info","ts":"2026-05-15T00:50:42.309886Z","caller":"traceutil/trace.go:171","msg":"trace[1632633756] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-zr6jt; range_end:; response_count:1; response_revision:944; }","duration":"298.774606ms","start":"2026-05-15T00:50:42.011099Z","end":"2026-05-15T00:50:42.309873Z","steps":["trace[1632633756] 'agreement among raft nodes before linearized reading' (duration: 298.604092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:42.768884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.105132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-15T00:50:42.769059Z","caller":"traceutil/trace.go:171","msg":"trace[1019233494] transaction","detail":"{read_only:false; number_of_response:1; response_revision:945; }","duration":"455.792628ms","start":"2026-05-15T00:50:42.313209Z","end":"2026-05-15T00:50:42.769002Z","steps":["trace[1019233494] 'process raft request' (duration: 141.338196ms)","trace[1019233494] 'compare' (duration: 313.517815ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:50:42.769168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:42.313184Z","time spent":"455.92296ms","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":76,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-05-15T00:50:42.800767Z","caller":"traceutil/trace.go:171","msg":"trace[143438376] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"482.416395ms","start":"2026-05-15T00:50:42.318322Z","end":"2026-05-15T00:50:42.800738Z","steps":["trace[143438376] 'process raft request' (duration: 482.253881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:50:42.800986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:50:42.318304Z","time spent":"482.548289ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5056,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:50:43.137074Z","caller":"traceutil/trace.go:171","msg":"trace[1804369012] transaction","detail":"{read_only:false; number_of_response:1; response_revision:949; }","duration":"144.130372ms","start":"2026-05-15T00:50:42.992913Z","end":"2026-05-15T00:50:43.137044Z","steps":["trace[1804369012] 'process raft request' (duration: 60.076438ms)","trace[1804369012] 'compare' (duration: 83.782737ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:48.503869Z","caller":"traceutil/trace.go:171","msg":"trace[524688927] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"139.540934ms","start":"2026-05-15T00:50:48.364302Z","end":"2026-05-15T00:50:48.503843Z","steps":["trace[524688927] 'process raft request' (duration: 139.498243ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:48.503907Z","caller":"traceutil/trace.go:171","msg":"trace[148547530] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"139.812022ms","start":"2026-05-15T00:50:48.364071Z","end":"2026-05-15T00:50:48.503883Z","steps":["trace[148547530] 'process raft request' (duration: 122.08054ms)","trace[148547530] 'compare' (duration: 17.455534ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:48.503954Z","caller":"traceutil/trace.go:171","msg":"trace[83476468] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"139.842413ms","start":"2026-05-15T00:50:48.364074Z","end":"2026-05-15T00:50:48.503916Z","steps":["trace[83476468] 'process raft request' (duration: 139.681879ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:52.702532Z","caller":"traceutil/trace.go:171","msg":"trace[1401939371] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"133.189198ms","start":"2026-05-15T00:50:52.56932Z","end":"2026-05-15T00:50:52.702509Z","steps":["trace[1401939371] 'process raft request' (duration: 133.159517ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:50:52.702572Z","caller":"traceutil/trace.go:171","msg":"trace[630586638] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"133.179808ms","start":"2026-05-15T00:50:52.569365Z","end":"2026-05-15T00:50:52.702545Z","steps":["trace[630586638] 'process raft request' (duration: 64.642945ms)","trace[630586638] 'compare' (duration: 68.309746ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:50:52.702719Z","caller":"traceutil/trace.go:171","msg":"trace[205349038] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"133.356203ms","start":"2026-05-15T00:50:52.569357Z","end":"2026-05-15T00:50:52.702713Z","steps":["trace[205349038] 'process raft request' (duration: 133.089616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:03.346454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000094589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-15T00:51:03.346975Z","caller":"traceutil/trace.go:171","msg":"trace[68328213] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000690575s","start":"2026-05-15T00:51:01.346264Z","end":"2026-05-15T00:51:03.346954Z","steps":["trace[68328213] 'agreement among raft nodes before linearized reading' (duration: 2.00014071s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:03.347062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:01.346224Z","time spent":"2.00082344s","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/15 00:51:03 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-15T00:51:03.818524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.080678446s","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-05-15T00:51:03.818597Z","caller":"traceutil/trace.go:171","msg":"trace[112752553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"3.080761807s","start":"2026-05-15T00:51:00.737819Z","end":"2026-05-15T00:51:03.818581Z","steps":["trace[112752553] 'range keys from in-memory index tree' (duration: 3.080558512s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:03.818631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:00.737787Z","time spent":"3.08083602s","remote":"127.0.0.1:53500","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-05-15T00:51:03.818848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.220117617s","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-05-15T00:51:03.818938Z","caller":"traceutil/trace.go:171","msg":"trace[77773193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1008; }","duration":"3.22026128s","start":"2026-05-15T00:51:00.598654Z","end":"2026-05-15T00:51:03.818915Z","steps":["trace[77773193] 'range keys from in-memory index tree' (duration: 3.219957502s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:03.819004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:00.598636Z","time spent":"3.220343013s","remote":"127.0.0.1:53500","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-05-15T00:51:03.819008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.074479553s","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-05-15T00:51:03.819072Z","caller":"traceutil/trace.go:171","msg":"trace[139102319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"3.074603287s","start":"2026-05-15T00:51:00.744453Z","end":"2026-05-15T00:51:03.819057Z","steps":["trace[139102319] 'range keys from in-memory index tree' (duration: 3.074323689s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:03.819102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:00.74444Z","time spent":"3.074654119s","remote":"127.0.0.1:53500","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-05-15T00:51:03.818893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.081769106s","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-05-15T00:51:03.819206Z","caller":"traceutil/trace.go:171","msg":"trace[1602562362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"3.082101665s","start":"2026-05-15T00:51:00.73707Z","end":"2026-05-15T00:51:03.819171Z","steps":["trace[1602562362] 'range keys from in-memory index tree' (duration: 3.081657213s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:03.819343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:00.737044Z","time spent":"3.082233148s","remote":"127.0.0.1:53500","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-05-15T00:51:04.314769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.623648744s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-15T00:51:04.540278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.563114322s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-15T00:51:04.540708Z","caller":"traceutil/trace.go:171","msg":"trace[1571378687] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"3.547392556s","start":"2026-05-15T00:51:00.993301Z","end":"2026-05-15T00:51:04.540694Z","steps":["trace[1571378687] 'read index received' (duration: 71.612µs)","trace[1571378687] 'applied index is now lower than readState.Index' (duration: 3.547320364s)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:51:04.540901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.547543329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:51:04.540972Z","caller":"traceutil/trace.go:171","msg":"trace[803893259] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1008; }","duration":"3.547724154s","start":"2026-05-15T00:51:00.993239Z","end":"2026-05-15T00:51:04.540963Z","steps":["trace[803893259] 'agreement among raft nodes before linearized reading' (duration: 3.5475605s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:04.541009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:00.993212Z","time spent":"3.547785537s","remote":"127.0.0.1:53608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:51:04.541112Z","caller":"traceutil/trace.go:171","msg":"trace[1272424007] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"2.850092282s","start":"2026-05-15T00:51:01.690983Z","end":"2026-05-15T00:51:04.541075Z","steps":["trace[1272424007] 'process raft request' (duration: 2.849395503s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:04.5413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:01.69095Z","time spent":"2.850248306s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:51:04.541134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.252509ms","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-05-15T00:51:04.541884Z","caller":"traceutil/trace.go:171","msg":"trace[357213201] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1008; }","duration":"493.032771ms","start":"2026-05-15T00:51:04.048834Z","end":"2026-05-15T00:51:04.541867Z","steps":["trace[357213201] 'range keys from in-memory index tree' (duration: 492.108566ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:05.003663Z","caller":"traceutil/trace.go:171","msg":"trace[1587103197] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.177015173s","start":"2026-05-15T00:51:03.826613Z","end":"2026-05-15T00:51:05.003629Z","steps":["trace[1587103197] 'process raft request' (duration: 1.176949651s)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:05.003679Z","caller":"traceutil/trace.go:171","msg":"trace[416036435] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"3.001504806s","start":"2026-05-15T00:51:02.002121Z","end":"2026-05-15T00:51:05.003626Z","steps":["trace[416036435] 'process raft request' (duration: 3.001322141s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.003815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:03.826591Z","time spent":"1.177142366s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:51:05.003872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:02.00209Z","time spent":"3.001674112s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:05.004096Z","caller":"traceutil/trace.go:171","msg":"trace[1553600117] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.177429455s","start":"2026-05-15T00:51:03.826655Z","end":"2026-05-15T00:51:05.004085Z","steps":["trace[1553600117] 'process raft request' (duration: 1.176937822s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.004168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:03.826628Z","time spent":"1.177506708s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:05.004727Z","caller":"traceutil/trace.go:171","msg":"trace[135463060] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.177672452s","start":"2026-05-15T00:51:03.827041Z","end":"2026-05-15T00:51:05.004714Z","steps":["trace[135463060] 'process raft request' (duration: 1.176837278s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.004837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:03.827033Z","time spent":"1.177760434s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:51:05.004954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.009258ms","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-05-15T00:51:05.004989Z","caller":"traceutil/trace.go:171","msg":"trace[1386044104] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1014; }","duration":"516.118452ms","start":"2026-05-15T00:51:04.488861Z","end":"2026-05-15T00:51:05.004979Z","steps":["trace[1386044104] 'agreement among raft nodes before linearized reading' (duration: 516.05028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.00502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:04.488818Z","time spent":"516.194784ms","remote":"127.0.0.1:53342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:51:05.005157Z","caller":"traceutil/trace.go:171","msg":"trace[291280801] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.176709905s","start":"2026-05-15T00:51:03.828438Z","end":"2026-05-15T00:51:05.005148Z","steps":["trace[291280801] 'process raft request' (duration: 1.17620264s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.005212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:03.828428Z","time spent":"1.176755076s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:51:05.005359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.355918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T00:51:05.005385Z","caller":"traceutil/trace.go:171","msg":"trace[1568594051] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1014; }","duration":"516.41916ms","start":"2026-05-15T00:51:04.488957Z","end":"2026-05-15T00:51:05.005376Z","steps":["trace[1568594051] 'agreement among raft nodes before linearized reading' (duration: 516.376879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.005409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:04.488926Z","time spent":"516.476282ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-15T00:51:05.005649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590132586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:51:05.004754Z","caller":"traceutil/trace.go:171","msg":"trace[971656565] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1046; }","duration":"463.979644ms","start":"2026-05-15T00:51:04.540736Z","end":"2026-05-15T00:51:05.004716Z","steps":["trace[971656565] 'read index received' (duration: 462.641547ms)","trace[971656565] 'applied index is now lower than readState.Index' (duration: 1.336697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:51:05.005733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658619947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T00:51:05.005817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.632741857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T00:51:05.005822Z","caller":"traceutil/trace.go:171","msg":"trace[2076366317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"1.658752211s","start":"2026-05-15T00:51:03.347046Z","end":"2026-05-15T00:51:05.005798Z","steps":["trace[2076366317] 'agreement among raft nodes before linearized reading' (duration: 1.658638057s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.00594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:03.347017Z","time spent":"1.658871703s","remote":"127.0.0.1:53200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T00:51:05.005843Z","caller":"traceutil/trace.go:171","msg":"trace[2047801254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"2.632791938s","start":"2026-05-15T00:51:02.373044Z","end":"2026-05-15T00:51:05.005836Z","steps":["trace[2047801254] 'agreement among raft nodes before linearized reading' (duration: 2.632712636s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.006367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:02.37302Z","time spent":"2.633327033s","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-15T00:51:05.005736Z","caller":"traceutil/trace.go:171","msg":"trace[1713980662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"1.590260638s","start":"2026-05-15T00:51:03.415455Z","end":"2026-05-15T00:51:05.005715Z","steps":["trace[1713980662] 'agreement among raft nodes before linearized reading' (duration: 1.590126745s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:05.006664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:03.415434Z","time spent":"1.591204505s","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T00:51:06.255843Z","caller":"traceutil/trace.go:171","msg":"trace[281016353] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"145.355095ms","start":"2026-05-15T00:51:06.110465Z","end":"2026-05-15T00:51:06.25582Z","steps":["trace[281016353] 'process raft request' (duration: 145.223572ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:07.291864Z","caller":"traceutil/trace.go:171","msg":"trace[712377001] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1057; }","duration":"272.072305ms","start":"2026-05-15T00:51:07.019762Z","end":"2026-05-15T00:51:07.291834Z","steps":["trace[712377001] 'read index received' (duration: 22.586428ms)","trace[712377001] 'applied index is now lower than readState.Index' (duration: 249.485017ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:51:07.291974Z","caller":"traceutil/trace.go:171","msg":"trace[295677054] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"279.120831ms","start":"2026-05-15T00:51:07.012799Z","end":"2026-05-15T00:51:07.29192Z","steps":["trace[295677054] 'process raft request' (duration: 278.602756ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:07.291958Z","caller":"traceutil/trace.go:171","msg":"trace[2135653450] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"278.417881ms","start":"2026-05-15T00:51:07.013513Z","end":"2026-05-15T00:51:07.291931Z","steps":["trace[2135653450] 'process raft request' (duration: 278.235616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:07.292099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.304321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T00:51:07.292136Z","caller":"traceutil/trace.go:171","msg":"trace[24922835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"272.385144ms","start":"2026-05-15T00:51:07.019736Z","end":"2026-05-15T00:51:07.292121Z","steps":["trace[24922835] 'agreement among raft nodes before linearized reading' (duration: 272.216458ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:07.292174Z","caller":"traceutil/trace.go:171","msg":"trace[1282829520] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"278.480303ms","start":"2026-05-15T00:51:07.013676Z","end":"2026-05-15T00:51:07.292156Z","steps":["trace[1282829520] 'process raft request' (duration: 278.115653ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:15.515609Z","caller":"traceutil/trace.go:171","msg":"trace[2135141807] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"104.217715ms","start":"2026-05-15T00:51:15.411371Z","end":"2026-05-15T00:51:15.515588Z","steps":["trace[2135141807] 'process raft request' (duration: 94.371849ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:15.51561Z","caller":"traceutil/trace.go:171","msg":"trace[1569941265] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"103.132954ms","start":"2026-05-15T00:51:15.412448Z","end":"2026-05-15T00:51:15.515581Z","steps":["trace[1569941265] 'process raft request' (duration: 103.059652ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:16.812202Z","caller":"traceutil/trace.go:171","msg":"trace[1805320380] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"208.205901ms","start":"2026-05-15T00:51:16.603966Z","end":"2026-05-15T00:51:16.812172Z","steps":["trace[1805320380] 'process raft request' (duration: 207.848091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:17.613343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.482438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:51:17.613498Z","caller":"traceutil/trace.go:171","msg":"trace[550373589] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"164.848874ms","start":"2026-05-15T00:51:17.448607Z","end":"2026-05-15T00:51:17.613455Z","steps":["trace[550373589] 'process raft request' (duration: 26.18641ms)","trace[550373589] 'compare' (duration: 138.396116ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:51:19.777599Z","caller":"traceutil/trace.go:171","msg":"trace[1092189373] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"103.554086ms","start":"2026-05-15T00:51:19.674028Z","end":"2026-05-15T00:51:19.777583Z","steps":["trace[1092189373] 'process raft request' (duration: 103.489544ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:25.79919Z","caller":"traceutil/trace.go:171","msg":"trace[1962895984] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"101.621802ms","start":"2026-05-15T00:51:25.697542Z","end":"2026-05-15T00:51:25.799163Z","steps":["trace[1962895984] 'process raft request' (duration: 101.381696ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:29.535815Z","caller":"traceutil/trace.go:171","msg":"trace[2102853280] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"323.354759ms","start":"2026-05-15T00:51:29.212437Z","end":"2026-05-15T00:51:29.535792Z","steps":["trace[2102853280] 'read index received' (duration: 323.170554ms)","trace[2102853280] 'applied index is now lower than readState.Index' (duration: 183.065µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:51:29.536113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.604136ms","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-05-15T00:51:29.536395Z","caller":"traceutil/trace.go:171","msg":"trace[758560301] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"615.823969ms","start":"2026-05-15T00:51:28.92001Z","end":"2026-05-15T00:51:29.535834Z","steps":["trace[758560301] 'process raft request' (duration: 615.614343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:29.536575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:28.91998Z","time spent":"616.484167ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:29.536631Z","caller":"traceutil/trace.go:171","msg":"trace[323265465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"323.830213ms","start":"2026-05-15T00:51:29.212383Z","end":"2026-05-15T00:51:29.536213Z","steps":["trace[323265465] 'agreement among raft nodes before linearized reading' (duration: 323.480113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:29.536722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:29.21236Z","time spent":"324.317826ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T00:51:30.033325Z","caller":"traceutil/trace.go:171","msg":"trace[870490879] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"174.502212ms","start":"2026-05-15T00:51:29.85879Z","end":"2026-05-15T00:51:30.033292Z","steps":["trace[870490879] 'process raft request' (duration: 83.142477ms)","trace[870490879] 'compare' (duration: 91.083707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:51:31.428851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.703296ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-15T00:51:31.653324Z","caller":"traceutil/trace.go:171","msg":"trace[1638238629] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"109.452259ms","start":"2026-05-15T00:51:31.543832Z","end":"2026-05-15T00:51:31.653285Z","steps":["trace[1638238629] 'process raft request' (duration: 109.191872ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:31.986114Z","caller":"traceutil/trace.go:171","msg":"trace[1313003689] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"132.483901ms","start":"2026-05-15T00:51:31.853589Z","end":"2026-05-15T00:51:31.986073Z","steps":["trace[1313003689] 'process raft request' (duration: 119.357116ms)","trace[1313003689] 'compare' (duration: 12.94664ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:51:33.989287Z","caller":"traceutil/trace.go:171","msg":"trace[108164648] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"328.487492ms","start":"2026-05-15T00:51:33.660768Z","end":"2026-05-15T00:51:33.989256Z","steps":["trace[108164648] 'process raft request' (duration: 328.300547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:33.989679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:33.660736Z","time spent":"328.650168ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:33.989755Z","caller":"traceutil/trace.go:171","msg":"trace[1563492148] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1146; }","duration":"227.864629ms","start":"2026-05-15T00:51:33.761807Z","end":"2026-05-15T00:51:33.989671Z","steps":["trace[1563492148] 'read index received' (duration: 227.854139ms)","trace[1563492148] 'applied index is now lower than readState.Index' (duration: 8.37µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:51:33.99106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.243208ms","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-05-15T00:51:33.991127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.285218ms","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-05-15T00:51:33.991222Z","caller":"traceutil/trace.go:171","msg":"trace[347725219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"229.420732ms","start":"2026-05-15T00:51:33.761779Z","end":"2026-05-15T00:51:33.991199Z","steps":["trace[347725219] 'agreement among raft nodes before linearized reading' (duration: 229.095983ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:33.991629Z","caller":"traceutil/trace.go:171","msg":"trace[1684636960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"229.319929ms","start":"2026-05-15T00:51:33.761796Z","end":"2026-05-15T00:51:33.991116Z","steps":["trace[1684636960] 'agreement among raft nodes before linearized reading' (duration: 229.046012ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:51:34.531982Z","caller":"traceutil/trace.go:171","msg":"trace[1114123122] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"536.515869ms","start":"2026-05-15T00:51:33.995438Z","end":"2026-05-15T00:51:34.531954Z","steps":["trace[1114123122] 'process raft request' (duration: 536.330073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:34.532151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:33.99541Z","time spent":"536.655712ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:34.559133Z","caller":"traceutil/trace.go:171","msg":"trace[1432548527] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"563.12551ms","start":"2026-05-15T00:51:33.995984Z","end":"2026-05-15T00:51:34.559109Z","steps":["trace[1432548527] 'process raft request' (duration: 562.933626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:34.559304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:33.995959Z","time spent":"563.252173ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:34.559439Z","caller":"traceutil/trace.go:171","msg":"trace[1915186632] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"563.424817ms","start":"2026-05-15T00:51:33.996004Z","end":"2026-05-15T00:51:34.559429Z","steps":["trace[1915186632] 'process raft request' (duration: 563.070448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:34.559483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:33.995981Z","time spent":"563.480971ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:51:34.559522Z","caller":"traceutil/trace.go:171","msg":"trace[1282780745] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1146; }","duration":"503.586541ms","start":"2026-05-15T00:51:34.055837Z","end":"2026-05-15T00:51:34.559424Z","steps":["trace[1282780745] 'read index received' (duration: 475.717074ms)","trace[1282780745] 'applied index is now lower than readState.Index' (duration: 27.867187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:51:34.559885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.972322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-15T00:51:34.559936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.020874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:51:34.559994Z","caller":"traceutil/trace.go:171","msg":"trace[748820353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"214.138286ms","start":"2026-05-15T00:51:34.345841Z","end":"2026-05-15T00:51:34.559979Z","steps":["trace[748820353] 'agreement among raft nodes before linearized reading' (duration: 214.034883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:34.560011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.035175ms","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-05-15T00:51:34.560096Z","caller":"traceutil/trace.go:171","msg":"trace[932821111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"405.175498ms","start":"2026-05-15T00:51:34.154909Z","end":"2026-05-15T00:51:34.560084Z","steps":["trace[932821111] 'agreement among raft nodes before linearized reading' (duration: 404.923532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:34.560167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:34.154846Z","time spent":"405.308453ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-15T00:51:34.560657Z","caller":"traceutil/trace.go:171","msg":"trace[1783626144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"504.188967ms","start":"2026-05-15T00:51:34.055783Z","end":"2026-05-15T00:51:34.559972Z","steps":["trace[1783626144] 'agreement among raft nodes before linearized reading' (duration: 503.766205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:34.56079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:51:34.05576Z","time spent":"504.990851ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-15T00:51:48.709269Z","caller":"traceutil/trace.go:171","msg":"trace[1181459042] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"112.812833ms","start":"2026-05-15T00:51:48.596439Z","end":"2026-05-15T00:51:48.709252Z","steps":["trace[1181459042] 'process raft request' (duration: 112.648609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:51:51.100611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.250593ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-15T00:52:04.632933Z","caller":"traceutil/trace.go:171","msg":"trace[325330515] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"286.416339ms","start":"2026-05-15T00:52:04.346492Z","end":"2026-05-15T00:52:04.632908Z","steps":["trace[325330515] 'read index received' (duration: 286.260675ms)","trace[325330515] 'applied index is now lower than readState.Index' (duration: 154.194µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:04.633066Z","caller":"traceutil/trace.go:171","msg":"trace[2024231963] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"490.252559ms","start":"2026-05-15T00:52:04.142771Z","end":"2026-05-15T00:52:04.633023Z","steps":["trace[2024231963] 'process raft request' (duration: 489.977612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:04.633173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.630836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:04.633233Z","caller":"traceutil/trace.go:171","msg":"trace[1743284498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"286.77863ms","start":"2026-05-15T00:52:04.346439Z","end":"2026-05-15T00:52:04.633218Z","steps":["trace[1743284498] 'agreement among raft nodes before linearized reading' (duration: 286.573574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:04.633277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:04.142743Z","time spent":"490.443165ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:52:05.041558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.558798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:52:05.041707Z","caller":"traceutil/trace.go:171","msg":"trace[1833449049] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"407.558125ms","start":"2026-05-15T00:52:04.634125Z","end":"2026-05-15T00:52:05.041683Z","steps":["trace[1833449049] 'read index received' (duration: 201.839323ms)","trace[1833449049] 'applied index is now lower than readState.Index' (duration: 205.715442ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:05.041818Z","caller":"traceutil/trace.go:171","msg":"trace[3474781] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"429.359853ms","start":"2026-05-15T00:52:04.61243Z","end":"2026-05-15T00:52:05.041789Z","steps":["trace[3474781] 'process raft request' (duration: 223.478996ms)","trace[3474781] 'compare' (duration: 205.217608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:52:05.041902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.76191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T00:52:05.041939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:04.612409Z","time spent":"429.465976ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:52:05.04195Z","caller":"traceutil/trace.go:171","msg":"trace[543930949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"407.838213ms","start":"2026-05-15T00:52:04.634097Z","end":"2026-05-15T00:52:05.041935Z","steps":["trace[543930949] 'agreement among raft nodes before linearized reading' (duration: 407.71405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:05.042085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:04.634084Z","time spent":"407.914456ms","remote":"127.0.0.1:53200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T00:52:05.042287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.860849ms","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-05-15T00:52:05.042271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.663458ms","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-05-15T00:52:05.042319Z","caller":"traceutil/trace.go:171","msg":"trace[1604542120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"171.943861ms","start":"2026-05-15T00:52:04.870368Z","end":"2026-05-15T00:52:05.042311Z","steps":["trace[1604542120] 'agreement among raft nodes before linearized reading' (duration: 171.814728ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:05.042341Z","caller":"traceutil/trace.go:171","msg":"trace[862188744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"131.770542ms","start":"2026-05-15T00:52:04.91055Z","end":"2026-05-15T00:52:05.04232Z","steps":["trace[862188744] 'agreement among raft nodes before linearized reading' (duration: 131.592626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:05.042439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.669758ms","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-05-15T00:52:05.042456Z","caller":"traceutil/trace.go:171","msg":"trace[1109794389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"230.741839ms","start":"2026-05-15T00:52:04.811709Z","end":"2026-05-15T00:52:05.04245Z","steps":["trace[1109794389] 'agreement among raft nodes before linearized reading' (duration: 230.477302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:05.042541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.28741ms","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-05-15T00:52:05.042653Z","caller":"traceutil/trace.go:171","msg":"trace[1146204334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1263; }","duration":"130.437844ms","start":"2026-05-15T00:52:04.912189Z","end":"2026-05-15T00:52:05.042627Z","steps":["trace[1146204334] 'agreement among raft nodes before linearized reading' (duration: 130.005492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:05.042974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.470432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T00:52:05.043061Z","caller":"traceutil/trace.go:171","msg":"trace[1086225921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1263; }","duration":"244.592115ms","start":"2026-05-15T00:52:04.798453Z","end":"2026-05-15T00:52:05.043045Z","steps":["trace[1086225921] 'agreement among raft nodes before linearized reading' (duration: 243.687469ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:05.294009Z","caller":"traceutil/trace.go:171","msg":"trace[1499493349] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"246.719504ms","start":"2026-05-15T00:52:05.047266Z","end":"2026-05-15T00:52:05.293986Z","steps":["trace[1499493349] 'process raft request' (duration: 246.664832ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:05.294038Z","caller":"traceutil/trace.go:171","msg":"trace[1341016895] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"246.761365ms","start":"2026-05-15T00:52:05.047242Z","end":"2026-05-15T00:52:05.294004Z","steps":["trace[1341016895] 'process raft request' (duration: 246.56695ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:05.294185Z","caller":"traceutil/trace.go:171","msg":"trace[115641808] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"246.854108ms","start":"2026-05-15T00:52:05.047324Z","end":"2026-05-15T00:52:05.294178Z","steps":["trace[115641808] 'process raft request' (duration: 246.636902ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:05.294325Z","caller":"traceutil/trace.go:171","msg":"trace[262902801] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"246.328633ms","start":"2026-05-15T00:52:05.047988Z","end":"2026-05-15T00:52:05.294317Z","steps":["trace[262902801] 'process raft request' (duration: 246.160458ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:06.396121Z","caller":"traceutil/trace.go:171","msg":"trace[737212159] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"263.82641ms","start":"2026-05-15T00:52:06.132259Z","end":"2026-05-15T00:52:06.396085Z","steps":["trace[737212159] 'process raft request' (duration: 263.595194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:07.023576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.989289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:52:07.023689Z","caller":"traceutil/trace.go:171","msg":"trace[524831383] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"281.38058ms","start":"2026-05-15T00:52:06.742292Z","end":"2026-05-15T00:52:07.023672Z","steps":["trace[524831383] 'read index received' (duration: 117.068182ms)","trace[524831383] 'applied index is now lower than readState.Index' (duration: 164.311308ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:07.024039Z","caller":"traceutil/trace.go:171","msg":"trace[2114231111] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"384.593815ms","start":"2026-05-15T00:52:06.639403Z","end":"2026-05-15T00:52:07.023997Z","steps":["trace[2114231111] 'process raft request' (duration: 220.070211ms)","trace[2114231111] 'compare' (duration: 163.441834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:52:07.024207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.526883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"warn","ts":"2026-05-15T00:52:07.024218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:06.639361Z","time spent":"384.75387ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:52:07.024245Z","caller":"traceutil/trace.go:171","msg":"trace[626450630] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1272; }","duration":"281.987876ms","start":"2026-05-15T00:52:06.742245Z","end":"2026-05-15T00:52:07.024233Z","steps":["trace[626450630] 'agreement among raft nodes before linearized reading' (duration: 281.471502ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:07.111367Z","caller":"traceutil/trace.go:171","msg":"trace[1447726754] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"189.448688ms","start":"2026-05-15T00:52:06.921892Z","end":"2026-05-15T00:52:07.111341Z","steps":["trace[1447726754] 'process raft request' (duration: 189.317265ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:07.347934Z","caller":"traceutil/trace.go:171","msg":"trace[1758953443] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"315.762556ms","start":"2026-05-15T00:52:07.032151Z","end":"2026-05-15T00:52:07.347914Z","steps":["trace[1758953443] 'process raft request' (duration: 315.645662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:07.34819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.032128Z","time spent":"315.976602ms","remote":"127.0.0.1:53408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:52:07.598795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.891229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:52:07.599009Z","caller":"traceutil/trace.go:171","msg":"trace[1149398210] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1325; }","duration":"481.37027ms","start":"2026-05-15T00:52:07.117596Z","end":"2026-05-15T00:52:07.598966Z","steps":["trace[1149398210] 'read index received' (duration: 230.298294ms)","trace[1149398210] 'applied index is now lower than readState.Index' (duration: 251.070116ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:07.599135Z","caller":"traceutil/trace.go:171","msg":"trace[1560564219] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"481.70464ms","start":"2026-05-15T00:52:07.117417Z","end":"2026-05-15T00:52:07.599121Z","steps":["trace[1560564219] 'process raft request' (duration: 309.404319ms)","trace[1560564219] 'compare' (duration: 171.746676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:52:07.599221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.117393Z","time spent":"481.796152ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T00:52:07.599481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.1159ms","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-05-15T00:52:07.599502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.895814ms","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-05-15T00:52:07.599561Z","caller":"traceutil/trace.go:171","msg":"trace[1289580380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"301.240254ms","start":"2026-05-15T00:52:07.298301Z","end":"2026-05-15T00:52:07.599542Z","steps":["trace[1289580380] 'agreement among raft nodes before linearized reading' (duration: 300.935935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:07.599612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.298281Z","time spent":"301.320026ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T00:52:07.599635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.051489ms","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-05-15T00:52:07.599617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.201941ms","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-05-15T00:52:07.599663Z","caller":"traceutil/trace.go:171","msg":"trace[769395941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"482.116961ms","start":"2026-05-15T00:52:07.117538Z","end":"2026-05-15T00:52:07.599655Z","steps":["trace[769395941] 'agreement among raft nodes before linearized reading' (duration: 481.821092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:07.599649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.584515ms","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-05-15T00:52:07.599703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.117515Z","time spent":"482.181373ms","remote":"127.0.0.1:53500","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-05-15T00:52:07.599726Z","caller":"traceutil/trace.go:171","msg":"trace[1283570276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"300.72566ms","start":"2026-05-15T00:52:07.298981Z","end":"2026-05-15T00:52:07.599707Z","steps":["trace[1283570276] 'agreement among raft nodes before linearized reading' (duration: 300.275706ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:07.599745Z","caller":"traceutil/trace.go:171","msg":"trace[325534600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"301.386498ms","start":"2026-05-15T00:52:07.298334Z","end":"2026-05-15T00:52:07.59972Z","steps":["trace[325534600] 'agreement among raft nodes before linearized reading' (duration: 301.048729ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:07.599568Z","caller":"traceutil/trace.go:171","msg":"trace[1904634618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"300.989687ms","start":"2026-05-15T00:52:07.298563Z","end":"2026-05-15T00:52:07.599552Z","steps":["trace[1904634618] 'agreement among raft nodes before linearized reading' (duration: 300.75167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:07.599802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.298964Z","time spent":"300.824921ms","remote":"127.0.0.1:53500","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-05-15T00:52:07.599812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.298555Z","time spent":"301.246404ms","remote":"127.0.0.1:53500","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-05-15T00:52:07.599818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.298304Z","time spent":"301.49636ms","remote":"127.0.0.1:53500","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-05-15T00:52:07.599917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.983338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-15T00:52:07.599945Z","caller":"traceutil/trace.go:171","msg":"trace[1497236266] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1275; }","duration":"249.05875ms","start":"2026-05-15T00:52:07.350878Z","end":"2026-05-15T00:52:07.599937Z","steps":["trace[1497236266] 'agreement among raft nodes before linearized reading' (duration: 248.518265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:08.237162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.818926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T00:52:08.237292Z","caller":"traceutil/trace.go:171","msg":"trace[892451109] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"631.969439ms","start":"2026-05-15T00:52:07.605298Z","end":"2026-05-15T00:52:08.237267Z","steps":["trace[892451109] 'process raft request' (duration: 374.965518ms)","trace[892451109] 'compare' (duration: 256.690302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:52:08.237406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.60526Z","time spent":"632.102992ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:52:08.325308Z","caller":"traceutil/trace.go:171","msg":"trace[532541692] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"719.473716ms","start":"2026-05-15T00:52:07.605811Z","end":"2026-05-15T00:52:08.325285Z","steps":["trace[532541692] 'process raft request' (duration: 719.405594ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:08.32534Z","caller":"traceutil/trace.go:171","msg":"trace[737670049] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"719.911257ms","start":"2026-05-15T00:52:07.60541Z","end":"2026-05-15T00:52:08.325321Z","steps":["trace[737670049] 'process raft request' (duration: 719.728662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:08.325442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.605381Z","time spent":"719.995721ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:52:08.325451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.605795Z","time spent":"719.581789ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:52:08.325459Z","caller":"traceutil/trace.go:171","msg":"trace[1004630665] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"719.98626ms","start":"2026-05-15T00:52:07.605445Z","end":"2026-05-15T00:52:08.325431Z","steps":["trace[1004630665] 'process raft request' (duration: 719.745343ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:08.325338Z","caller":"traceutil/trace.go:171","msg":"trace[159008863] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"719.949809ms","start":"2026-05-15T00:52:07.605345Z","end":"2026-05-15T00:52:08.325295Z","steps":["trace[159008863] 'process raft request' (duration: 719.669371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:08.325606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.605436Z","time spent":"720.092513ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:52:08.325782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.605315Z","time spent":"720.311558ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T00:52:08.326734Z","caller":"traceutil/trace.go:171","msg":"trace[1369059836] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"719.566249ms","start":"2026-05-15T00:52:07.606023Z","end":"2026-05-15T00:52:08.325589Z","steps":["trace[1369059836] 'process raft request' (duration: 719.228429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:08.326837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.606012Z","time spent":"720.780632ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T00:52:08.327096Z","caller":"traceutil/trace.go:171","msg":"trace[525945495] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1327; }","duration":"632.140762ms","start":"2026-05-15T00:52:07.694943Z","end":"2026-05-15T00:52:08.327084Z","steps":["trace[525945495] 'read index received' (duration: 285.334969ms)","trace[525945495] 'applied index is now lower than readState.Index' (duration: 346.804933ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:52:08.32718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.232054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:08.327211Z","caller":"traceutil/trace.go:171","msg":"trace[513855114] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1282; }","duration":"632.267296ms","start":"2026-05-15T00:52:07.694935Z","end":"2026-05-15T00:52:08.327203Z","steps":["trace[513855114] 'agreement among raft nodes before linearized reading' (duration: 632.195413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:08.327235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.694872Z","time spent":"632.356088ms","remote":"127.0.0.1:51928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-15T00:52:08.327321Z","caller":"traceutil/trace.go:171","msg":"trace[1549704064] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"720.780113ms","start":"2026-05-15T00:52:07.606522Z","end":"2026-05-15T00:52:08.327302Z","steps":["trace[1549704064] 'process raft request' (duration: 718.951132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:08.327566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:07.606509Z","time spent":"720.909916ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:52:08.512773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.381549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:08.512883Z","caller":"traceutil/trace.go:171","msg":"trace[2114049270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"169.528254ms","start":"2026-05-15T00:52:08.343328Z","end":"2026-05-15T00:52:08.512856Z","steps":["trace[2114049270] 'range keys from in-memory index tree' (duration: 169.309387ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:09.602462Z","caller":"traceutil/trace.go:171","msg":"trace[950417217] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"114.553022ms","start":"2026-05-15T00:52:09.48788Z","end":"2026-05-15T00:52:09.602433Z","steps":["trace[950417217] 'process raft request' (duration: 114.362206ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:10.327816Z","caller":"traceutil/trace.go:171","msg":"trace[233422902] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"119.569281ms","start":"2026-05-15T00:52:10.208212Z","end":"2026-05-15T00:52:10.327781Z","steps":["trace[233422902] 'process raft request' (duration: 119.219742ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:10.489215Z","caller":"traceutil/trace.go:171","msg":"trace[1410331478] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"152.584722ms","start":"2026-05-15T00:52:10.336594Z","end":"2026-05-15T00:52:10.489179Z","steps":["trace[1410331478] 'process raft request' (duration: 151.12931ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:10.489593Z","caller":"traceutil/trace.go:171","msg":"trace[1582144812] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"152.934661ms","start":"2026-05-15T00:52:10.336592Z","end":"2026-05-15T00:52:10.489527Z","steps":["trace[1582144812] 'process raft request' (duration: 152.528379ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:10.489683Z","caller":"traceutil/trace.go:171","msg":"trace[700396742] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"150.504183ms","start":"2026-05-15T00:52:10.339165Z","end":"2026-05-15T00:52:10.489669Z","steps":["trace[700396742] 'process raft request' (duration: 150.248936ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:10.489966Z","caller":"traceutil/trace.go:171","msg":"trace[1046463632] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1338; }","duration":"153.295361ms","start":"2026-05-15T00:52:10.33666Z","end":"2026-05-15T00:52:10.489955Z","steps":["trace[1046463632] 'read index received' (duration: 35.036476ms)","trace[1046463632] 'applied index is now lower than readState.Index' (duration: 118.257585ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:10.490071Z","caller":"traceutil/trace.go:171","msg":"trace[1583096708] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"149.489035ms","start":"2026-05-15T00:52:10.340541Z","end":"2026-05-15T00:52:10.49003Z","steps":["trace[1583096708] 'process raft request' (duration: 149.083944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:10.490179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.973915ms","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-05-15T00:52:10.490217Z","caller":"traceutil/trace.go:171","msg":"trace[1807633002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"152.049867ms","start":"2026-05-15T00:52:10.338158Z","end":"2026-05-15T00:52:10.490208Z","steps":["trace[1807633002] 'agreement among raft nodes before linearized reading' (duration: 151.887453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:10.490387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.979033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:10.490421Z","caller":"traceutil/trace.go:171","msg":"trace[570283527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"148.027004ms","start":"2026-05-15T00:52:10.342386Z","end":"2026-05-15T00:52:10.490413Z","steps":["trace[570283527] 'agreement among raft nodes before linearized reading' (duration: 147.978833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:10.490365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.641351ms","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-05-15T00:52:10.490486Z","caller":"traceutil/trace.go:171","msg":"trace[805323079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"153.834706ms","start":"2026-05-15T00:52:10.336627Z","end":"2026-05-15T00:52:10.490462Z","steps":["trace[805323079] 'agreement among raft nodes before linearized reading' (duration: 153.390804ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:20.200723Z","caller":"traceutil/trace.go:171","msg":"trace[365149712] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"203.542416ms","start":"2026-05-15T00:52:19.997141Z","end":"2026-05-15T00:52:20.200683Z","steps":["trace[365149712] 'process raft request' (duration: 203.230967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.10561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.044411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:21.105683Z","caller":"traceutil/trace.go:171","msg":"trace[327627620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"760.157554ms","start":"2026-05-15T00:52:20.345509Z","end":"2026-05-15T00:52:21.105667Z","steps":["trace[327627620] 'range keys from in-memory index tree' (duration: 759.97644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.105716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:20.34549Z","time spent":"760.217776ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T00:52:21.106106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.691196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-15T00:52:21.106141Z","caller":"traceutil/trace.go:171","msg":"trace[1738450325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1367; }","duration":"682.764786ms","start":"2026-05-15T00:52:20.423368Z","end":"2026-05-15T00:52:21.106133Z","steps":["trace[1738450325] 'range keys from in-memory index tree' (duration: 682.52597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.106162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:20.423339Z","time spent":"682.8172ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T00:52:21.106316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.32389ms","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-05-15T00:52:21.106381Z","caller":"traceutil/trace.go:171","msg":"trace[935464064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1367; }","duration":"551.388913ms","start":"2026-05-15T00:52:20.554959Z","end":"2026-05-15T00:52:21.106348Z","steps":["trace[935464064] 'range keys from in-memory index tree' (duration: 551.171227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.10637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.791404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-15T00:52:21.106415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:20.554949Z","time spent":"551.457185ms","remote":"127.0.0.1:53500","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-05-15T00:52:21.106457Z","caller":"traceutil/trace.go:171","msg":"trace[2132964020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1367; }","duration":"569.923967ms","start":"2026-05-15T00:52:20.53651Z","end":"2026-05-15T00:52:21.106434Z","steps":["trace[2132964020] 'range keys from in-memory index tree' (duration: 569.586058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.106549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:20.53649Z","time spent":"570.02552ms","remote":"127.0.0.1:53500","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-05-15T00:52:21.106555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.893025ms","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-05-15T00:52:21.106615Z","caller":"traceutil/trace.go:171","msg":"trace[1795364965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1367; }","duration":"552.987318ms","start":"2026-05-15T00:52:20.553612Z","end":"2026-05-15T00:52:21.106599Z","steps":["trace[1795364965] 'range keys from in-memory index tree' (duration: 552.72984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.106663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:20.553599Z","time spent":"553.057419ms","remote":"127.0.0.1:53500","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-05-15T00:52:21.106635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.044771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-15T00:52:21.106763Z","caller":"traceutil/trace.go:171","msg":"trace[1802705146] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"265.149704ms","start":"2026-05-15T00:52:20.841545Z","end":"2026-05-15T00:52:21.106694Z","steps":["trace[1802705146] 'range keys from in-memory index tree' (duration: 264.921628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.106855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.171595ms","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-05-15T00:52:21.106978Z","caller":"traceutil/trace.go:171","msg":"trace[893669719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1367; }","duration":"556.600787ms","start":"2026-05-15T00:52:20.550357Z","end":"2026-05-15T00:52:21.106958Z","steps":["trace[893669719] 'range keys from in-memory index tree' (duration: 555.743174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:21.107106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:52:20.550335Z","time spent":"556.754991ms","remote":"127.0.0.1:53500","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-05-15T00:52:21.251722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.751787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:21.251805Z","caller":"traceutil/trace.go:171","msg":"trace[1154516707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"143.877461ms","start":"2026-05-15T00:52:21.107908Z","end":"2026-05-15T00:52:21.251786Z","steps":["trace[1154516707] 'agreement among raft nodes before linearized reading' (duration: 143.723976ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.251856Z","caller":"traceutil/trace.go:171","msg":"trace[1086469854] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"143.615884ms","start":"2026-05-15T00:52:21.107944Z","end":"2026-05-15T00:52:21.25156Z","steps":["trace[1086469854] 'read index received' (duration: 59.349246ms)","trace[1086469854] 'applied index is now lower than readState.Index' (duration: 84.265218ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:21.256225Z","caller":"traceutil/trace.go:171","msg":"trace[282939889] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"144.784066ms","start":"2026-05-15T00:52:21.111398Z","end":"2026-05-15T00:52:21.256182Z","steps":["trace[282939889] 'process raft request' (duration: 144.55934ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.256357Z","caller":"traceutil/trace.go:171","msg":"trace[969455000] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"144.849558ms","start":"2026-05-15T00:52:21.111479Z","end":"2026-05-15T00:52:21.256328Z","steps":["trace[969455000] 'process raft request' (duration: 144.618431ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.256372Z","caller":"traceutil/trace.go:171","msg":"trace[1702795837] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"144.59257ms","start":"2026-05-15T00:52:21.111765Z","end":"2026-05-15T00:52:21.256358Z","steps":["trace[1702795837] 'process raft request' (duration: 144.378144ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.256586Z","caller":"traceutil/trace.go:171","msg":"trace[1856528415] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"144.659802ms","start":"2026-05-15T00:52:21.111912Z","end":"2026-05-15T00:52:21.256571Z","steps":["trace[1856528415] 'process raft request' (duration: 144.322903ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.256766Z","caller":"traceutil/trace.go:171","msg":"trace[968502627] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"144.303523ms","start":"2026-05-15T00:52:21.112451Z","end":"2026-05-15T00:52:21.256754Z","steps":["trace[968502627] 'process raft request' (duration: 143.950243ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.256917Z","caller":"traceutil/trace.go:171","msg":"trace[1800001838] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"101.526185ms","start":"2026-05-15T00:52:21.155375Z","end":"2026-05-15T00:52:21.256901Z","steps":["trace[1800001838] 'process raft request' (duration: 101.358131ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:21.256295Z","caller":"traceutil/trace.go:171","msg":"trace[1495374440] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"144.367063ms","start":"2026-05-15T00:52:21.111898Z","end":"2026-05-15T00:52:21.256265Z","steps":["trace[1495374440] 'process raft request' (duration: 144.302552ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:43.503555Z","caller":"traceutil/trace.go:171","msg":"trace[264716493] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"135.806468ms","start":"2026-05-15T00:52:43.367723Z","end":"2026-05-15T00:52:43.50353Z","steps":["trace[264716493] 'process raft request' (duration: 132.383603ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:43.503984Z","caller":"traceutil/trace.go:171","msg":"trace[600520500] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"135.990382ms","start":"2026-05-15T00:52:43.36797Z","end":"2026-05-15T00:52:43.50396Z","steps":["trace[600520500] 'process raft request' (duration: 135.511809ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:43.50439Z","caller":"traceutil/trace.go:171","msg":"trace[1685049407] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"135.211861ms","start":"2026-05-15T00:52:43.369158Z","end":"2026-05-15T00:52:43.50437Z","steps":["trace[1685049407] 'process raft request' (duration: 134.710336ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:43.50451Z","caller":"traceutil/trace.go:171","msg":"trace[1919111247] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"134.908593ms","start":"2026-05-15T00:52:43.369579Z","end":"2026-05-15T00:52:43.504487Z","steps":["trace[1919111247] 'process raft request' (duration: 134.675406ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:57.279955Z","caller":"traceutil/trace.go:171","msg":"trace[1812526721] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"103.123441ms","start":"2026-05-15T00:52:57.17679Z","end":"2026-05-15T00:52:57.279913Z","steps":["trace[1812526721] 'process raft request' (duration: 102.975156ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:52:59.461858Z","caller":"traceutil/trace.go:171","msg":"trace[65187721] linearizableReadLoop","detail":"{readStateIndex:1607; appliedIndex:1606; }","duration":"116.517812ms","start":"2026-05-15T00:52:59.345309Z","end":"2026-05-15T00:52:59.461827Z","steps":["trace[65187721] 'read index received' (duration: 116.325267ms)","trace[65187721] 'applied index is now lower than readState.Index' (duration: 191.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:52:59.461961Z","caller":"traceutil/trace.go:171","msg":"trace[2134172553] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"119.369532ms","start":"2026-05-15T00:52:59.342553Z","end":"2026-05-15T00:52:59.461922Z","steps":["trace[2134172553] 'process raft request' (duration: 119.062034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:52:59.462023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.704426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:52:59.46211Z","caller":"traceutil/trace.go:171","msg":"trace[240355014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1545; }","duration":"116.846281ms","start":"2026-05-15T00:52:59.345246Z","end":"2026-05-15T00:52:59.462092Z","steps":["trace[240355014] 'agreement among raft nodes before linearized reading' (duration: 116.701158ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:18.94525Z","caller":"traceutil/trace.go:171","msg":"trace[1237653803] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"158.472786ms","start":"2026-05-15T00:53:18.786744Z","end":"2026-05-15T00:53:18.945216Z","steps":["trace[1237653803] 'process raft request' (duration: 100.673642ms)","trace[1237653803] 'compare' (duration: 57.677311ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:19.714977Z","caller":"traceutil/trace.go:171","msg":"trace[543558884] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"177.104313ms","start":"2026-05-15T00:53:19.537844Z","end":"2026-05-15T00:53:19.714949Z","steps":["trace[543558884] 'process raft request' (duration: 176.917008ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:19.783629Z","caller":"traceutil/trace.go:171","msg":"trace[1503341399] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"162.5386ms","start":"2026-05-15T00:53:19.621062Z","end":"2026-05-15T00:53:19.7836Z","steps":["trace[1503341399] 'process raft request' (duration: 145.664531ms)","trace[1503341399] 'compare' (duration: 16.657093ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:20.020701Z","caller":"traceutil/trace.go:171","msg":"trace[57820241] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"232.370517ms","start":"2026-05-15T00:53:19.788287Z","end":"2026-05-15T00:53:20.020658Z","steps":["trace[57820241] 'process raft request' (duration: 179.559051ms)","trace[57820241] 'compare' (duration: 52.541378ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:20.021906Z","caller":"traceutil/trace.go:171","msg":"trace[762631250] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"233.385124ms","start":"2026-05-15T00:53:19.788489Z","end":"2026-05-15T00:53:20.021874Z","steps":["trace[762631250] 'process raft request' (duration: 232.418257ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:20.021454Z","caller":"traceutil/trace.go:171","msg":"trace[1850773336] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"232.959733ms","start":"2026-05-15T00:53:19.788449Z","end":"2026-05-15T00:53:20.021409Z","steps":["trace[1850773336] 'process raft request' (duration: 232.13966ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:20.022035Z","caller":"traceutil/trace.go:171","msg":"trace[476346655] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"233.199409ms","start":"2026-05-15T00:53:19.7888Z","end":"2026-05-15T00:53:20.022Z","steps":["trace[476346655] 'process raft request' (duration: 232.987593ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:31.96238Z","caller":"traceutil/trace.go:171","msg":"trace[839762419] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"103.959601ms","start":"2026-05-15T00:53:31.858398Z","end":"2026-05-15T00:53:31.962357Z","steps":["trace[839762419] 'process raft request' (duration: 103.846418ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:32.29375Z","caller":"traceutil/trace.go:171","msg":"trace[1037559232] linearizableReadLoop","detail":"{readStateIndex:1765; appliedIndex:1764; }","duration":"103.329644ms","start":"2026-05-15T00:53:32.190394Z","end":"2026-05-15T00:53:32.293723Z","steps":["trace[1037559232] 'read index received' (duration: 4.678729ms)","trace[1037559232] 'applied index is now lower than readState.Index' (duration: 98.649635ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:32.293833Z","caller":"traceutil/trace.go:171","msg":"trace[1741529898] transaction","detail":"{read_only:false; response_revision:1696; number_of_response:1; }","duration":"103.604332ms","start":"2026-05-15T00:53:32.190192Z","end":"2026-05-15T00:53:32.293797Z","steps":["trace[1741529898] 'compare' (duration: 98.47039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:53:32.294024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.614382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-15T00:53:32.294213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.702835ms","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-05-15T00:53:32.294225Z","caller":"traceutil/trace.go:171","msg":"trace[1338438472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1696; }","duration":"103.975563ms","start":"2026-05-15T00:53:32.190236Z","end":"2026-05-15T00:53:32.294212Z","steps":["trace[1338438472] 'agreement among raft nodes before linearized reading' (duration: 103.635884ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:32.294257Z","caller":"traceutil/trace.go:171","msg":"trace[1866021908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1696; }","duration":"103.765256ms","start":"2026-05-15T00:53:32.190479Z","end":"2026-05-15T00:53:32.294245Z","steps":["trace[1866021908] 'agreement among raft nodes before linearized reading' (duration: 103.669543ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:34.420684Z","caller":"traceutil/trace.go:171","msg":"trace[211662930] linearizableReadLoop","detail":"{readStateIndex:1773; appliedIndex:1772; }","duration":"114.782521ms","start":"2026-05-15T00:53:34.305855Z","end":"2026-05-15T00:53:34.420637Z","steps":["trace[211662930] 'read index received' (duration: 38.306768ms)","trace[211662930] 'applied index is now lower than readState.Index' (duration: 76.473813ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:34.420823Z","caller":"traceutil/trace.go:171","msg":"trace[1486985484] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"117.424724ms","start":"2026-05-15T00:53:34.303365Z","end":"2026-05-15T00:53:34.42079Z","steps":["trace[1486985484] 'process raft request' (duration: 40.90903ms)","trace[1486985484] 'compare' (duration: 76.146944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:53:34.421019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.146531ms","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-05-15T00:53:34.421085Z","caller":"traceutil/trace.go:171","msg":"trace[1434630503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1704; }","duration":"115.216624ms","start":"2026-05-15T00:53:34.305847Z","end":"2026-05-15T00:53:34.421063Z","steps":["trace[1434630503] 'agreement among raft nodes before linearized reading' (duration: 115.028488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:53:34.421118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.723462ms","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-05-15T00:53:34.421214Z","caller":"traceutil/trace.go:171","msg":"trace[1980742249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1704; }","duration":"113.900047ms","start":"2026-05-15T00:53:34.307292Z","end":"2026-05-15T00:53:34.421192Z","steps":["trace[1980742249] 'agreement among raft nodes before linearized reading' (duration: 113.602978ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:34.581127Z","caller":"traceutil/trace.go:171","msg":"trace[624794811] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"156.497103ms","start":"2026-05-15T00:53:34.424604Z","end":"2026-05-15T00:53:34.581101Z","steps":["trace[624794811] 'process raft request' (duration: 156.358549ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:34.58334Z","caller":"traceutil/trace.go:171","msg":"trace[65756094] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"158.309384ms","start":"2026-05-15T00:53:34.425003Z","end":"2026-05-15T00:53:34.583313Z","steps":["trace[65756094] 'process raft request' (duration: 158.065587ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:53:52.256868Z","caller":"traceutil/trace.go:171","msg":"trace[1608810125] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"132.48301ms","start":"2026-05-15T00:53:52.124323Z","end":"2026-05-15T00:53:52.256807Z","steps":["trace[1608810125] 'process raft request' (duration: 109.071043ms)","trace[1608810125] 'compare' (duration: 23.232293ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:58.511492Z","caller":"traceutil/trace.go:171","msg":"trace[1502080651] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"163.892679ms","start":"2026-05-15T00:53:58.34757Z","end":"2026-05-15T00:53:58.511463Z","steps":["trace[1502080651] 'read index received' (duration: 114.026041ms)","trace[1502080651] 'applied index is now lower than readState.Index' (duration: 49.865838ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:53:58.511556Z","caller":"traceutil/trace.go:171","msg":"trace[142184485] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"186.773601ms","start":"2026-05-15T00:53:58.324746Z","end":"2026-05-15T00:53:58.511519Z","steps":["trace[142184485] 'process raft request' (duration: 136.876842ms)","trace[142184485] 'compare' (duration: 49.727884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:53:58.511642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.050153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:53:58.511915Z","caller":"traceutil/trace.go:171","msg":"trace[2046513092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1813; }","duration":"164.363941ms","start":"2026-05-15T00:53:58.347526Z","end":"2026-05-15T00:53:58.51189Z","steps":["trace[2046513092] 'agreement among raft nodes before linearized reading' (duration: 164.062692ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:54:21.630037Z","caller":"traceutil/trace.go:171","msg":"trace[1053357092] linearizableReadLoop","detail":"{readStateIndex:1999; appliedIndex:1998; }","duration":"284.677765ms","start":"2026-05-15T00:54:21.345337Z","end":"2026-05-15T00:54:21.630015Z","steps":["trace[1053357092] 'read index received' (duration: 284.464759ms)","trace[1053357092] 'applied index is now lower than readState.Index' (duration: 212.286µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T00:54:21.630264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.902001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T00:54:21.630306Z","caller":"traceutil/trace.go:171","msg":"trace[848228101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1920; }","duration":"284.995573ms","start":"2026-05-15T00:54:21.345303Z","end":"2026-05-15T00:54:21.630298Z","steps":["trace[848228101] 'agreement among raft nodes before linearized reading' (duration: 284.87617ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:54:21.630538Z","caller":"traceutil/trace.go:171","msg":"trace[1372248191] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"293.068917ms","start":"2026-05-15T00:54:21.337434Z","end":"2026-05-15T00:54:21.630503Z","steps":["trace[1372248191] 'process raft request' (duration: 292.380788ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:55:10.269404Z","caller":"traceutil/trace.go:171","msg":"trace[1720668319] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"136.395299ms","start":"2026-05-15T00:55:10.132974Z","end":"2026-05-15T00:55:10.269369Z","steps":["trace[1720668319] 'process raft request' (duration: 136.197684ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:55:27.483753Z","caller":"traceutil/trace.go:171","msg":"trace[1704850534] linearizableReadLoop","detail":"{readStateIndex:2335; appliedIndex:2334; }","duration":"240.396743ms","start":"2026-05-15T00:55:27.243311Z","end":"2026-05-15T00:55:27.483708Z","steps":["trace[1704850534] 'read index received' (duration: 240.119475ms)","trace[1704850534] 'applied index is now lower than readState.Index' (duration: 275.648µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:55:27.483989Z","caller":"traceutil/trace.go:171","msg":"trace[1416407582] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"341.865465ms","start":"2026-05-15T00:55:27.142104Z","end":"2026-05-15T00:55:27.483969Z","steps":["trace[1416407582] 'process raft request' (duration: 341.30857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:55:27.484423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.637552ms","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-05-15T00:55:27.484522Z","caller":"traceutil/trace.go:171","msg":"trace[275138913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2242; }","duration":"220.776896ms","start":"2026-05-15T00:55:27.263723Z","end":"2026-05-15T00:55:27.4845Z","steps":["trace[275138913] 'agreement among raft nodes before linearized reading' (duration: 220.509138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:55:27.484512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T00:55:27.142067Z","time spent":"341.97555ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T00:55:27.484708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.827598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-15T00:55:27.484742Z","caller":"traceutil/trace.go:171","msg":"trace[358724165] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2242; }","duration":"186.893439ms","start":"2026-05-15T00:55:27.29784Z","end":"2026-05-15T00:55:27.484734Z","steps":["trace[358724165] 'agreement among raft nodes before linearized reading' (duration: 186.801496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:55:27.485052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.736009ms","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-05-15T00:55:27.485118Z","caller":"traceutil/trace.go:171","msg":"trace[1378812929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2242; }","duration":"241.844212ms","start":"2026-05-15T00:55:27.243258Z","end":"2026-05-15T00:55:27.485102Z","steps":["trace[1378812929] 'agreement among raft nodes before linearized reading' (duration: 241.472421ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T00:55:45.842873Z","caller":"traceutil/trace.go:171","msg":"trace[1485137195] linearizableReadLoop","detail":"{readStateIndex:2436; appliedIndex:2435; }","duration":"101.237198ms","start":"2026-05-15T00:55:45.741609Z","end":"2026-05-15T00:55:45.842846Z","steps":["trace[1485137195] 'read index received' (duration: 100.994931ms)","trace[1485137195] 'applied index is now lower than readState.Index' (duration: 241.217µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T00:55:45.842923Z","caller":"traceutil/trace.go:171","msg":"trace[391660879] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"101.545917ms","start":"2026-05-15T00:55:45.741341Z","end":"2026-05-15T00:55:45.842887Z","steps":["trace[391660879] 'process raft request' (duration: 101.351311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T00:55:45.843073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.441083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-05-15T00:55:45.843115Z","caller":"traceutil/trace.go:171","msg":"trace[554742332] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:2339; }","duration":"101.525896ms","start":"2026-05-15T00:55:45.741574Z","end":"2026-05-15T00:55:45.8431Z","steps":["trace[554742332] 'agreement among raft nodes before linearized reading' (duration: 101.409313ms)"],"step_count":1}