{"level":"warn","ts":"2026-05-29T05:53:59.526661Z","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-29T05:53:59.526761Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.104:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.104:2380","--initial-cluster=instance=https://199.19.213.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.104: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-29T05:53:59.526838Z","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-29T05:53:59.526857Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-05-29T05:53:59.526877Z","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-29T05:53:59.527696Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"]} {"level":"info","ts":"2026-05-29T05:53:59.527815Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.104: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-29T05:53:59.531939Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.778862ms"} {"level":"info","ts":"2026-05-29T05:53:59.538519Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a0c9a032e61a05a","cluster-id":"153e4a99345578f6"} {"level":"info","ts":"2026-05-29T05:53:59.538624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:53:59.538656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 0"} {"level":"info","ts":"2026-05-29T05:53:59.538673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a0c9a032e61a05a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:53:59.538685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 1"} {"level":"info","ts":"2026-05-29T05:53:59.538739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"warn","ts":"2026-05-29T05:53:59.547457Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:53:59.548886Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:53:59.550276Z","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-29T05:53:59.553097Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a0c9a032e61a05a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:53:59.556438Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a0c9a032e61a05a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:53:59.556575Z","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-29T05:53:59.55662Z","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-29T05:53:59.556686Z","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-29T05:53:59.557069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"info","ts":"2026-05-29T05:53:59.557285Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","added-peer-id":"8a0c9a032e61a05a","added-peer-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-05-29T05:53:59.559764Z","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-29T05:53:59.559962Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a0c9a032e61a05a","initial-advertise-peer-urls":["https://199.19.213.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:53:59.560013Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:53:59.560091Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-05-29T05:53:59.560103Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-05-29T05:54:00.33957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:54:00.339687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:54:00.33972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgPreVoteResp from 8a0c9a032e61a05a at term 1"} {"level":"info","ts":"2026-05-29T05:54:00.339753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:54:00.339817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgVoteResp from 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-05-29T05:54:00.339844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became leader at term 2"} {"level":"info","ts":"2026-05-29T05:54:00.339867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a0c9a032e61a05a elected leader 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-05-29T05:54:00.341115Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:54:00.342149Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:54:00.34214Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a0c9a032e61a05a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.104:2379]}","request-path":"/0/members/8a0c9a032e61a05a/attributes","cluster-id":"153e4a99345578f6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:54:00.342167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:54:00.34252Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:54:00.342638Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:54:00.342653Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:54:00.342644Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:54:00.342681Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:54:00.346434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:54:00.348104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.104:2379"} {"level":"info","ts":"2026-05-29T05:54:06.304835Z","caller":"traceutil/trace.go:171","msg":"trace[2057536839] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"172.083146ms","start":"2026-05-29T05:54:06.132721Z","end":"2026-05-29T05:54:06.304805Z","steps":["trace[2057536839] 'process raft request' (duration: 136.260242ms)","trace[2057536839] 'compare' (duration: 35.68564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:12.372194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709841004,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:54:12.718248Z","caller":"traceutil/trace.go:171","msg":"trace[111159938] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"961.63009ms","start":"2026-05-29T05:54:11.756591Z","end":"2026-05-29T05:54:12.718221Z","steps":["trace[111159938] 'process raft request' (duration: 961.468726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:12.718922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:11.756575Z","time spent":"961.756263ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:54:13.41933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.270179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:54:13.419706Z","caller":"traceutil/trace.go:171","msg":"trace[1509008299] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:401; }","duration":"1.54821819s","start":"2026-05-29T05:54:11.87144Z","end":"2026-05-29T05:54:13.419659Z","steps":["trace[1509008299] 'read index received' (duration: 846.727859ms)","trace[1509008299] 'applied index is now lower than readState.Index' (duration: 701.488861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:13.419835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548381073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-29T05:54:13.419904Z","caller":"traceutil/trace.go:171","msg":"trace[2066046828] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:393; }","duration":"1.548499636s","start":"2026-05-29T05:54:11.871393Z","end":"2026-05-29T05:54:13.419893Z","steps":["trace[2066046828] 'agreement among raft nodes before linearized reading' (duration: 1.548370823s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:13.419937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:11.871378Z","time spent":"1.548550557s","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-29T05:54:13.41983Z","caller":"traceutil/trace.go:171","msg":"trace[1963985418] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.36811632s","start":"2026-05-29T05:54:12.051633Z","end":"2026-05-29T05:54:13.419749Z","steps":["trace[1963985418] 'process raft request' (duration: 1.218166114s)","trace[1963985418] 'compare' (duration: 149.124295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:13.420127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.22659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-29T05:54:13.420212Z","caller":"traceutil/trace.go:171","msg":"trace[1232143128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"147.368443ms","start":"2026-05-29T05:54:13.272823Z","end":"2026-05-29T05:54:13.420191Z","steps":["trace[1232143128] 'agreement among raft nodes before linearized reading' (duration: 147.167078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:13.42018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:12.051616Z","time spent":"1.368438817s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:54:13.420267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.358965ms","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-29T05:54:13.42038Z","caller":"traceutil/trace.go:171","msg":"trace[896237283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"975.624372ms","start":"2026-05-29T05:54:12.444736Z","end":"2026-05-29T05:54:13.420361Z","steps":["trace[896237283] 'agreement among raft nodes before linearized reading' (duration: 975.386596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:13.420483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:12.444709Z","time spent":"975.759464ms","remote":"127.0.0.1:55164","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-29T05:54:13.420659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498259152s","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-29T05:54:13.420708Z","caller":"traceutil/trace.go:171","msg":"trace[488146837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"1.498336164s","start":"2026-05-29T05:54:11.922363Z","end":"2026-05-29T05:54:13.420699Z","steps":["trace[488146837] 'agreement among raft nodes before linearized reading' (duration: 1.497512854s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:13.420737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:11.92235Z","time spent":"1.498381065s","remote":"127.0.0.1:55164","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-29T05:54:13.420887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.609195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.104\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T05:54:13.420921Z","caller":"traceutil/trace.go:171","msg":"trace[447934992] range","detail":"{range_begin:/registry/masterleases/199.19.213.104; range_end:; response_count:1; response_revision:393; }","duration":"288.680297ms","start":"2026-05-29T05:54:13.132233Z","end":"2026-05-29T05:54:13.420913Z","steps":["trace[447934992] 'agreement among raft nodes before linearized reading' (duration: 287.826656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:13.912441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.377889ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-29T05:54:13.912583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:13.42183Z","time spent":"490.748963ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T05:54:14.346701Z","caller":"traceutil/trace.go:171","msg":"trace[1660494559] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"922.032776ms","start":"2026-05-29T05:54:13.42465Z","end":"2026-05-29T05:54:14.346683Z","steps":["trace[1660494559] 'process raft request' (duration: 877.668265ms)","trace[1660494559] 'compare' (duration: 44.153675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:14.347498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:13.424634Z","time spent":"922.734214ms","remote":"127.0.0.1:55164","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-29T05:54:14.349361Z","caller":"traceutil/trace.go:171","msg":"trace[536977446] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"924.563758ms","start":"2026-05-29T05:54:13.424768Z","end":"2026-05-29T05:54:14.349331Z","steps":["trace[536977446] 'process raft request' (duration: 921.871103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:14.349525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:13.424762Z","time spent":"924.663781ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:54:14.350727Z","caller":"traceutil/trace.go:171","msg":"trace[308523382] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"925.809009ms","start":"2026-05-29T05:54:13.424878Z","end":"2026-05-29T05:54:14.350687Z","steps":["trace[308523382] 'process raft request' (duration: 924.389264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:14.351288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:13.424871Z","time spent":"926.002593ms","remote":"127.0.0.1:55164","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-29T05:54:14.35342Z","caller":"traceutil/trace.go:171","msg":"trace[1226578444] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"925.50515ms","start":"2026-05-29T05:54:13.427878Z","end":"2026-05-29T05:54:14.353383Z","steps":["trace[1226578444] 'process raft request' (duration: 922.521818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:14.35352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:13.427869Z","time spent":"925.596212ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:54:14.353846Z","caller":"traceutil/trace.go:171","msg":"trace[537537709] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"440.18588ms","start":"2026-05-29T05:54:13.913641Z","end":"2026-05-29T05:54:14.353827Z","steps":["trace[537537709] 'process raft request' (duration: 439.670978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:14.354499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:13.913616Z","time spent":"440.258902ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:54:38.488657Z","caller":"traceutil/trace.go:171","msg":"trace[1871255474] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"498.84894ms","start":"2026-05-29T05:54:37.989785Z","end":"2026-05-29T05:54:38.488634Z","steps":["trace[1871255474] 'process raft request' (duration: 498.723507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.488919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:37.989766Z","time spent":"499.048016ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:54:38.497394Z","caller":"traceutil/trace.go:171","msg":"trace[1568168450] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"500.288535ms","start":"2026-05-29T05:54:37.997076Z","end":"2026-05-29T05:54:38.497365Z","steps":["trace[1568168450] 'process raft request' (duration: 500.069449ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:38.497473Z","caller":"traceutil/trace.go:171","msg":"trace[350101914] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"499.002785ms","start":"2026-05-29T05:54:37.998448Z","end":"2026-05-29T05:54:38.497451Z","steps":["trace[350101914] 'process raft request' (duration: 498.7998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.497645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:37.998438Z","time spent":"499.145648ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:38.49768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:37.99706Z","time spent":"500.406968ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:54:38.497675Z","caller":"traceutil/trace.go:171","msg":"trace[1176927884] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"495.043668ms","start":"2026-05-29T05:54:38.002587Z","end":"2026-05-29T05:54:38.497631Z","steps":["trace[1176927884] 'process raft request' (duration: 494.736441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.497867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.002564Z","time spent":"495.242032ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:54:38.498089Z","caller":"traceutil/trace.go:171","msg":"trace[1018306263] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"494.149696ms","start":"2026-05-29T05:54:38.003907Z","end":"2026-05-29T05:54:38.498057Z","steps":["trace[1018306263] 'process raft request' (duration: 493.49654ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:38.498229Z","caller":"traceutil/trace.go:171","msg":"trace[1402386375] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"484.277645ms","start":"2026-05-29T05:54:38.013941Z","end":"2026-05-29T05:54:38.498219Z","steps":["trace[1402386375] 'process raft request' (duration: 484.161932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.498291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.013926Z","time spent":"484.345616ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:54:38.498405Z","caller":"traceutil/trace.go:171","msg":"trace[1812857342] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"487.734889ms","start":"2026-05-29T05:54:38.010458Z","end":"2026-05-29T05:54:38.498193Z","steps":["trace[1812857342] 'process raft request' (duration: 487.592276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.498608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.01045Z","time spent":"488.088568ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:54:38.498438Z","caller":"traceutil/trace.go:171","msg":"trace[1997964431] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"473.882691ms","start":"2026-05-29T05:54:38.024541Z","end":"2026-05-29T05:54:38.498424Z","steps":["trace[1997964431] 'process raft request' (duration: 473.705777ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:38.498758Z","caller":"traceutil/trace.go:171","msg":"trace[526123360] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"436.629844ms","start":"2026-05-29T05:54:38.062118Z","end":"2026-05-29T05:54:38.498748Z","steps":["trace[526123360] 'process raft request' (duration: 436.428649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.498857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.062096Z","time spent":"436.726545ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:38.498814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.003895Z","time spent":"494.267899ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:38.499041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.024532Z","time spent":"474.29246ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:54:38.499245Z","caller":"traceutil/trace.go:171","msg":"trace[1520609054] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"345.421629ms","start":"2026-05-29T05:54:38.15381Z","end":"2026-05-29T05:54:38.499232Z","steps":["trace[1520609054] 'process raft request' (duration: 344.808075ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:38.499296Z","caller":"traceutil/trace.go:171","msg":"trace[1320287848] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"465.077706ms","start":"2026-05-29T05:54:38.034201Z","end":"2026-05-29T05:54:38.499279Z","steps":["trace[1320287848] 'process raft request' (duration: 464.113633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.499309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.153782Z","time spent":"345.505392ms","remote":"127.0.0.1:55010","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-29T05:54:38.498385Z","caller":"traceutil/trace.go:171","msg":"trace[658911308] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"479.224851ms","start":"2026-05-29T05:54:38.019137Z","end":"2026-05-29T05:54:38.498362Z","steps":["trace[658911308] 'process raft request' (duration: 479.010566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:38.499361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.034181Z","time spent":"465.153629ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:38.499447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:38.019129Z","time spent":"480.293157ms","remote":"127.0.0.1:55010","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-29T05:54:44.752586Z","caller":"traceutil/trace.go:171","msg":"trace[1844111101] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"172.044203ms","start":"2026-05-29T05:54:44.580514Z","end":"2026-05-29T05:54:44.752558Z","steps":["trace[1844111101] 'process raft request' (duration: 170.924776ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:44.762543Z","caller":"traceutil/trace.go:171","msg":"trace[1196570647] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"181.699749ms","start":"2026-05-29T05:54:44.580816Z","end":"2026-05-29T05:54:44.762516Z","steps":["trace[1196570647] 'process raft request' (duration: 180.705125ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:44.762192Z","caller":"traceutil/trace.go:171","msg":"trace[1711222390] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"180.929311ms","start":"2026-05-29T05:54:44.580908Z","end":"2026-05-29T05:54:44.761838Z","steps":["trace[1711222390] 'read index received' (duration: 170.376463ms)","trace[1711222390] 'applied index is now lower than readState.Index' (duration: 10.551858ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:54:44.762664Z","caller":"traceutil/trace.go:171","msg":"trace[965877568] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"179.618838ms","start":"2026-05-29T05:54:44.583035Z","end":"2026-05-29T05:54:44.762654Z","steps":["trace[965877568] 'process raft request' (duration: 178.752398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:44.763071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.13245ms","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-29T05:54:44.763251Z","caller":"traceutil/trace.go:171","msg":"trace[1053430433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"182.352946ms","start":"2026-05-29T05:54:44.580885Z","end":"2026-05-29T05:54:44.763238Z","steps":["trace[1053430433] 'agreement among raft nodes before linearized reading' (duration: 181.259189ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:44.946411Z","caller":"traceutil/trace.go:171","msg":"trace[1473457263] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"140.557747ms","start":"2026-05-29T05:54:44.805817Z","end":"2026-05-29T05:54:44.946375Z","steps":["trace[1473457263] 'process raft request' (duration: 72.17401ms)","trace[1473457263] 'compare' (duration: 68.126851ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:54:45.90096Z","caller":"traceutil/trace.go:171","msg":"trace[1802952627] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"492.363741ms","start":"2026-05-29T05:54:45.408571Z","end":"2026-05-29T05:54:45.900935Z","steps":["trace[1802952627] 'process raft request' (duration: 491.702665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:45.901123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:45.40854Z","time spent":"492.496734ms","remote":"127.0.0.1:55054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:54:46.293421Z","caller":"traceutil/trace.go:171","msg":"trace[151887668] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"623.004726ms","start":"2026-05-29T05:54:45.670389Z","end":"2026-05-29T05:54:46.293393Z","steps":["trace[151887668] 'process raft request' (duration: 566.250693ms)","trace[151887668] 'compare' (duration: 56.642051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:46.293567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:45.670367Z","time spent":"623.12946ms","remote":"127.0.0.1:55164","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-29T05:54:46.603654Z","caller":"traceutil/trace.go:171","msg":"trace[103684224] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"433.703892ms","start":"2026-05-29T05:54:46.169926Z","end":"2026-05-29T05:54:46.60363Z","steps":["trace[103684224] 'read index received' (duration: 66.716506ms)","trace[103684224] 'applied index is now lower than readState.Index' (duration: 366.986716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:46.603752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.822715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:54:46.603773Z","caller":"traceutil/trace.go:171","msg":"trace[1458965319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"433.921157ms","start":"2026-05-29T05:54:46.169845Z","end":"2026-05-29T05:54:46.603766Z","steps":["trace[1458965319] 'agreement among raft nodes before linearized reading' (duration: 433.856896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:46.603794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:46.16982Z","time spent":"433.970099ms","remote":"127.0.0.1:54952","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-29T05:54:46.60413Z","caller":"traceutil/trace.go:171","msg":"trace[922427957] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"638.561556ms","start":"2026-05-29T05:54:45.965515Z","end":"2026-05-29T05:54:46.604077Z","steps":["trace[922427957] 'process raft request' (duration: 583.609077ms)","trace[922427957] 'compare' (duration: 54.396005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:46.604289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:45.965491Z","time spent":"638.73739ms","remote":"127.0.0.1:55136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:47.270544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709841547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:54:48.130799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.830645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:54:48.130886Z","caller":"traceutil/trace.go:171","msg":"trace[1950283225] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"1.361421325s","start":"2026-05-29T05:54:46.769451Z","end":"2026-05-29T05:54:48.130872Z","steps":["trace[1950283225] 'read index received' (duration: 630.380846ms)","trace[1950283225] 'applied index is now lower than readState.Index' (duration: 731.039709ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:54:48.130953Z","caller":"traceutil/trace.go:171","msg":"trace[149102997] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.50930208s","start":"2026-05-29T05:54:46.621644Z","end":"2026-05-29T05:54:48.130946Z","steps":["trace[149102997] 'process raft request' (duration: 778.25222ms)","trace[149102997] 'compare' (duration: 730.370394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:48.131168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:46.621621Z","time spent":"1.509531746s","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:48.131523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361902998s","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-29T05:54:48.1316Z","caller":"traceutil/trace.go:171","msg":"trace[78716406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.362038421s","start":"2026-05-29T05:54:46.769544Z","end":"2026-05-29T05:54:48.131583Z","steps":["trace[78716406] 'agreement among raft nodes before linearized reading' (duration: 1.361800386s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:48.131638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362158404s","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-29T05:54:48.131685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.736784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:54:48.131713Z","caller":"traceutil/trace.go:171","msg":"trace[2050935076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"961.797585ms","start":"2026-05-29T05:54:47.169907Z","end":"2026-05-29T05:54:48.131705Z","steps":["trace[2050935076] 'agreement among raft nodes before linearized reading' (duration: 961.755374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:48.131737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:47.169888Z","time spent":"961.844466ms","remote":"127.0.0.1:54952","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-29T05:54:48.131674Z","caller":"traceutil/trace.go:171","msg":"trace[1853501879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.362259966s","start":"2026-05-29T05:54:46.769405Z","end":"2026-05-29T05:54:48.131665Z","steps":["trace[1853501879] 'agreement among raft nodes before linearized reading' (duration: 1.361886507s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:48.131877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162807803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T05:54:48.131639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:46.769522Z","time spent":"1.362109484s","remote":"127.0.0.1:55164","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-29T05:54:48.131939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338270912s","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-29T05:54:48.131944Z","caller":"traceutil/trace.go:171","msg":"trace[532463360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"1.162919177s","start":"2026-05-29T05:54:46.969007Z","end":"2026-05-29T05:54:48.131926Z","steps":["trace[532463360] 'agreement among raft nodes before linearized reading' (duration: 1.162706251s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:48.132003Z","caller":"traceutil/trace.go:171","msg":"trace[699680446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.338360183s","start":"2026-05-29T05:54:46.793633Z","end":"2026-05-29T05:54:48.131993Z","steps":["trace[699680446] 'agreement among raft nodes before linearized reading' (duration: 1.338276181s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:48.132031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:46.79362Z","time spent":"1.338404245s","remote":"127.0.0.1:55164","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-29T05:54:48.132031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:46.968994Z","time spent":"1.163023439s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:54:48.131906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:46.769385Z","time spent":"1.362497263s","remote":"127.0.0.1:55164","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-29T05:55:04.207705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.830268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qk9kv\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-29T05:55:04.207791Z","caller":"traceutil/trace.go:171","msg":"trace[523253763] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qk9kv; range_end:; response_count:1; response_revision:799; }","duration":"139.928521ms","start":"2026-05-29T05:55:04.067839Z","end":"2026-05-29T05:55:04.207767Z","steps":["trace[523253763] 'range keys from in-memory index tree' (duration: 139.718186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.207686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.038024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:55:04.208104Z","caller":"traceutil/trace.go:171","msg":"trace[302537426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"474.520007ms","start":"2026-05-29T05:55:03.733559Z","end":"2026-05-29T05:55:04.208079Z","steps":["trace[302537426] 'range keys from in-memory index tree' (duration: 473.866061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.208405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:03.733525Z","time spent":"474.854375ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:55:04.755051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709841851,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:55:04.784011Z","caller":"traceutil/trace.go:171","msg":"trace[1939136786] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"571.720136ms","start":"2026-05-29T05:55:04.212228Z","end":"2026-05-29T05:55:04.783949Z","steps":["trace[1939136786] 'process raft request' (duration: 571.513701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.784153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:04.212216Z","time spent":"571.86333ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:55:04.979861Z","caller":"traceutil/trace.go:171","msg":"trace[1562484811] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"763.320837ms","start":"2026-05-29T05:55:04.216526Z","end":"2026-05-29T05:55:04.979846Z","steps":["trace[1562484811] 'process raft request' (duration: 763.142943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.979984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:04.216512Z","time spent":"763.40352ms","remote":"127.0.0.1:55098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:55:04.980073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.73411ms","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-29T05:55:04.980135Z","caller":"traceutil/trace.go:171","msg":"trace[1375119547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"725.860614ms","start":"2026-05-29T05:55:04.254259Z","end":"2026-05-29T05:55:04.980119Z","steps":["trace[1375119547] 'agreement among raft nodes before linearized reading' (duration: 725.646048ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:04.979857Z","caller":"traceutil/trace.go:171","msg":"trace[2107705143] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"725.494905ms","start":"2026-05-29T05:55:04.254335Z","end":"2026-05-29T05:55:04.97983Z","steps":["trace[2107705143] 'read index received' (duration: 530.03896ms)","trace[2107705143] 'applied index is now lower than readState.Index' (duration: 195.454805ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:04.980169Z","caller":"traceutil/trace.go:171","msg":"trace[10474613] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"290.534712ms","start":"2026-05-29T05:55:04.689598Z","end":"2026-05-29T05:55:04.980133Z","steps":["trace[10474613] 'process raft request' (duration: 290.186384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.980178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:04.254235Z","time spent":"725.932255ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:55:04.980361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.480915ms","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-29T05:55:04.980401Z","caller":"traceutil/trace.go:171","msg":"trace[751803284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"604.556397ms","start":"2026-05-29T05:55:04.375832Z","end":"2026-05-29T05:55:04.980388Z","steps":["trace[751803284] 'agreement among raft nodes before linearized reading' (duration: 604.444474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.980445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:04.375812Z","time spent":"604.620818ms","remote":"127.0.0.1:55164","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-29T05:55:04.980457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.416024ms","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-29T05:55:04.980544Z","caller":"traceutil/trace.go:171","msg":"trace[183584379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"682.559077ms","start":"2026-05-29T05:55:04.297961Z","end":"2026-05-29T05:55:04.98052Z","steps":["trace[183584379] 'agreement among raft nodes before linearized reading' (duration: 682.288981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.980623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:04.29794Z","time spent":"682.66743ms","remote":"127.0.0.1:55164","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-29T05:55:04.980632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.472779ms","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-29T05:55:04.980742Z","caller":"traceutil/trace.go:171","msg":"trace[1335103125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"605.629303ms","start":"2026-05-29T05:55:04.37508Z","end":"2026-05-29T05:55:04.980709Z","steps":["trace[1335103125] 'agreement among raft nodes before linearized reading' (duration: 605.279444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:04.980807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:04.375061Z","time spent":"605.734375ms","remote":"127.0.0.1:55164","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":"info","ts":"2026-05-29T05:55:24.999315Z","caller":"traceutil/trace.go:171","msg":"trace[2076757388] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"124.142108ms","start":"2026-05-29T05:55:24.875147Z","end":"2026-05-29T05:55:24.999289Z","steps":["trace[2076757388] 'process raft request' (duration: 123.995424ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:25.326286Z","caller":"traceutil/trace.go:171","msg":"trace[1335641795] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"142.280835ms","start":"2026-05-29T05:55:25.183954Z","end":"2026-05-29T05:55:25.326235Z","steps":["trace[1335641795] 'process raft request' (duration: 105.328102ms)","trace[1335641795] 'compare' (duration: 36.633885ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:25.326294Z","caller":"traceutil/trace.go:171","msg":"trace[1826436368] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"115.007877ms","start":"2026-05-29T05:55:25.211245Z","end":"2026-05-29T05:55:25.326253Z","steps":["trace[1826436368] 'process raft request' (duration: 114.906015ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:25.326487Z","caller":"traceutil/trace.go:171","msg":"trace[1474557009] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"142.223094ms","start":"2026-05-29T05:55:25.184126Z","end":"2026-05-29T05:55:25.326349Z","steps":["trace[1474557009] 'process raft request' (duration: 141.977837ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:33.597728Z","caller":"traceutil/trace.go:171","msg":"trace[856921241] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"180.270366ms","start":"2026-05-29T05:55:33.417435Z","end":"2026-05-29T05:55:33.597705Z","steps":["trace[856921241] 'read index received' (duration: 180.198514ms)","trace[856921241] 'applied index is now lower than readState.Index' (duration: 71.162µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:33.597853Z","caller":"traceutil/trace.go:171","msg":"trace[293618631] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"197.387037ms","start":"2026-05-29T05:55:33.400453Z","end":"2026-05-29T05:55:33.597841Z","steps":["trace[293618631] 'process raft request' (duration: 197.11989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:33.598055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.616826ms","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-29T05:55:33.598091Z","caller":"traceutil/trace.go:171","msg":"trace[2087566885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"180.665967ms","start":"2026-05-29T05:55:33.417415Z","end":"2026-05-29T05:55:33.598081Z","steps":["trace[2087566885] 'agreement among raft nodes before linearized reading' (duration: 180.544804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:33.59822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.930556ms","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-29T05:55:33.598246Z","caller":"traceutil/trace.go:171","msg":"trace[1976591066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"179.982789ms","start":"2026-05-29T05:55:33.418257Z","end":"2026-05-29T05:55:33.598239Z","steps":["trace[1976591066] 'agreement among raft nodes before linearized reading' (duration: 179.910467ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:39.53035Z","caller":"traceutil/trace.go:171","msg":"trace[1432613132] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"360.583472ms","start":"2026-05-29T05:55:39.169744Z","end":"2026-05-29T05:55:39.530327Z","steps":["trace[1432613132] 'read index received' (duration: 360.389407ms)","trace[1432613132] 'applied index is now lower than readState.Index' (duration: 193.195µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:39.530722Z","caller":"traceutil/trace.go:171","msg":"trace[1665264791] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"437.882108ms","start":"2026-05-29T05:55:39.092807Z","end":"2026-05-29T05:55:39.530689Z","steps":["trace[1665264791] 'process raft request' (duration: 437.387065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:39.530745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.076664ms","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-29T05:55:39.530784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.689277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:39.530834Z","caller":"traceutil/trace.go:171","msg":"trace[82270485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"274.215248ms","start":"2026-05-29T05:55:39.256599Z","end":"2026-05-29T05:55:39.530814Z","steps":["trace[82270485] 'agreement among raft nodes before linearized reading' (duration: 273.982592ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:39.530834Z","caller":"traceutil/trace.go:171","msg":"trace[1674966423] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1017; }","duration":"128.774569ms","start":"2026-05-29T05:55:39.402044Z","end":"2026-05-29T05:55:39.530819Z","steps":["trace[1674966423] 'agreement among raft nodes before linearized reading' (duration: 128.582654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:39.530893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:39.092777Z","time spent":"438.02579ms","remote":"127.0.0.1:55164","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-29T05:55:39.531162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.737112ms","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-29T05:55:39.531196Z","caller":"traceutil/trace.go:171","msg":"trace[1732256330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"209.810783ms","start":"2026-05-29T05:55:39.321374Z","end":"2026-05-29T05:55:39.531185Z","steps":["trace[1732256330] 'agreement among raft nodes before linearized reading' (duration: 209.273188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:39.530733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.976552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:39.531276Z","caller":"traceutil/trace.go:171","msg":"trace[495328486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"361.560098ms","start":"2026-05-29T05:55:39.169704Z","end":"2026-05-29T05:55:39.531264Z","steps":["trace[495328486] 'agreement among raft nodes before linearized reading' (duration: 360.89937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:39.531331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:39.169686Z","time spent":"361.63392ms","remote":"127.0.0.1:54952","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-29T05:55:39.832499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.496634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:55:39.832698Z","caller":"traceutil/trace.go:171","msg":"trace[364324964] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1056; }","duration":"190.791282ms","start":"2026-05-29T05:55:39.64189Z","end":"2026-05-29T05:55:39.832682Z","steps":["trace[364324964] 'read index received' (duration: 114.459343ms)","trace[364324964] 'applied index is now lower than readState.Index' (duration: 76.331049ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:39.832687Z","caller":"traceutil/trace.go:171","msg":"trace[930447496] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"297.990674ms","start":"2026-05-29T05:55:39.534672Z","end":"2026-05-29T05:55:39.832662Z","steps":["trace[930447496] 'process raft request' (duration: 107.251023ms)","trace[930447496] 'compare' (duration: 190.393392ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:39.832767Z","caller":"traceutil/trace.go:171","msg":"trace[1061458702] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"199.05449ms","start":"2026-05-29T05:55:39.633681Z","end":"2026-05-29T05:55:39.832735Z","steps":["trace[1061458702] 'process raft request' (duration: 198.959667ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:39.832776Z","caller":"traceutil/trace.go:171","msg":"trace[97998065] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"297.729927ms","start":"2026-05-29T05:55:39.534988Z","end":"2026-05-29T05:55:39.832718Z","steps":["trace[97998065] 'process raft request' (duration: 297.605854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:39.832878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.279889ms","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-29T05:55:39.83291Z","caller":"traceutil/trace.go:171","msg":"trace[1867553045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"190.30943ms","start":"2026-05-29T05:55:39.642589Z","end":"2026-05-29T05:55:39.832898Z","steps":["trace[1867553045] 'agreement among raft nodes before linearized reading' (duration: 190.188527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:39.832907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.996947ms","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-29T05:55:39.83296Z","caller":"traceutil/trace.go:171","msg":"trace[871222590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"191.08955ms","start":"2026-05-29T05:55:39.641855Z","end":"2026-05-29T05:55:39.832944Z","steps":["trace[871222590] 'agreement among raft nodes before linearized reading' (duration: 190.892805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.01449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509648612s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:55:42.014698Z","caller":"traceutil/trace.go:171","msg":"trace[389657167] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1059; }","duration":"846.241237ms","start":"2026-05-29T05:55:41.168442Z","end":"2026-05-29T05:55:42.014683Z","steps":["trace[389657167] 'read index received' (duration: 275.015069ms)","trace[389657167] 'applied index is now lower than readState.Index' (duration: 571.225248ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:42.014882Z","caller":"traceutil/trace.go:171","msg":"trace[1571977273] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"2.176564869s","start":"2026-05-29T05:55:39.838304Z","end":"2026-05-29T05:55:42.014869Z","steps":["trace[1571977273] 'process raft request' (duration: 666.450955ms)","trace[1571977273] 'compare' (duration: 1.509432286s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:55:42.014939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:39.838279Z","time spent":"2.17662925s","remote":"127.0.0.1:55164","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-29T05:55:42.015119Z","caller":"traceutil/trace.go:171","msg":"trace[926160657] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"2.17664439s","start":"2026-05-29T05:55:39.838465Z","end":"2026-05-29T05:55:42.015109Z","steps":["trace[926160657] 'process raft request' (duration: 2.176115887s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.015168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:39.838433Z","time spent":"2.176708482s","remote":"127.0.0.1:55164","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-29T05:55:42.015784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.302266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:42.015822Z","caller":"traceutil/trace.go:171","msg":"trace[653290936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"847.398919ms","start":"2026-05-29T05:55:41.168411Z","end":"2026-05-29T05:55:42.015809Z","steps":["trace[653290936] 'agreement among raft nodes before linearized reading' (duration: 846.857994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.01585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:41.168396Z","time spent":"847.448369ms","remote":"127.0.0.1:54952","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-29T05:55:42.515833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709842317,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T05:55:42.895537Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057636253s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T05:55:42.895836Z","caller":"traceutil/trace.go:171","msg":"trace[600334287] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"881.085605ms","start":"2026-05-29T05:55:42.014732Z","end":"2026-05-29T05:55:42.895818Z","steps":["trace[600334287] 'read index received' (duration: 880.916041ms)","trace[600334287] 'applied index is now lower than readState.Index' (duration: 168.934µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:55:42.895954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404839594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:55:42.895998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059393049s","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-29T05:55:42.896026Z","caller":"traceutil/trace.go:171","msg":"trace[1335013671] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1023; }","duration":"1.404964177s","start":"2026-05-29T05:55:41.491041Z","end":"2026-05-29T05:55:42.896005Z","steps":["trace[1335013671] 'agreement among raft nodes before linearized reading' (duration: 1.404860024s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:42.89608Z","caller":"traceutil/trace.go:171","msg":"trace[425080307] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.058316721s","start":"2026-05-29T05:55:41.837756Z","end":"2026-05-29T05:55:42.896072Z","steps":["trace[425080307] 'process raft request' (duration: 1.057941991s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.896051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361210815s","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-29T05:55:42.896178Z","caller":"traceutil/trace.go:171","msg":"trace[1809699417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"1.361375439s","start":"2026-05-29T05:55:41.534784Z","end":"2026-05-29T05:55:42.89616Z","steps":["trace[1809699417] 'agreement among raft nodes before linearized reading' (duration: 1.361105362s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.896185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:41.491021Z","time spent":"1.405082369s","remote":"127.0.0.1:37692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:55:42.896202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:41.837726Z","time spent":"1.058376982s","remote":"127.0.0.1:55164","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-29T05:55:42.896241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:41.534761Z","time spent":"1.361465441s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T05:55:42.896033Z","caller":"traceutil/trace.go:171","msg":"trace[1029615537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"1.059429159s","start":"2026-05-29T05:55:41.836594Z","end":"2026-05-29T05:55:42.896023Z","steps":["trace[1029615537] 'agreement among raft nodes before linearized reading' (duration: 1.059261635s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.896338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:41.836572Z","time spent":"1.059733728s","remote":"127.0.0.1:55164","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-29T05:55:42.896528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059777949s","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-29T05:55:42.896605Z","caller":"traceutil/trace.go:171","msg":"trace[476583090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"1.059892662s","start":"2026-05-29T05:55:41.836693Z","end":"2026-05-29T05:55:42.896586Z","steps":["trace[476583090] 'agreement among raft nodes before linearized reading' (duration: 1.059143023s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.896609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.74917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:55:42.896657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:41.836688Z","time spent":"1.059957384s","remote":"127.0.0.1:55164","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-29T05:55:42.896663Z","caller":"traceutil/trace.go:171","msg":"trace[457617631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"879.815572ms","start":"2026-05-29T05:55:42.016831Z","end":"2026-05-29T05:55:42.896647Z","steps":["trace[457617631] 'agreement among raft nodes before linearized reading' (duration: 879.680558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:42.896697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:42.016772Z","time spent":"879.917984ms","remote":"127.0.0.1:54944","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-29T05:55:44.210732Z","caller":"traceutil/trace.go:171","msg":"trace[753992953] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"187.813704ms","start":"2026-05-29T05:55:44.022866Z","end":"2026-05-29T05:55:44.21068Z","steps":["trace[753992953] 'process raft request' (duration: 187.65565ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:46.238854Z","caller":"traceutil/trace.go:171","msg":"trace[787168723] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"152.524235ms","start":"2026-05-29T05:55:46.086305Z","end":"2026-05-29T05:55:46.238829Z","steps":["trace[787168723] 'process raft request' (duration: 152.311299ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:46.263823Z","caller":"traceutil/trace.go:171","msg":"trace[1917293680] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"174.497093ms","start":"2026-05-29T05:55:46.089296Z","end":"2026-05-29T05:55:46.263793Z","steps":["trace[1917293680] 'process raft request' (duration: 154.911978ms)","trace[1917293680] 'compare' (duration: 19.414391ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:55:48.655053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.110378ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:55:52.526276Z","caller":"traceutil/trace.go:171","msg":"trace[1615329753] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"205.631293ms","start":"2026-05-29T05:55:52.320627Z","end":"2026-05-29T05:55:52.526259Z","steps":["trace[1615329753] 'read index received' (duration: 205.452258ms)","trace[1615329753] 'applied index is now lower than readState.Index' (duration: 178.475µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:55:52.526421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.776257ms","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-29T05:55:52.52639Z","caller":"traceutil/trace.go:171","msg":"trace[689938665] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"240.331896ms","start":"2026-05-29T05:55:52.286026Z","end":"2026-05-29T05:55:52.526358Z","steps":["trace[689938665] 'process raft request' (duration: 240.029748ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:52.526443Z","caller":"traceutil/trace.go:171","msg":"trace[315942517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"205.837908ms","start":"2026-05-29T05:55:52.320598Z","end":"2026-05-29T05:55:52.526435Z","steps":["trace[315942517] 'agreement among raft nodes before linearized reading' (duration: 205.723745ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:52.961036Z","caller":"traceutil/trace.go:171","msg":"trace[2136907316] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"430.561734ms","start":"2026-05-29T05:55:52.530458Z","end":"2026-05-29T05:55:52.961019Z","steps":["trace[2136907316] 'process raft request' (duration: 427.243926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:52.961134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:52.530437Z","time spent":"430.646737ms","remote":"127.0.0.1:55164","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-29T05:56:04.833773Z","caller":"traceutil/trace.go:171","msg":"trace[1181000698] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"203.375554ms","start":"2026-05-29T05:56:04.630372Z","end":"2026-05-29T05:56:04.833748Z","steps":["trace[1181000698] 'process raft request' (duration: 203.20687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.752507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.101242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:56:05.752598Z","caller":"traceutil/trace.go:171","msg":"trace[1779072556] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1094; }","duration":"754.256507ms","start":"2026-05-29T05:56:04.998312Z","end":"2026-05-29T05:56:05.752568Z","steps":["trace[1779072556] 'count revisions from in-memory index tree' (duration: 753.970369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.752578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.299891ms","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-29T05:56:05.752641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:04.998292Z","time spent":"754.338698ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:56:05.752672Z","caller":"traceutil/trace.go:171","msg":"trace[316155348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"677.436585ms","start":"2026-05-29T05:56:05.075207Z","end":"2026-05-29T05:56:05.752643Z","steps":["trace[316155348] 'range keys from in-memory index tree' (duration: 677.072174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.752681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.314931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-29T05:56:05.752731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.075186Z","time spent":"677.527766ms","remote":"127.0.0.1:55164","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-29T05:56:05.752791Z","caller":"traceutil/trace.go:171","msg":"trace[1796528243] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"642.015551ms","start":"2026-05-29T05:56:05.110759Z","end":"2026-05-29T05:56:05.752774Z","steps":["trace[1796528243] 'read index received' (duration: 443.532715ms)","trace[1796528243] 'applied index is now lower than readState.Index' (duration: 198.481496ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:05.752916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.162615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:05.752881Z","caller":"traceutil/trace.go:171","msg":"trace[959696986] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"668.393296ms","start":"2026-05-29T05:56:05.084452Z","end":"2026-05-29T05:56:05.752846Z","steps":["trace[959696986] 'process raft request' (duration: 469.802408ms)","trace[959696986] 'compare' (duration: 197.882149ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:05.752949Z","caller":"traceutil/trace.go:171","msg":"trace[1224184518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"642.195186ms","start":"2026-05-29T05:56:05.110745Z","end":"2026-05-29T05:56:05.75294Z","steps":["trace[1224184518] 'agreement among raft nodes before linearized reading' (duration: 642.133325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.752981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.110684Z","time spent":"642.289798ms","remote":"127.0.0.1:54952","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-29T05:56:05.752988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.65157ms","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-29T05:56:05.753027Z","caller":"traceutil/trace.go:171","msg":"trace[1112793887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"636.690781ms","start":"2026-05-29T05:56:05.116324Z","end":"2026-05-29T05:56:05.753015Z","steps":["trace[1112793887] 'agreement among raft nodes before linearized reading' (duration: 636.561328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.753033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.084428Z","time spent":"668.52881ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:05.753069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.11629Z","time spent":"636.771813ms","remote":"127.0.0.1:55164","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-29T05:56:05.753068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.025891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:05.753161Z","caller":"traceutil/trace.go:171","msg":"trace[941813806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"585.145354ms","start":"2026-05-29T05:56:05.167994Z","end":"2026-05-29T05:56:05.75314Z","steps":["trace[941813806] 'agreement among raft nodes before linearized reading' (duration: 585.029171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.753201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.188887ms","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-29T05:56:05.753212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.167947Z","time spent":"585.253508ms","remote":"127.0.0.1:54944","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-29T05:56:05.753226Z","caller":"traceutil/trace.go:171","msg":"trace[1995272667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"416.246978ms","start":"2026-05-29T05:56:05.336971Z","end":"2026-05-29T05:56:05.753218Z","steps":["trace[1995272667] 'agreement among raft nodes before linearized reading' (duration: 416.172227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.753252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.336949Z","time spent":"416.29678ms","remote":"127.0.0.1:55164","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-29T05:56:05.753469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.190523ms","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-29T05:56:05.753527Z","caller":"traceutil/trace.go:171","msg":"trace[663543322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"417.284775ms","start":"2026-05-29T05:56:05.336227Z","end":"2026-05-29T05:56:05.753512Z","steps":["trace[663543322] 'agreement among raft nodes before linearized reading' (duration: 416.70238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:05.753853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.336208Z","time spent":"417.603104ms","remote":"127.0.0.1:55164","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-29T05:56:06.47009Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709842521,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:56:06.60501Z","caller":"traceutil/trace.go:171","msg":"trace[320115471] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"847.187333ms","start":"2026-05-29T05:56:05.757797Z","end":"2026-05-29T05:56:06.604985Z","steps":["trace[320115471] 'process raft request' (duration: 817.81521ms)","trace[320115471] 'compare' (duration: 28.799848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:06.605214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.757776Z","time spent":"847.369647ms","remote":"127.0.0.1:55164","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-29T05:56:07.02506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.627561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:07.025473Z","caller":"traceutil/trace.go:171","msg":"trace[1133573858] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"1.267281481s","start":"2026-05-29T05:56:05.758171Z","end":"2026-05-29T05:56:07.025452Z","steps":["trace[1133573858] 'process raft request' (duration: 1.267055815s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:07.025525Z","caller":"traceutil/trace.go:171","msg":"trace[6854245] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.26684155s","start":"2026-05-29T05:56:05.758675Z","end":"2026-05-29T05:56:07.025516Z","steps":["trace[6854245] 'process raft request' (duration: 1.266697246s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:07.025588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.758153Z","time spent":"1.267373794s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:07.025661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.758662Z","time spent":"1.266930622s","remote":"127.0.0.1:55164","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-29T05:56:07.025485Z","caller":"traceutil/trace.go:171","msg":"trace[1313810336] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"180.692937ms","start":"2026-05-29T05:56:06.844774Z","end":"2026-05-29T05:56:07.025467Z","steps":["trace[1313810336] 'process raft request' (duration: 180.642085ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:07.025866Z","caller":"traceutil/trace.go:171","msg":"trace[1821726318] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1139; }","duration":"1.056077162s","start":"2026-05-29T05:56:05.969594Z","end":"2026-05-29T05:56:07.025671Z","steps":["trace[1821726318] 'read index received' (duration: 606.107297ms)","trace[1821726318] 'applied index is now lower than readState.Index' (duration: 449.967975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:07.025938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056339289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:07.026022Z","caller":"traceutil/trace.go:171","msg":"trace[1446067665] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1100; }","duration":"1.056451332s","start":"2026-05-29T05:56:05.969562Z","end":"2026-05-29T05:56:07.026013Z","steps":["trace[1446067665] 'agreement among raft nodes before linearized reading' (duration: 1.056348569s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:07.026047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.969542Z","time spent":"1.056497803s","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:07.026198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.807472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T05:56:07.026325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.154913ms","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-29T05:56:07.026352Z","caller":"traceutil/trace.go:171","msg":"trace[1107698844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"188.219774ms","start":"2026-05-29T05:56:06.838127Z","end":"2026-05-29T05:56:07.026346Z","steps":["trace[1107698844] 'agreement among raft nodes before linearized reading' (duration: 188.108442ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:07.02634Z","caller":"traceutil/trace.go:171","msg":"trace[77063241] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1100; }","duration":"985.998077ms","start":"2026-05-29T05:56:06.040328Z","end":"2026-05-29T05:56:07.026326Z","steps":["trace[77063241] 'agreement among raft nodes before linearized reading' (duration: 985.814692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:07.026457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.283561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:07.026525Z","caller":"traceutil/trace.go:171","msg":"trace[1256658073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"856.373435ms","start":"2026-05-29T05:56:06.170139Z","end":"2026-05-29T05:56:07.026513Z","steps":["trace[1256658073] 'agreement among raft nodes before linearized reading' (duration: 856.286242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:07.026589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:06.170121Z","time spent":"856.458857ms","remote":"127.0.0.1:54952","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-29T05:56:07.02622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.501366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:07.026817Z","caller":"traceutil/trace.go:171","msg":"trace[2021534582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1100; }","duration":"109.127912ms","start":"2026-05-29T05:56:06.917677Z","end":"2026-05-29T05:56:07.026805Z","steps":["trace[2021534582] 'agreement among raft nodes before linearized reading' (duration: 108.503535ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:07.026913Z","caller":"traceutil/trace.go:171","msg":"trace[8412179] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"1.26874863s","start":"2026-05-29T05:56:05.758133Z","end":"2026-05-29T05:56:07.026882Z","steps":["trace[8412179] 'process raft request' (duration: 1.115200828s)","trace[8412179] 'compare' (duration: 150.953164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:07.027004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:05.758113Z","time spent":"1.268849843s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:07.026496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:06.040304Z","time spent":"986.178542ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:07.391288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.201439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:07.391461Z","caller":"traceutil/trace.go:171","msg":"trace[1533787175] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"220.759461ms","start":"2026-05-29T05:56:07.170678Z","end":"2026-05-29T05:56:07.391437Z","steps":["trace[1533787175] 'read index received' (duration: 66.252404ms)","trace[1533787175] 'applied index is now lower than readState.Index' (duration: 154.505147ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:07.391622Z","caller":"traceutil/trace.go:171","msg":"trace[117842106] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"360.636234ms","start":"2026-05-29T05:56:07.030967Z","end":"2026-05-29T05:56:07.391603Z","steps":["trace[117842106] 'process raft request' (duration: 206.054114ms)","trace[117842106] 'compare' (duration: 154.064436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:07.391724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:07.030951Z","time spent":"360.721396ms","remote":"127.0.0.1:55164","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-29T05:56:07.392052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.367968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:07.392145Z","caller":"traceutil/trace.go:171","msg":"trace[1283635686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"221.507611ms","start":"2026-05-29T05:56:07.170613Z","end":"2026-05-29T05:56:07.39212Z","steps":["trace[1283635686] 'agreement among raft nodes before linearized reading' (duration: 221.349097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:08.671162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709842532,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:56:08.953264Z","caller":"traceutil/trace.go:171","msg":"trace[1941989141] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"875.178939ms","start":"2026-05-29T05:56:08.078053Z","end":"2026-05-29T05:56:08.953232Z","steps":["trace[1941989141] 'process raft request' (duration: 874.977154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:08.953464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:08.078031Z","time spent":"875.324623ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:09.268235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.249596ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T05:56:09.268363Z","caller":"traceutil/trace.go:171","msg":"trace[673146268] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"1.097610796s","start":"2026-05-29T05:56:08.170724Z","end":"2026-05-29T05:56:09.268335Z","steps":["trace[673146268] 'read index received' (duration: 782.336886ms)","trace[673146268] 'applied index is now lower than readState.Index' (duration: 315.27175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:09.268508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09777152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:09.268626Z","caller":"traceutil/trace.go:171","msg":"trace[1518770421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"1.097932184s","start":"2026-05-29T05:56:08.170675Z","end":"2026-05-29T05:56:09.268607Z","steps":["trace[1518770421] 'agreement among raft nodes before linearized reading' (duration: 1.0977738s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:09.268674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:08.170653Z","time spent":"1.098008206s","remote":"127.0.0.1:54952","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-29T05:56:09.268806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.799992ms","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-29T05:56:09.268909Z","caller":"traceutil/trace.go:171","msg":"trace[619537828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"658.938786ms","start":"2026-05-29T05:56:08.609953Z","end":"2026-05-29T05:56:09.268892Z","steps":["trace[619537828] 'agreement among raft nodes before linearized reading' (duration: 658.615968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:09.269001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:08.60993Z","time spent":"659.05485ms","remote":"127.0.0.1:55164","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-29T05:56:09.26907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.208341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T05:56:09.269111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.091137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T05:56:09.269125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.31717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:56:09.269178Z","caller":"traceutil/trace.go:171","msg":"trace[290814545] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1103; }","duration":"110.359825ms","start":"2026-05-29T05:56:09.158785Z","end":"2026-05-29T05:56:09.269145Z","steps":["trace[290814545] 'agreement among raft nodes before linearized reading' (duration: 110.17074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:09.269083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.445807ms","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-29T05:56:09.269198Z","caller":"traceutil/trace.go:171","msg":"trace[899796232] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1103; }","duration":"380.251601ms","start":"2026-05-29T05:56:08.888924Z","end":"2026-05-29T05:56:09.269176Z","steps":["trace[899796232] 'agreement among raft nodes before linearized reading' (duration: 380.086426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:09.269204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.203461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:56:09.269198Z","caller":"traceutil/trace.go:171","msg":"trace[1743318617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"239.433483ms","start":"2026-05-29T05:56:09.02975Z","end":"2026-05-29T05:56:09.269183Z","steps":["trace[1743318617] 'agreement among raft nodes before linearized reading' (duration: 239.176986ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:09.269257Z","caller":"traceutil/trace.go:171","msg":"trace[1081688020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"238.278493ms","start":"2026-05-29T05:56:09.030965Z","end":"2026-05-29T05:56:09.269243Z","steps":["trace[1081688020] 'agreement among raft nodes before linearized reading' (duration: 238.13746ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:09.269255Z","caller":"traceutil/trace.go:171","msg":"trace[1028686273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"238.672223ms","start":"2026-05-29T05:56:09.030564Z","end":"2026-05-29T05:56:09.269236Z","steps":["trace[1028686273] 'agreement among raft nodes before linearized reading' (duration: 238.316624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:09.269261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:08.8889Z","time spent":"380.346863ms","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:09.904542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.259531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:09.904694Z","caller":"traceutil/trace.go:171","msg":"trace[757671094] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1108; }","duration":"369.496268ms","start":"2026-05-29T05:56:09.53518Z","end":"2026-05-29T05:56:09.904676Z","steps":["trace[757671094] 'count revisions from in-memory index tree' (duration: 369.033306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:09.904744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:09.535099Z","time spent":"369.632091ms","remote":"127.0.0.1:55126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:56:09.974518Z","caller":"traceutil/trace.go:171","msg":"trace[365494701] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"168.747642ms","start":"2026-05-29T05:56:09.80574Z","end":"2026-05-29T05:56:09.974487Z","steps":["trace[365494701] 'process raft request' (duration: 168.587618ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:12.086311Z","caller":"traceutil/trace.go:171","msg":"trace[1125449650] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"106.149254ms","start":"2026-05-29T05:56:11.980131Z","end":"2026-05-29T05:56:12.086281Z","steps":["trace[1125449650] 'process raft request' (duration: 106.005691ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:26.511065Z","caller":"traceutil/trace.go:171","msg":"trace[1598292698] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"284.510214ms","start":"2026-05-29T05:56:26.226527Z","end":"2026-05-29T05:56:26.511038Z","steps":["trace[1598292698] 'process raft request' (duration: 284.339599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:27.967164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709842788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:56:28.290659Z","caller":"traceutil/trace.go:171","msg":"trace[1224390807] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"824.169689ms","start":"2026-05-29T05:56:27.466472Z","end":"2026-05-29T05:56:28.290642Z","steps":["trace[1224390807] 'read index received' (duration: 824.042356ms)","trace[1224390807] 'applied index is now lower than readState.Index' (duration: 126.853µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:28.290762Z","caller":"traceutil/trace.go:171","msg":"trace[1239508538] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"872.616819ms","start":"2026-05-29T05:56:27.418139Z","end":"2026-05-29T05:56:28.290755Z","steps":["trace[1239508538] 'process raft request' (duration: 872.410664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.290835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.41811Z","time spent":"872.67129ms","remote":"127.0.0.1:55164","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-29T05:56:28.291068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.426239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:28.291095Z","caller":"traceutil/trace.go:171","msg":"trace[1728403878] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1213; }","duration":"471.45717ms","start":"2026-05-29T05:56:27.819631Z","end":"2026-05-29T05:56:28.291088Z","steps":["trace[1728403878] 'agreement among raft nodes before linearized reading' (duration: 471.409029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.29113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.819566Z","time spent":"471.559503ms","remote":"127.0.0.1:37634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:28.291154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.781715ms","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-29T05:56:28.291209Z","caller":"traceutil/trace.go:171","msg":"trace[2101069267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"640.862677ms","start":"2026-05-29T05:56:27.650326Z","end":"2026-05-29T05:56:28.291188Z","steps":["trace[2101069267] 'agreement among raft nodes before linearized reading' (duration: 640.760815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.29125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.650307Z","time spent":"640.935149ms","remote":"127.0.0.1:55164","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-29T05:56:28.291262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.796106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:56:28.291279Z","caller":"traceutil/trace.go:171","msg":"trace[1216498383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"824.841587ms","start":"2026-05-29T05:56:27.466433Z","end":"2026-05-29T05:56:28.291274Z","steps":["trace[1216498383] 'agreement among raft nodes before linearized reading' (duration: 824.770195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.291291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.466418Z","time spent":"824.869998ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:56:28.291375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.93745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:28.291387Z","caller":"traceutil/trace.go:171","msg":"trace[275614058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"122.983411ms","start":"2026-05-29T05:56:28.1684Z","end":"2026-05-29T05:56:28.291383Z","steps":["trace[275614058] 'agreement among raft nodes before linearized reading' (duration: 122.962531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.291391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.133364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:56:28.291425Z","caller":"traceutil/trace.go:171","msg":"trace[1312614840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"712.200986ms","start":"2026-05-29T05:56:27.579212Z","end":"2026-05-29T05:56:28.291413Z","steps":["trace[1312614840] 'agreement among raft nodes before linearized reading' (duration: 712.121234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.291464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.579185Z","time spent":"712.271268ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:56:28.291042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.40952ms","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-29T05:56:28.291597Z","caller":"traceutil/trace.go:171","msg":"trace[986044981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"795.000985ms","start":"2026-05-29T05:56:27.49659Z","end":"2026-05-29T05:56:28.291591Z","steps":["trace[986044981] 'agreement among raft nodes before linearized reading' (duration: 794.335238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.291614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.496579Z","time spent":"795.029106ms","remote":"127.0.0.1:55164","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-29T05:56:28.291673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.324856ms","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-29T05:56:28.291685Z","caller":"traceutil/trace.go:171","msg":"trace[118522276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"713.372507ms","start":"2026-05-29T05:56:27.578308Z","end":"2026-05-29T05:56:28.291681Z","steps":["trace[118522276] 'agreement among raft nodes before linearized reading' (duration: 713.335406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.291696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:27.578287Z","time spent":"713.405737ms","remote":"127.0.0.1:55164","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-29T05:56:34.476106Z","caller":"traceutil/trace.go:171","msg":"trace[213641734] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"306.718615ms","start":"2026-05-29T05:56:34.169364Z","end":"2026-05-29T05:56:34.476083Z","steps":["trace[213641734] 'read index received' (duration: 306.352725ms)","trace[213641734] 'applied index is now lower than readState.Index' (duration: 364.72µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:34.476201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:33.677753Z","time spent":"798.441187ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-29T05:56:34.476252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.866278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:34.47629Z","caller":"traceutil/trace.go:171","msg":"trace[1721496321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"306.954891ms","start":"2026-05-29T05:56:34.169323Z","end":"2026-05-29T05:56:34.476278Z","steps":["trace[1721496321] 'agreement among raft nodes before linearized reading' (duration: 306.870549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:34.47632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:34.169304Z","time spent":"307.008172ms","remote":"127.0.0.1:54952","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-29T05:56:34.476426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.368385ms","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-29T05:56:34.476498Z","caller":"traceutil/trace.go:171","msg":"trace[480194104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"159.484708ms","start":"2026-05-29T05:56:34.317Z","end":"2026-05-29T05:56:34.476485Z","steps":["trace[480194104] 'agreement among raft nodes before linearized reading' (duration: 159.272843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:34.476544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.324168ms","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-29T05:56:34.476585Z","caller":"traceutil/trace.go:171","msg":"trace[1302823585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"155.37488ms","start":"2026-05-29T05:56:34.321198Z","end":"2026-05-29T05:56:34.476573Z","steps":["trace[1302823585] 'agreement among raft nodes before linearized reading' (duration: 155.211596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:34.476714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.444412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T05:56:34.476726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.664183ms","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-29T05:56:34.476742Z","caller":"traceutil/trace.go:171","msg":"trace[656731482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"155.479272ms","start":"2026-05-29T05:56:34.321254Z","end":"2026-05-29T05:56:34.476734Z","steps":["trace[656731482] 'agreement among raft nodes before linearized reading' (duration: 155.406211ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:34.476753Z","caller":"traceutil/trace.go:171","msg":"trace[497281000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"159.701544ms","start":"2026-05-29T05:56:34.317044Z","end":"2026-05-29T05:56:34.476745Z","steps":["trace[497281000] 'agreement among raft nodes before linearized reading' (duration: 159.628222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:34.476858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.82391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:56:34.477195Z","caller":"traceutil/trace.go:171","msg":"trace[275583734] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"159.169429ms","start":"2026-05-29T05:56:34.317999Z","end":"2026-05-29T05:56:34.477168Z","steps":["trace[275583734] 'agreement among raft nodes before linearized reading' (duration: 158.710667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:44.928125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.309491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:44.928234Z","caller":"traceutil/trace.go:171","msg":"trace[1160174500] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"223.554517ms","start":"2026-05-29T05:56:44.70466Z","end":"2026-05-29T05:56:44.928214Z","steps":["trace[1160174500] 'process raft request' (duration: 66.080042ms)","trace[1160174500] 'compare' (duration: 157.016873ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:47.009161Z","caller":"traceutil/trace.go:171","msg":"trace[1639227999] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"442.519902ms","start":"2026-05-29T05:56:46.566616Z","end":"2026-05-29T05:56:47.009136Z","steps":["trace[1639227999] 'read index received' (duration: 442.368798ms)","trace[1639227999] 'applied index is now lower than readState.Index' (duration: 149.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:47.009197Z","caller":"traceutil/trace.go:171","msg":"trace[2143688200] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"455.239956ms","start":"2026-05-29T05:56:46.553925Z","end":"2026-05-29T05:56:47.009165Z","steps":["trace[2143688200] 'process raft request' (duration: 455.082132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.009355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:46.553901Z","time spent":"455.37449ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:56:47.009417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.7852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:56:47.009496Z","caller":"traceutil/trace.go:171","msg":"trace[335191170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"442.915193ms","start":"2026-05-29T05:56:46.566564Z","end":"2026-05-29T05:56:47.009479Z","steps":["trace[335191170] 'agreement among raft nodes before linearized reading' (duration: 442.679067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.009537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:46.566543Z","time spent":"442.984954ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:56:47.066425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.058239ms","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-29T05:56:47.066507Z","caller":"traceutil/trace.go:171","msg":"trace[460571027] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1282; }","duration":"219.190442ms","start":"2026-05-29T05:56:46.847298Z","end":"2026-05-29T05:56:47.066489Z","steps":["trace[460571027] 'agreement among raft nodes before linearized reading' (duration: 219.058469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.066556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.701025ms","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-29T05:56:47.066547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.759007ms","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-29T05:56:47.066593Z","caller":"traceutil/trace.go:171","msg":"trace[526690878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"437.792579ms","start":"2026-05-29T05:56:46.62879Z","end":"2026-05-29T05:56:47.066583Z","steps":["trace[526690878] 'agreement among raft nodes before linearized reading' (duration: 437.643275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.066616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:46.628761Z","time spent":"437.849849ms","remote":"127.0.0.1:55164","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-29T05:56:47.066623Z","caller":"traceutil/trace.go:171","msg":"trace[627233610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"437.893241ms","start":"2026-05-29T05:56:46.628712Z","end":"2026-05-29T05:56:47.066605Z","steps":["trace[627233610] 'agreement among raft nodes before linearized reading' (duration: 437.747447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.066666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:46.628696Z","time spent":"437.960823ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T05:56:47.066712Z","caller":"traceutil/trace.go:171","msg":"trace[934633929] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"132.962624ms","start":"2026-05-29T05:56:46.933736Z","end":"2026-05-29T05:56:47.066699Z","steps":["trace[934633929] 'process raft request' (duration: 132.395968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.066836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.981404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T05:56:47.066961Z","caller":"traceutil/trace.go:171","msg":"trace[1105982843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"438.130287ms","start":"2026-05-29T05:56:46.6288Z","end":"2026-05-29T05:56:47.06693Z","steps":["trace[1105982843] 'agreement among raft nodes before linearized reading' (duration: 437.613023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.067021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:46.628796Z","time spent":"438.21344ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:56:47.06705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.889813ms","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-29T05:56:47.067089Z","caller":"traceutil/trace.go:171","msg":"trace[2041333764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"481.958525ms","start":"2026-05-29T05:56:46.585119Z","end":"2026-05-29T05:56:47.067078Z","steps":["trace[2041333764] 'agreement among raft nodes before linearized reading' (duration: 481.192045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:47.067125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:46.585105Z","time spent":"482.013537ms","remote":"127.0.0.1:55164","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":"info","ts":"2026-05-29T05:56:47.13932Z","caller":"traceutil/trace.go:171","msg":"trace[1416918842] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"125.931788ms","start":"2026-05-29T05:56:47.013366Z","end":"2026-05-29T05:56:47.139297Z","steps":["trace[1416918842] 'process raft request' (duration: 69.761087ms)","trace[1416918842] 'compare' (duration: 56.071409ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:47.170931Z","caller":"traceutil/trace.go:171","msg":"trace[831549673] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"100.211175ms","start":"2026-05-29T05:56:47.070696Z","end":"2026-05-29T05:56:47.170907Z","steps":["trace[831549673] 'process raft request' (duration: 100.075752ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:47.170928Z","caller":"traceutil/trace.go:171","msg":"trace[466101218] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"100.230426ms","start":"2026-05-29T05:56:47.070625Z","end":"2026-05-29T05:56:47.170855Z","steps":["trace[466101218] 'process raft request' (duration: 100.059251ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:47.17099Z","caller":"traceutil/trace.go:171","msg":"trace[839834346] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"100.238306ms","start":"2026-05-29T05:56:47.070726Z","end":"2026-05-29T05:56:47.170964Z","steps":["trace[839834346] 'process raft request' (duration: 100.067061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:48.499612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11554722007709842953,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:56:48.625614Z","caller":"traceutil/trace.go:171","msg":"trace[956809994] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"626.380059ms","start":"2026-05-29T05:56:47.999214Z","end":"2026-05-29T05:56:48.625594Z","steps":["trace[956809994] 'read index received' (duration: 626.208425ms)","trace[956809994] 'applied index is now lower than readState.Index' (duration: 170.974µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:48.625649Z","caller":"traceutil/trace.go:171","msg":"trace[1411516618] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"732.010656ms","start":"2026-05-29T05:56:47.89361Z","end":"2026-05-29T05:56:48.625621Z","steps":["trace[1411516618] 'process raft request' (duration: 731.859982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:48.625765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:47.893585Z","time spent":"732.118148ms","remote":"127.0.0.1:55042","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-29T05:56:48.625789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.77719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:48.625828Z","caller":"traceutil/trace.go:171","msg":"trace[55276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"455.851292ms","start":"2026-05-29T05:56:48.169965Z","end":"2026-05-29T05:56:48.625816Z","steps":["trace[55276] 'agreement among raft nodes before linearized reading' (duration: 455.77916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:48.625836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.617975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"warn","ts":"2026-05-29T05:56:48.625853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:48.169948Z","time spent":"455.898673ms","remote":"127.0.0.1:54952","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-29T05:56:48.625866Z","caller":"traceutil/trace.go:171","msg":"trace[954947950] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1289; }","duration":"626.782389ms","start":"2026-05-29T05:56:47.999075Z","end":"2026-05-29T05:56:48.625857Z","steps":["trace[954947950] 'agreement among raft nodes before linearized reading' (duration: 626.630965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:48.625898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:47.999049Z","time spent":"626.842211ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-29T05:56:49.270302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.166398ms","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-29T05:56:49.270444Z","caller":"traceutil/trace.go:171","msg":"trace[1576272637] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1289; }","duration":"538.318472ms","start":"2026-05-29T05:56:48.732102Z","end":"2026-05-29T05:56:49.270421Z","steps":["trace[1576272637] 'range keys from in-memory index tree' (duration: 538.149537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.270592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.797662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:49.270664Z","caller":"traceutil/trace.go:171","msg":"trace[245860359] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"642.985453ms","start":"2026-05-29T05:56:48.627665Z","end":"2026-05-29T05:56:49.270651Z","steps":["trace[245860359] 'read index received' (duration: 227.077058ms)","trace[245860359] 'applied index is now lower than readState.Index' (duration: 415.907605ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:49.270712Z","caller":"traceutil/trace.go:171","msg":"trace[1928762441] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"644.266998ms","start":"2026-05-29T05:56:48.626438Z","end":"2026-05-29T05:56:49.270705Z","steps":["trace[1928762441] 'process raft request' (duration: 228.285531ms)","trace[1928762441] 'compare' (duration: 415.642118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:49.270748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:48.626422Z","time spent":"644.306678ms","remote":"127.0.0.1:55164","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-29T05:56:49.270767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.086686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:49.270828Z","caller":"traceutil/trace.go:171","msg":"trace[1570690476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"643.163638ms","start":"2026-05-29T05:56:48.627645Z","end":"2026-05-29T05:56:49.270809Z","steps":["trace[1570690476] 'agreement among raft nodes before linearized reading' (duration: 643.064706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.270874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:48.627636Z","time spent":"643.22707ms","remote":"127.0.0.1:54944","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-29T05:56:49.420993Z","caller":"traceutil/trace.go:171","msg":"trace[1744547665] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"788.036883ms","start":"2026-05-29T05:56:48.632928Z","end":"2026-05-29T05:56:49.420965Z","steps":["trace[1744547665] 'process raft request' (duration: 787.968381ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:49.42106Z","caller":"traceutil/trace.go:171","msg":"trace[1246420497] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"788.816025ms","start":"2026-05-29T05:56:48.632215Z","end":"2026-05-29T05:56:49.421031Z","steps":["trace[1246420497] 'process raft request' (duration: 774.567922ms)","trace[1246420497] 'compare' (duration: 14.003406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:49.421162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:48.632912Z","time spent":"788.160937ms","remote":"127.0.0.1:55086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:49.421219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:48.632195Z","time spent":"788.943428ms","remote":"127.0.0.1:55042","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-29T05:56:49.502197Z","caller":"traceutil/trace.go:171","msg":"trace[878674768] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1344; }","duration":"231.492915ms","start":"2026-05-29T05:56:49.270681Z","end":"2026-05-29T05:56:49.502174Z","steps":["trace[878674768] 'read index received' (duration: 136.114336ms)","trace[878674768] 'applied index is now lower than readState.Index' (duration: 95.377668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:49.502442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.814698ms","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-29T05:56:49.502441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.189652ms","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-29T05:56:49.502477Z","caller":"traceutil/trace.go:171","msg":"trace[1997479487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"327.890759ms","start":"2026-05-29T05:56:49.174576Z","end":"2026-05-29T05:56:49.502467Z","steps":["trace[1997479487] 'agreement among raft nodes before linearized reading' (duration: 327.721565ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:49.502445Z","caller":"traceutil/trace.go:171","msg":"trace[746243161] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"431.122024ms","start":"2026-05-29T05:56:49.071297Z","end":"2026-05-29T05:56:49.502419Z","steps":["trace[746243161] 'process raft request' (duration: 430.744024ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:49.502509Z","caller":"traceutil/trace.go:171","msg":"trace[1113235946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"327.292234ms","start":"2026-05-29T05:56:49.175202Z","end":"2026-05-29T05:56:49.502494Z","steps":["trace[1113235946] 'agreement among raft nodes before linearized reading' (duration: 327.083848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.502516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.174571Z","time spent":"327.937301ms","remote":"127.0.0.1:55164","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-29T05:56:49.502545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.17519Z","time spent":"327.347246ms","remote":"127.0.0.1:55164","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-29T05:56:49.502597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.066764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T05:56:49.502613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.071273Z","time spent":"431.244507ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:49.502666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.209255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:56:49.502692Z","caller":"traceutil/trace.go:171","msg":"trace[655848398] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1293; }","duration":"305.277508ms","start":"2026-05-29T05:56:49.197404Z","end":"2026-05-29T05:56:49.502682Z","steps":["trace[655848398] 'agreement among raft nodes before linearized reading' (duration: 305.232106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.50271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.197381Z","time spent":"305.323578ms","remote":"127.0.0.1:55330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:49.502452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.137488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:56:49.502742Z","caller":"traceutil/trace.go:171","msg":"trace[80922241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"359.470877ms","start":"2026-05-29T05:56:49.14326Z","end":"2026-05-29T05:56:49.502731Z","steps":["trace[80922241] 'agreement among raft nodes before linearized reading' (duration: 359.058376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.50276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.256749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T05:56:49.502776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.143242Z","time spent":"359.524658ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:56:49.502655Z","caller":"traceutil/trace.go:171","msg":"trace[15296735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"328.155807ms","start":"2026-05-29T05:56:49.174483Z","end":"2026-05-29T05:56:49.502639Z","steps":["trace[15296735] 'agreement among raft nodes before linearized reading' (duration: 328.007613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.503028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.174461Z","time spent":"328.557716ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T05:56:49.502802Z","caller":"traceutil/trace.go:171","msg":"trace[1291037747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"328.335001ms","start":"2026-05-29T05:56:49.174454Z","end":"2026-05-29T05:56:49.502789Z","steps":["trace[1291037747] 'agreement among raft nodes before linearized reading' (duration: 328.226409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:49.503104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:49.174427Z","time spent":"328.671349ms","remote":"127.0.0.1:55164","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-29T05:56:49.502868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.849736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:49.503155Z","caller":"traceutil/trace.go:171","msg":"trace[938704120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"226.166715ms","start":"2026-05-29T05:56:49.27698Z","end":"2026-05-29T05:56:49.503147Z","steps":["trace[938704120] 'agreement among raft nodes before linearized reading' (duration: 225.861477ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:49.607876Z","caller":"traceutil/trace.go:171","msg":"trace[1410394707] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"100.463162ms","start":"2026-05-29T05:56:49.507395Z","end":"2026-05-29T05:56:49.607858Z","steps":["trace[1410394707] 'process raft request' (duration: 99.949998ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:49.608081Z","caller":"traceutil/trace.go:171","msg":"trace[1334428723] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"100.321038ms","start":"2026-05-29T05:56:49.507735Z","end":"2026-05-29T05:56:49.608056Z","steps":["trace[1334428723] 'process raft request' (duration: 99.703211ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:50.349576Z","caller":"traceutil/trace.go:171","msg":"trace[1209074680] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"226.303637ms","start":"2026-05-29T05:56:50.123248Z","end":"2026-05-29T05:56:50.349552Z","steps":["trace[1209074680] 'process raft request' (duration: 226.133153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:50.891127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.614438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:50.891219Z","caller":"traceutil/trace.go:171","msg":"trace[260360546] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"721.484331ms","start":"2026-05-29T05:56:50.169718Z","end":"2026-05-29T05:56:50.891203Z","steps":["trace[260360546] 'read index received' (duration: 179.621275ms)","trace[260360546] 'applied index is now lower than readState.Index' (duration: 541.861876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:50.891318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.597224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:50.891345Z","caller":"traceutil/trace.go:171","msg":"trace[307117821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"721.648415ms","start":"2026-05-29T05:56:50.169687Z","end":"2026-05-29T05:56:50.891335Z","steps":["trace[307117821] 'agreement among raft nodes before linearized reading' (duration: 721.599014ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:50.891321Z","caller":"traceutil/trace.go:171","msg":"trace[1682122380] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"766.824888ms","start":"2026-05-29T05:56:50.124462Z","end":"2026-05-29T05:56:50.891287Z","steps":["trace[1682122380] 'process raft request' (duration: 605.959913ms)","trace[1682122380] 'compare' (duration: 160.2732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:50.891484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:50.124444Z","time spent":"766.939771ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:50.891376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:50.16967Z","time spent":"721.699636ms","remote":"127.0.0.1:54944","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-29T05:56:50.891794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.115923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-dfthp\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-29T05:56:50.891916Z","caller":"traceutil/trace.go:171","msg":"trace[609607772] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-dfthp; range_end:; response_count:1; response_revision:1302; }","duration":"129.268027ms","start":"2026-05-29T05:56:50.762626Z","end":"2026-05-29T05:56:50.891894Z","steps":["trace[609607772] 'agreement among raft nodes before linearized reading' (duration: 129.027411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:51.152266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.089801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:51.152393Z","caller":"traceutil/trace.go:171","msg":"trace[780179951] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"186.060323ms","start":"2026-05-29T05:56:50.966316Z","end":"2026-05-29T05:56:51.152376Z","steps":["trace[780179951] 'process raft request' (duration: 32.776408ms)","trace[780179951] 'compare' (duration: 152.31545ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:51.170607Z","caller":"traceutil/trace.go:171","msg":"trace[381210377] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"203.0906ms","start":"2026-05-29T05:56:50.967495Z","end":"2026-05-29T05:56:51.170585Z","steps":["trace[381210377] 'process raft request' (duration: 202.568726ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:51.171155Z","caller":"traceutil/trace.go:171","msg":"trace[799427459] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"199.872486ms","start":"2026-05-29T05:56:50.971266Z","end":"2026-05-29T05:56:51.171139Z","steps":["trace[799427459] 'process raft request' (duration: 198.8909ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:59.709017Z","caller":"traceutil/trace.go:171","msg":"trace[1204105292] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"169.396633ms","start":"2026-05-29T05:56:59.539592Z","end":"2026-05-29T05:56:59.708989Z","steps":["trace[1204105292] 'process raft request' (duration: 169.231979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.228244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416628822s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:57:01.229435Z","caller":"traceutil/trace.go:171","msg":"trace[2121010938] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"1.572361372s","start":"2026-05-29T05:56:59.657016Z","end":"2026-05-29T05:57:01.229378Z","steps":["trace[2121010938] 'process raft request' (duration: 154.48669ms)","trace[2121010938] 'compare' (duration: 1.416233853s)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:57:01.229302Z","caller":"traceutil/trace.go:171","msg":"trace[621424587] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"1.568129089s","start":"2026-05-29T05:56:59.66115Z","end":"2026-05-29T05:57:01.22928Z","steps":["trace[621424587] 'read index received' (duration: 47.672853ms)","trace[621424587] 'applied index is now lower than readState.Index' (duration: 1.520454256s)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:57:01.229741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.656992Z","time spent":"1.572597098s","remote":"127.0.0.1:55164","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-29T05:57:01.229921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568753195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T05:57:01.229999Z","caller":"traceutil/trace.go:171","msg":"trace[1480611709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"1.568871187s","start":"2026-05-29T05:56:59.661109Z","end":"2026-05-29T05:57:01.229981Z","steps":["trace[1480611709] 'agreement among raft nodes before linearized reading' (duration: 1.568628971s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.230048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.661098Z","time spent":"1.568942188s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:57:01.230474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408068743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-29T05:57:01.230514Z","caller":"traceutil/trace.go:171","msg":"trace[134223793] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"1.408145295s","start":"2026-05-29T05:56:59.822358Z","end":"2026-05-29T05:57:01.230503Z","steps":["trace[134223793] 'agreement among raft nodes before linearized reading' (duration: 1.408048143s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.230565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.822297Z","time spent":"1.408260768s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T05:57:01.230563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566182233s","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-29T05:57:01.230605Z","caller":"traceutil/trace.go:171","msg":"trace[6653374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.566258145s","start":"2026-05-29T05:56:59.664336Z","end":"2026-05-29T05:57:01.230594Z","steps":["trace[6653374] 'agreement among raft nodes before linearized reading' (duration: 1.566147912s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.230634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.664319Z","time spent":"1.566309275s","remote":"127.0.0.1:55164","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-29T05:57:01.230675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566323576s","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-29T05:57:01.2308Z","caller":"traceutil/trace.go:171","msg":"trace[1472022482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"1.566453609s","start":"2026-05-29T05:56:59.664313Z","end":"2026-05-29T05:57:01.230767Z","steps":["trace[1472022482] 'agreement among raft nodes before linearized reading' (duration: 1.566231213s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.230845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.664306Z","time spent":"1.566526651s","remote":"127.0.0.1:55164","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-29T05:57:01.230882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060557913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:57:01.231073Z","caller":"traceutil/trace.go:171","msg":"trace[733181933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"1.060881511s","start":"2026-05-29T05:57:00.170171Z","end":"2026-05-29T05:57:01.231053Z","steps":["trace[733181933] 'agreement among raft nodes before linearized reading' (duration: 1.060310187s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.231474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:57:00.170152Z","time spent":"1.06130375s","remote":"127.0.0.1:54952","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-29T05:57:01.231416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.56696406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-29T05:57:01.232335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.311288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-29T05:57:01.23239Z","caller":"traceutil/trace.go:171","msg":"trace[501465441] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1376; }","duration":"838.41243ms","start":"2026-05-29T05:57:00.393965Z","end":"2026-05-29T05:57:01.232377Z","steps":["trace[501465441] 'agreement among raft nodes before linearized reading' (duration: 836.472374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.232429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:57:00.393943Z","time spent":"838.477782ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-29T05:57:01.232719Z","caller":"traceutil/trace.go:171","msg":"trace[278516739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.567192135s","start":"2026-05-29T05:56:59.664437Z","end":"2026-05-29T05:57:01.231629Z","steps":["trace[278516739] 'agreement among raft nodes before linearized reading' (duration: 1.56653386s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.232855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568513379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-29T05:57:01.232897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.664329Z","time spent":"1.568513999s","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T05:57:01.232955Z","caller":"traceutil/trace.go:171","msg":"trace[1487543221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.568695073s","start":"2026-05-29T05:56:59.664244Z","end":"2026-05-29T05:57:01.232939Z","steps":["trace[1487543221] 'agreement among raft nodes before linearized reading' (duration: 1.566072319s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.233054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:59.664221Z","time spent":"1.568818986s","remote":"127.0.0.1:55164","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":"info","ts":"2026-05-29T05:57:01.500367Z","caller":"traceutil/trace.go:171","msg":"trace[242120662] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"265.792616ms","start":"2026-05-29T05:57:01.234547Z","end":"2026-05-29T05:57:01.50034Z","steps":["trace[242120662] 'process raft request' (duration: 259.721378ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.502172Z","caller":"traceutil/trace.go:171","msg":"trace[1252410015] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"264.745451ms","start":"2026-05-29T05:57:01.237416Z","end":"2026-05-29T05:57:01.502161Z","steps":["trace[1252410015] 'process raft request' (duration: 264.704969ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.502152Z","caller":"traceutil/trace.go:171","msg":"trace[1809402281] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"266.568805ms","start":"2026-05-29T05:57:01.235557Z","end":"2026-05-29T05:57:01.502126Z","steps":["trace[1809402281] 'process raft request' (duration: 266.471613ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.502257Z","caller":"traceutil/trace.go:171","msg":"trace[513727646] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1433; }","duration":"261.278687ms","start":"2026-05-29T05:57:01.240962Z","end":"2026-05-29T05:57:01.50224Z","steps":["trace[513727646] 'read index received' (duration: 253.378834ms)","trace[513727646] 'applied index is now lower than readState.Index' (duration: 7.898693ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:57:01.502295Z","caller":"traceutil/trace.go:171","msg":"trace[1440755911] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"266.690069ms","start":"2026-05-29T05:57:01.235593Z","end":"2026-05-29T05:57:01.502283Z","steps":["trace[1440755911] 'process raft request' (duration: 266.465383ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.502339Z","caller":"traceutil/trace.go:171","msg":"trace[288654422] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"266.7219ms","start":"2026-05-29T05:57:01.235604Z","end":"2026-05-29T05:57:01.502326Z","steps":["trace[288654422] 'process raft request' (duration: 266.475933ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.502393Z","caller":"traceutil/trace.go:171","msg":"trace[1043845777] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"264.958967ms","start":"2026-05-29T05:57:01.237426Z","end":"2026-05-29T05:57:01.502385Z","steps":["trace[1043845777] 'process raft request' (duration: 264.714831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.502517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.540143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:57:01.50255Z","caller":"traceutil/trace.go:171","msg":"trace[1473376296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1384; }","duration":"261.609345ms","start":"2026-05-29T05:57:01.240933Z","end":"2026-05-29T05:57:01.502542Z","steps":["trace[1473376296] 'agreement among raft nodes before linearized reading' (duration: 261.519573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:57:01.502574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.013908ms","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-29T05:57:01.502614Z","caller":"traceutil/trace.go:171","msg":"trace[871347286] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1384; }","duration":"208.088269ms","start":"2026-05-29T05:57:01.294513Z","end":"2026-05-29T05:57:01.502601Z","steps":["trace[871347286] 'agreement among raft nodes before linearized reading' (duration: 207.971926ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.502377Z","caller":"traceutil/trace.go:171","msg":"trace[1062910634] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"264.958666ms","start":"2026-05-29T05:57:01.237411Z","end":"2026-05-29T05:57:01.502369Z","steps":["trace[1062910634] 'process raft request' (duration: 264.687489ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:57:01.503048Z","caller":"traceutil/trace.go:171","msg":"trace[52470832] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"266.606756ms","start":"2026-05-29T05:57:01.235501Z","end":"2026-05-29T05:57:01.502108Z","steps":["trace[52470832] 'process raft request' (duration: 266.437722ms)"],"step_count":1}