{"level":"warn","ts":"2026-05-08T02:34:21.623016Z","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-08T02:34:21.62311Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.128: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.128:2380","--initial-cluster=instance=https://199.19.213.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.128: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-08T02:34:21.623173Z","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-08T02:34:21.623187Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-05-08T02:34:21.623204Z","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-08T02:34:21.623876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"]} {"level":"info","ts":"2026-05-08T02:34:21.623951Z","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.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.128: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-08T02:34:21.635333Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.176502ms"} {"level":"info","ts":"2026-05-08T02:34:21.646856Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a463a6aa9b462de2","cluster-id":"cd3d14b8ed85e2f2"} {"level":"info","ts":"2026-05-08T02:34:21.646944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T02:34:21.646969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 0"} {"level":"info","ts":"2026-05-08T02:34:21.646982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a463a6aa9b462de2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T02:34:21.646991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 1"} {"level":"info","ts":"2026-05-08T02:34:21.647039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"warn","ts":"2026-05-08T02:34:21.663938Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T02:34:21.665804Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T02:34:21.66702Z","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-08T02:34:21.669678Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a463a6aa9b462de2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T02:34:21.670019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a463a6aa9b462de2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T02:34:21.670173Z","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-08T02:34:21.670206Z","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-08T02:34:21.670215Z","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-08T02:34:21.67089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"info","ts":"2026-05-08T02:34:21.67098Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","added-peer-id":"a463a6aa9b462de2","added-peer-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-05-08T02:34:21.674647Z","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-08T02:34:21.674795Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-05-08T02:34:21.674838Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-05-08T02:34:21.674862Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a463a6aa9b462de2","initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T02:34:21.6749Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T02:34:22.647188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T02:34:22.64725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T02:34:22.647273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgPreVoteResp from a463a6aa9b462de2 at term 1"} {"level":"info","ts":"2026-05-08T02:34:22.647293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became candidate at term 2"} {"level":"info","ts":"2026-05-08T02:34:22.647329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgVoteResp from a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-05-08T02:34:22.647345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became leader at term 2"} {"level":"info","ts":"2026-05-08T02:34:22.647359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a463a6aa9b462de2 elected leader a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-05-08T02:34:22.668409Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a463a6aa9b462de2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.128:2379]}","request-path":"/0/members/a463a6aa9b462de2/attributes","cluster-id":"cd3d14b8ed85e2f2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T02:34:22.668456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:34:22.668509Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:34:22.668566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:34:22.668976Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T02:34:22.669017Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T02:34:22.672675Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T02:34:22.672933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.128:2379"} {"level":"info","ts":"2026-05-08T02:34:22.68695Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:34:22.6871Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:34:22.687129Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-08T02:34:46.844783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.689816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:34:46.844905Z","caller":"traceutil/trace.go:171","msg":"trace[577628414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"110.86705ms","start":"2026-05-08T02:34:46.734019Z","end":"2026-05-08T02:34:46.844886Z","steps":["trace[577628414] 'range keys from in-memory index tree' (duration: 110.604924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.52085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628037413,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:34:50.727416Z","caller":"traceutil/trace.go:171","msg":"trace[1578850622] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"725.76662ms","start":"2026-05-08T02:34:50.001612Z","end":"2026-05-08T02:34:50.727379Z","steps":["trace[1578850622] 'process raft request' (duration: 725.466873ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:50.727463Z","caller":"traceutil/trace.go:171","msg":"trace[1630379094] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"724.760516ms","start":"2026-05-08T02:34:50.002677Z","end":"2026-05-08T02:34:50.727437Z","steps":["trace[1630379094] 'process raft request' (duration: 724.580602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.728233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.001598Z","time spent":"725.909043ms","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:34:50.728619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.00267Z","time spent":"724.864798ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:34:50.915493Z","caller":"traceutil/trace.go:171","msg":"trace[851119574] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"903.525547ms","start":"2026-05-08T02:34:50.011936Z","end":"2026-05-08T02:34:50.915461Z","steps":["trace[851119574] 'process raft request' (duration: 903.280871ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:50.91554Z","caller":"traceutil/trace.go:171","msg":"trace[424452458] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"898.660143ms","start":"2026-05-08T02:34:50.016857Z","end":"2026-05-08T02:34:50.915517Z","steps":["trace[424452458] 'process raft request' (duration: 898.568731ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:50.915584Z","caller":"traceutil/trace.go:171","msg":"trace[414327392] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"899.149695ms","start":"2026-05-08T02:34:50.016397Z","end":"2026-05-08T02:34:50.915546Z","steps":["trace[414327392] 'process raft request' (duration: 898.980891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.915659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.011913Z","time spent":"903.662381ms","remote":"127.0.0.1:58636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:34:50.915682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.016847Z","time spent":"898.764806ms","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:34:50.915754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.016384Z","time spent":"899.278508ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:34:50.916099Z","caller":"traceutil/trace.go:171","msg":"trace[1243601783] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"896.83402ms","start":"2026-05-08T02:34:50.019242Z","end":"2026-05-08T02:34:50.916076Z","steps":["trace[1243601783] 'process raft request' (duration: 896.224806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.916268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.019234Z","time spent":"896.957643ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:34:50.916379Z","caller":"traceutil/trace.go:171","msg":"trace[853744726] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:508; }","duration":"896.596645ms","start":"2026-05-08T02:34:50.019768Z","end":"2026-05-08T02:34:50.916365Z","steps":["trace[853744726] 'read index received' (duration: 707.494341ms)","trace[853744726] 'applied index is now lower than readState.Index' (duration: 189.101404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:34:50.916687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.913122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-05-08T02:34:50.916735Z","caller":"traceutil/trace.go:171","msg":"trace[253307257] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:496; }","duration":"896.979984ms","start":"2026-05-08T02:34:50.019744Z","end":"2026-05-08T02:34:50.916724Z","steps":["trace[253307257] 'agreement among raft nodes before linearized reading' (duration: 896.898142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.916807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.019738Z","time spent":"897.059025ms","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4359,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-05-08T02:34:50.917622Z","caller":"traceutil/trace.go:171","msg":"trace[36421035] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"885.680879ms","start":"2026-05-08T02:34:50.031877Z","end":"2026-05-08T02:34:50.917558Z","steps":["trace[36421035] 'process raft request' (duration: 883.970879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.917901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.03186Z","time spent":"885.867893ms","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:34:50.920427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.522765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-08T02:34:50.920493Z","caller":"traceutil/trace.go:171","msg":"trace[1894490632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"766.635477ms","start":"2026-05-08T02:34:50.153843Z","end":"2026-05-08T02:34:50.920478Z","steps":["trace[1894490632] 'agreement among raft nodes before linearized reading' (duration: 766.503703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.92053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.153826Z","time spent":"766.690328ms","remote":"127.0.0.1:58444","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-08T02:34:50.922115Z","caller":"traceutil/trace.go:171","msg":"trace[1078035056] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"187.430925ms","start":"2026-05-08T02:34:50.734482Z","end":"2026-05-08T02:34:50.921913Z","steps":["trace[1078035056] 'process raft request' (duration: 185.753726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.922279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.944947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:34:50.922301Z","caller":"traceutil/trace.go:171","msg":"trace[697074888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"184.991018ms","start":"2026-05-08T02:34:50.737303Z","end":"2026-05-08T02:34:50.922294Z","steps":["trace[697074888] 'agreement among raft nodes before linearized reading' (duration: 184.936407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.92249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.06558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jzd8p\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-05-08T02:34:50.922513Z","caller":"traceutil/trace.go:171","msg":"trace[1933541039] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jzd8p; range_end:; response_count:1; response_revision:497; }","duration":"188.118441ms","start":"2026-05-08T02:34:50.734388Z","end":"2026-05-08T02:34:50.922506Z","steps":["trace[1933541039] 'agreement among raft nodes before linearized reading' (duration: 188.018419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.922827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.071334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:34:50.922852Z","caller":"traceutil/trace.go:171","msg":"trace[42397024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:497; }","duration":"502.141755ms","start":"2026-05-08T02:34:50.420702Z","end":"2026-05-08T02:34:50.922844Z","steps":["trace[42397024] 'agreement among raft nodes before linearized reading' (duration: 502.095744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.923055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.420677Z","time spent":"502.37043ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:34:50.923523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.915889ms","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-08T02:34:50.923547Z","caller":"traceutil/trace.go:171","msg":"trace[55377883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"550.98453ms","start":"2026-05-08T02:34:50.372556Z","end":"2026-05-08T02:34:50.92354Z","steps":["trace[55377883] 'agreement among raft nodes before linearized reading' (duration: 550.761405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:50.923754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:50.372532Z","time spent":"551.208775ms","remote":"127.0.0.1:58444","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-08T02:34:52.618131Z","caller":"traceutil/trace.go:171","msg":"trace[187290265] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"189.323599ms","start":"2026-05-08T02:34:52.428787Z","end":"2026-05-08T02:34:52.618111Z","steps":["trace[187290265] 'process raft request' (duration: 189.165896ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626362Z","caller":"traceutil/trace.go:171","msg":"trace[447188645] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"191.247505ms","start":"2026-05-08T02:34:52.435097Z","end":"2026-05-08T02:34:52.626344Z","steps":["trace[447188645] 'process raft request' (duration: 191.104081ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626361Z","caller":"traceutil/trace.go:171","msg":"trace[642347694] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"191.277675ms","start":"2026-05-08T02:34:52.43505Z","end":"2026-05-08T02:34:52.626327Z","steps":["trace[642347694] 'process raft request' (duration: 191.078971ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626506Z","caller":"traceutil/trace.go:171","msg":"trace[847345985] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"185.93665ms","start":"2026-05-08T02:34:52.440547Z","end":"2026-05-08T02:34:52.626483Z","steps":["trace[847345985] 'process raft request' (duration: 185.711855ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626542Z","caller":"traceutil/trace.go:171","msg":"trace[206870911] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"185.824967ms","start":"2026-05-08T02:34:52.440705Z","end":"2026-05-08T02:34:52.62653Z","steps":["trace[206870911] 'process raft request' (duration: 185.595322ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626625Z","caller":"traceutil/trace.go:171","msg":"trace[561181666] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"182.591892ms","start":"2026-05-08T02:34:52.444025Z","end":"2026-05-08T02:34:52.626616Z","steps":["trace[561181666] 'process raft request' (duration: 182.445549ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626668Z","caller":"traceutil/trace.go:171","msg":"trace[1923028355] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"181.863755ms","start":"2026-05-08T02:34:52.44479Z","end":"2026-05-08T02:34:52.626654Z","steps":["trace[1923028355] 'process raft request' (duration: 181.726412ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626699Z","caller":"traceutil/trace.go:171","msg":"trace[619923645] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"170.795155ms","start":"2026-05-08T02:34:52.455896Z","end":"2026-05-08T02:34:52.626691Z","steps":["trace[619923645] 'process raft request' (duration: 170.696843ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626686Z","caller":"traceutil/trace.go:171","msg":"trace[550278360] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"185.278985ms","start":"2026-05-08T02:34:52.441371Z","end":"2026-05-08T02:34:52.62665Z","steps":["trace[550278360] 'process raft request' (duration: 185.003028ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626783Z","caller":"traceutil/trace.go:171","msg":"trace[676034577] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"167.708093ms","start":"2026-05-08T02:34:52.459056Z","end":"2026-05-08T02:34:52.626764Z","steps":["trace[676034577] 'process raft request' (duration: 167.59044ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:34:52.626825Z","caller":"traceutil/trace.go:171","msg":"trace[1068663519] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"138.771754ms","start":"2026-05-08T02:34:52.488041Z","end":"2026-05-08T02:34:52.626813Z","steps":["trace[1068663519] 'process raft request' (duration: 138.661531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:57.234558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628037607,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:34:57.735473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628037607,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:34:58.120368Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.121477197s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T02:34:58.171551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.192214286s","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-08T02:34:58.17163Z","caller":"traceutil/trace.go:171","msg":"trace[714734712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"2.192336249s","start":"2026-05-08T02:34:55.979275Z","end":"2026-05-08T02:34:58.171611Z","steps":["trace[714734712] 'range keys from in-memory index tree' (duration: 2.192025562s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:58.17167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:55.979244Z","time spent":"2.192417481s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T02:34:58.172639Z","caller":"traceutil/trace.go:171","msg":"trace[1320991022] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.176722323s","start":"2026-05-08T02:34:55.995887Z","end":"2026-05-08T02:34:58.172609Z","steps":["trace[1320991022] 'process raft request' (duration: 2.128271417s)","trace[1320991022] 'compare' (duration: 48.022836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:34:58.172807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:55.995862Z","time spent":"2.176860566s","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:34:58.174956Z","caller":"traceutil/trace.go:171","msg":"trace[1274128701] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"551.256077ms","start":"2026-05-08T02:34:57.623678Z","end":"2026-05-08T02:34:58.174934Z","steps":["trace[1274128701] 'process raft request' (duration: 551.122164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:58.175049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:57.623653Z","time spent":"551.344818ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:34:58.175209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440923778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:34:58.175234Z","caller":"traceutil/trace.go:171","msg":"trace[916788572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.44098006s","start":"2026-05-08T02:34:56.734246Z","end":"2026-05-08T02:34:58.175226Z","steps":["trace[916788572] 'agreement among raft nodes before linearized reading' (duration: 1.440939359s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:58.175251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:56.734226Z","time spent":"1.44102109s","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:34:58.174956Z","caller":"traceutil/trace.go:171","msg":"trace[1546559275] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"1.440615401s","start":"2026-05-08T02:34:56.734287Z","end":"2026-05-08T02:34:58.174902Z","steps":["trace[1546559275] 'read index received' (duration: 1.386531293s)","trace[1546559275] 'applied index is now lower than readState.Index' (duration: 54.083328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:34:58.177834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146267929s","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-08T02:34:58.177878Z","caller":"traceutil/trace.go:171","msg":"trace[1842493850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.146345661s","start":"2026-05-08T02:34:57.031518Z","end":"2026-05-08T02:34:58.177863Z","steps":["trace[1842493850] 'agreement among raft nodes before linearized reading' (duration: 1.14626691s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:58.177862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123537117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-08T02:34:58.177868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14887198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-08T02:34:58.177919Z","caller":"traceutil/trace.go:171","msg":"trace[1931535158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.123626778s","start":"2026-05-08T02:34:57.054278Z","end":"2026-05-08T02:34:58.177905Z","steps":["trace[1931535158] 'agreement among raft nodes before linearized reading' (duration: 1.123510126s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:58.177962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:57.054265Z","time spent":"1.123691741s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T02:34:58.17796Z","caller":"traceutil/trace.go:171","msg":"trace[1154345694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"1.149062435s","start":"2026-05-08T02:34:57.028865Z","end":"2026-05-08T02:34:58.177927Z","steps":["trace[1154345694] 'agreement among raft nodes before linearized reading' (duration: 1.148803459s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:34:58.177899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:57.031497Z","time spent":"1.146397493s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:34:58.178011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:34:57.028843Z","time spent":"1.149156368s","remote":"127.0.0.1:58444","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-08T02:35:00.276693Z","caller":"traceutil/trace.go:171","msg":"trace[455003210] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"162.953312ms","start":"2026-05-08T02:35:00.113709Z","end":"2026-05-08T02:35:00.276662Z","steps":["trace[455003210] 'process raft request' (duration: 162.212625ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:01.465798Z","caller":"traceutil/trace.go:171","msg":"trace[1581421949] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"168.4186ms","start":"2026-05-08T02:35:01.29733Z","end":"2026-05-08T02:35:01.465749Z","steps":["trace[1581421949] 'read index received' (duration: 167.986499ms)","trace[1581421949] 'applied index is now lower than readState.Index' (duration: 430.581µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:01.466259Z","caller":"traceutil/trace.go:171","msg":"trace[1243103737] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"172.566697ms","start":"2026-05-08T02:35:01.293653Z","end":"2026-05-08T02:35:01.46622Z","steps":["trace[1243103737] 'process raft request' (duration: 171.518802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.477684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.484432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.477806Z","caller":"traceutil/trace.go:171","msg":"trace[1813550612] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:622; }","duration":"180.458582ms","start":"2026-05-08T02:35:01.297296Z","end":"2026-05-08T02:35:01.477754Z","steps":["trace[1813550612] 'agreement among raft nodes before linearized reading' (duration: 168.511962ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:01.856272Z","caller":"traceutil/trace.go:171","msg":"trace[55208899] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"390.319603ms","start":"2026-05-08T02:35:01.465927Z","end":"2026-05-08T02:35:01.856246Z","steps":["trace[55208899] 'read index received' (duration: 375.72218ms)","trace[55208899] 'applied index is now lower than readState.Index' (duration: 14.596382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:35:01.856365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.075741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.856446Z","caller":"traceutil/trace.go:171","msg":"trace[24211619] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"420.189754ms","start":"2026-05-08T02:35:01.436238Z","end":"2026-05-08T02:35:01.856427Z","steps":["trace[24211619] 'agreement among raft nodes before linearized reading' (duration: 420.04134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.856502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.436223Z","time spent":"420.265465ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-08T02:35:01.85661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.191043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.856684Z","caller":"traceutil/trace.go:171","msg":"trace[1940613047] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"420.306766ms","start":"2026-05-08T02:35:01.436361Z","end":"2026-05-08T02:35:01.856668Z","steps":["trace[1940613047] 'agreement among raft nodes before linearized reading' (duration: 420.184084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.856726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.436345Z","time spent":"420.371268ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:35:01.856799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.890205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:35:01.856843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.089778ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.856888Z","caller":"traceutil/trace.go:171","msg":"trace[49137662] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:623; }","duration":"559.027529ms","start":"2026-05-08T02:35:01.297843Z","end":"2026-05-08T02:35:01.856871Z","steps":["trace[49137662] 'agreement among raft nodes before linearized reading' (duration: 558.844525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.856919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.570212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.856951Z","caller":"traceutil/trace.go:171","msg":"trace[143126029] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:623; }","duration":"559.620823ms","start":"2026-05-08T02:35:01.297319Z","end":"2026-05-08T02:35:01.85694Z","steps":["trace[143126029] 'agreement among raft nodes before linearized reading' (duration: 559.581422ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:01.856988Z","caller":"traceutil/trace.go:171","msg":"trace[1861224698] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"527.246673ms","start":"2026-05-08T02:35:01.329367Z","end":"2026-05-08T02:35:01.856613Z","steps":["trace[1861224698] 'process raft request' (duration: 512.536528ms)","trace[1861224698] 'compare' (duration: 13.680251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:35:01.857031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.558546ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.857075Z","caller":"traceutil/trace.go:171","msg":"trace[1960106102] 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:623; }","duration":"534.639988ms","start":"2026-05-08T02:35:01.322418Z","end":"2026-05-08T02:35:01.857058Z","steps":["trace[1960106102] 'agreement among raft nodes before linearized reading' (duration: 534.584346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.857114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.322392Z","time spent":"534.713249ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T02:35:01.856937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.297834Z","time spent":"559.09022ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-08T02:35:01.856943Z","caller":"traceutil/trace.go:171","msg":"trace[739687245] 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:623; }","duration":"532.267182ms","start":"2026-05-08T02:35:01.324637Z","end":"2026-05-08T02:35:01.856905Z","steps":["trace[739687245] 'agreement among raft nodes before linearized reading' (duration: 532.040767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.857318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.329343Z","time spent":"527.711414ms","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:35:01.857349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.981585ms","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-08T02:35:01.857385Z","caller":"traceutil/trace.go:171","msg":"trace[1944992855] 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:623; }","duration":"535.029766ms","start":"2026-05-08T02:35:01.322344Z","end":"2026-05-08T02:35:01.857374Z","steps":["trace[1944992855] 'agreement among raft nodes before linearized reading' (duration: 534.979555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.857317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.324618Z","time spent":"532.600749ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-08T02:35:01.857416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.322336Z","time spent":"535.071718ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"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-08T02:35:01.856877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.325019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.857532Z","caller":"traceutil/trace.go:171","msg":"trace[9448795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"271.003444ms","start":"2026-05-08T02:35:01.586512Z","end":"2026-05-08T02:35:01.857516Z","steps":["trace[9448795] 'agreement among raft nodes before linearized reading' (duration: 270.334139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.857622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.058193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.857686Z","caller":"traceutil/trace.go:171","msg":"trace[1193638602] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"421.194097ms","start":"2026-05-08T02:35:01.436478Z","end":"2026-05-08T02:35:01.857672Z","steps":["trace[1193638602] 'agreement among raft nodes before linearized reading' (duration: 420.138032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.856995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.297308Z","time spent":"559.676744ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:35:01.85773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.436462Z","time spent":"421.257828ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T02:35:01.857757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.401058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:35:01.857784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.43564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.857831Z","caller":"traceutil/trace.go:171","msg":"trace[1918745676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"145.492141ms","start":"2026-05-08T02:35:01.712326Z","end":"2026-05-08T02:35:01.857818Z","steps":["trace[1918745676] 'agreement among raft nodes before linearized reading' (duration: 145.43353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.858012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.459926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.857796Z","caller":"traceutil/trace.go:171","msg":"trace[362229688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"124.472969ms","start":"2026-05-08T02:35:01.733314Z","end":"2026-05-08T02:35:01.857787Z","steps":["trace[362229688] 'agreement among raft nodes before linearized reading' (duration: 124.406877ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:01.858055Z","caller":"traceutil/trace.go:171","msg":"trace[2072350054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"271.505737ms","start":"2026-05-08T02:35:01.586539Z","end":"2026-05-08T02:35:01.858045Z","steps":["trace[2072350054] 'agreement among raft nodes before linearized reading' (duration: 271.439875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.858194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.021184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.858225Z","caller":"traceutil/trace.go:171","msg":"trace[540615873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"146.069426ms","start":"2026-05-08T02:35:01.712147Z","end":"2026-05-08T02:35:01.858217Z","steps":["trace[540615873] 'agreement among raft nodes before linearized reading' (duration: 146.021845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.858238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.088376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.858291Z","caller":"traceutil/trace.go:171","msg":"trace[652919483] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"146.143527ms","start":"2026-05-08T02:35:01.712131Z","end":"2026-05-08T02:35:01.858274Z","steps":["trace[652919483] 'agreement among raft nodes before linearized reading' (duration: 146.056495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.858518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.48049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:35:01.858534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.024942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.858572Z","caller":"traceutil/trace.go:171","msg":"trace[776739406] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"102.100384ms","start":"2026-05-08T02:35:01.756461Z","end":"2026-05-08T02:35:01.858562Z","steps":["trace[776739406] 'agreement among raft nodes before linearized reading' (duration: 102.046563ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:01.858571Z","caller":"traceutil/trace.go:171","msg":"trace[1320156276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"101.556092ms","start":"2026-05-08T02:35:01.757Z","end":"2026-05-08T02:35:01.858557Z","steps":["trace[1320156276] 'agreement among raft nodes before linearized reading' (duration: 101.48785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:01.858575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.236477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:01.858611Z","caller":"traceutil/trace.go:171","msg":"trace[1065280499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"102.292499ms","start":"2026-05-08T02:35:01.756309Z","end":"2026-05-08T02:35:01.858601Z","steps":["trace[1065280499] 'agreement among raft nodes before linearized reading' (duration: 102.236247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:03.646827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.970574Z","time spent":"1.676244657s","remote":"127.0.0.1:44280","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T02:35:03.647218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170328105s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:35:03.64732Z","caller":"traceutil/trace.go:171","msg":"trace[1203669876] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"1.328543715s","start":"2026-05-08T02:35:02.318752Z","end":"2026-05-08T02:35:03.647296Z","steps":["trace[1203669876] 'read index received' (duration: 157.993215ms)","trace[1203669876] 'applied index is now lower than readState.Index' (duration: 1.17054937s)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:03.647438Z","caller":"traceutil/trace.go:171","msg":"trace[1022328547] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.725999734s","start":"2026-05-08T02:35:01.921397Z","end":"2026-05-08T02:35:03.647397Z","steps":["trace[1022328547] 'process raft request' (duration: 555.277751ms)","trace[1022328547] 'compare' (duration: 1.170205961s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:35:03.64749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.074152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:03.647526Z","caller":"traceutil/trace.go:171","msg":"trace[34752694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"913.141483ms","start":"2026-05-08T02:35:02.734374Z","end":"2026-05-08T02:35:03.647515Z","steps":["trace[34752694] 'agreement among raft nodes before linearized reading' (duration: 913.054581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:03.647557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:02.734355Z","time spent":"913.195235ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:35:03.647601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328847661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-08T02:35:03.647601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305913103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-08T02:35:03.647651Z","caller":"traceutil/trace.go:171","msg":"trace[932995585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"1.328938064s","start":"2026-05-08T02:35:02.318701Z","end":"2026-05-08T02:35:03.647639Z","steps":["trace[932995585] 'agreement among raft nodes before linearized reading' (duration: 1.328737289s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:03.64767Z","caller":"traceutil/trace.go:171","msg":"trace[1387611869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"1.306014766s","start":"2026-05-08T02:35:02.341636Z","end":"2026-05-08T02:35:03.647651Z","steps":["trace[1387611869] 'agreement among raft nodes before linearized reading' (duration: 1.305790841s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:03.647688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:02.318677Z","time spent":"1.329003166s","remote":"127.0.0.1:58444","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-08T02:35:03.647709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:02.341617Z","time spent":"1.306084977s","remote":"127.0.0.1:58444","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-08T02:35:03.647765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305562715s","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-08T02:35:03.647842Z","caller":"traceutil/trace.go:171","msg":"trace[1097673364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.305683758s","start":"2026-05-08T02:35:02.342144Z","end":"2026-05-08T02:35:03.647827Z","steps":["trace[1097673364] 'agreement among raft nodes before linearized reading' (duration: 1.30533369s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:03.647912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:02.342137Z","time spent":"1.30576437s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:35:03.647599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:01.921379Z","time spent":"1.726146098s","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:35:03.648153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306158759s","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-08T02:35:03.648231Z","caller":"traceutil/trace.go:171","msg":"trace[1773370725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.306281892s","start":"2026-05-08T02:35:02.341929Z","end":"2026-05-08T02:35:03.648211Z","steps":["trace[1773370725] 'agreement among raft nodes before linearized reading' (duration: 1.30575175s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:03.648336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:02.341909Z","time spent":"1.306414395s","remote":"127.0.0.1:58444","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-08T02:35:04.809021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.388436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:04.809093Z","caller":"traceutil/trace.go:171","msg":"trace[1140423970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"315.526889ms","start":"2026-05-08T02:35:04.49355Z","end":"2026-05-08T02:35:04.809077Z","steps":["trace[1140423970] 'range keys from in-memory index tree' (duration: 315.308074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:04.809131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:04.493532Z","time spent":"315.591231ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:35:04.809367Z","caller":"traceutil/trace.go:171","msg":"trace[1914029588] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"134.138365ms","start":"2026-05-08T02:35:04.675212Z","end":"2026-05-08T02:35:04.80935Z","steps":["trace[1914029588] 'process raft request' (duration: 128.853021ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:04.931262Z","caller":"traceutil/trace.go:171","msg":"trace[161309205] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"111.973135ms","start":"2026-05-08T02:35:04.819267Z","end":"2026-05-08T02:35:04.93124Z","steps":["trace[161309205] 'process raft request' (duration: 104.804197ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:05.500224Z","caller":"traceutil/trace.go:171","msg":"trace[1063412033] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"198.889704ms","start":"2026-05-08T02:35:05.301316Z","end":"2026-05-08T02:35:05.500205Z","steps":["trace[1063412033] 'process raft request' (duration: 198.786221ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:05.564359Z","caller":"traceutil/trace.go:171","msg":"trace[1380855845] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"251.169839ms","start":"2026-05-08T02:35:05.313172Z","end":"2026-05-08T02:35:05.564342Z","steps":["trace[1380855845] 'process raft request' (duration: 249.596803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:06.232965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628037726,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:35:06.436172Z","caller":"traceutil/trace.go:171","msg":"trace[1408319490] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"704.259805ms","start":"2026-05-08T02:35:05.731888Z","end":"2026-05-08T02:35:06.436148Z","steps":["trace[1408319490] 'read index received' (duration: 698.383858ms)","trace[1408319490] 'applied index is now lower than readState.Index' (duration: 5.874457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:35:06.436372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.45645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:06.436408Z","caller":"traceutil/trace.go:171","msg":"trace[1101963216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"704.556852ms","start":"2026-05-08T02:35:05.73184Z","end":"2026-05-08T02:35:06.436397Z","steps":["trace[1101963216] 'agreement among raft nodes before linearized reading' (duration: 704.451289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:06.436437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:05.731821Z","time spent":"704.609153ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:35:06.437242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.554028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jzd8p\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-05-08T02:35:06.437295Z","caller":"traceutil/trace.go:171","msg":"trace[684385023] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jzd8p; range_end:; response_count:1; response_revision:647; }","duration":"129.64061ms","start":"2026-05-08T02:35:06.307639Z","end":"2026-05-08T02:35:06.43728Z","steps":["trace[684385023] 'agreement among raft nodes before linearized reading' (duration: 129.468986ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:06.437289Z","caller":"traceutil/trace.go:171","msg":"trace[801232977] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"717.197498ms","start":"2026-05-08T02:35:05.718983Z","end":"2026-05-08T02:35:06.436181Z","steps":["trace[801232977] 'process raft request' (duration: 711.30651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:06.437445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:05.718968Z","time spent":"718.398167ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:35:06.555511Z","caller":"traceutil/trace.go:171","msg":"trace[458035579] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"104.017609ms","start":"2026-05-08T02:35:06.451476Z","end":"2026-05-08T02:35:06.555494Z","steps":["trace[458035579] 'process raft request' (duration: 103.910866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:07.080537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.567016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:35:07.081188Z","caller":"traceutil/trace.go:171","msg":"trace[1345306649] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"234.833726ms","start":"2026-05-08T02:35:06.846325Z","end":"2026-05-08T02:35:07.081158Z","steps":["trace[1345306649] 'process raft request' (duration: 35.588144ms)","trace[1345306649] 'compare' (duration: 198.231018ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:07.081744Z","caller":"traceutil/trace.go:171","msg":"trace[699675887] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"234.340685ms","start":"2026-05-08T02:35:06.847386Z","end":"2026-05-08T02:35:07.081727Z","steps":["trace[699675887] 'process raft request' (duration: 233.513845ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:07.081503Z","caller":"traceutil/trace.go:171","msg":"trace[1360457318] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"234.338575ms","start":"2026-05-08T02:35:06.846906Z","end":"2026-05-08T02:35:07.081245Z","steps":["trace[1360457318] 'process raft request' (duration: 233.886205ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:07.26572Z","caller":"traceutil/trace.go:171","msg":"trace[1871872767] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"242.744452ms","start":"2026-05-08T02:35:07.022938Z","end":"2026-05-08T02:35:07.265682Z","steps":["trace[1871872767] 'process raft request' (duration: 172.168287ms)","trace[1871872767] 'compare' (duration: 70.247208ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:07.266688Z","caller":"traceutil/trace.go:171","msg":"trace[403420588] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"179.286945ms","start":"2026-05-08T02:35:07.087381Z","end":"2026-05-08T02:35:07.266668Z","steps":["trace[403420588] 'process raft request' (duration: 179.214613ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:07.266918Z","caller":"traceutil/trace.go:171","msg":"trace[703498452] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"179.505569ms","start":"2026-05-08T02:35:07.087396Z","end":"2026-05-08T02:35:07.266902Z","steps":["trace[703498452] 'process raft request' (duration: 179.11152ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:08.043102Z","caller":"traceutil/trace.go:171","msg":"trace[224569580] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"219.17853ms","start":"2026-05-08T02:35:07.823902Z","end":"2026-05-08T02:35:08.04308Z","steps":["trace[224569580] 'process raft request' (duration: 219.054417ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:08.166Z","caller":"traceutil/trace.go:171","msg":"trace[227598150] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"141.514048ms","start":"2026-05-08T02:35:08.024462Z","end":"2026-05-08T02:35:08.165976Z","steps":["trace[227598150] 'process raft request' (duration: 136.730496ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:08.167386Z","caller":"traceutil/trace.go:171","msg":"trace[686020989] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"116.38467ms","start":"2026-05-08T02:35:08.050977Z","end":"2026-05-08T02:35:08.167362Z","steps":["trace[686020989] 'process raft request' (duration: 116.251757ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:08.427977Z","caller":"traceutil/trace.go:171","msg":"trace[1106502254] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"169.534505ms","start":"2026-05-08T02:35:08.258407Z","end":"2026-05-08T02:35:08.427942Z","steps":["trace[1106502254] 'process raft request' (duration: 169.239648ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:08.429299Z","caller":"traceutil/trace.go:171","msg":"trace[1235781073] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"106.299852ms","start":"2026-05-08T02:35:08.322966Z","end":"2026-05-08T02:35:08.429266Z","steps":["trace[1235781073] 'read index received' (duration: 104.700175ms)","trace[1235781073] 'applied index is now lower than readState.Index' (duration: 1.598267ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:08.429472Z","caller":"traceutil/trace.go:171","msg":"trace[537042545] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"169.196768ms","start":"2026-05-08T02:35:08.260261Z","end":"2026-05-08T02:35:08.429457Z","steps":["trace[537042545] 'process raft request' (duration: 168.711216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:08.429777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.787404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jzd8p\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-05-08T02:35:08.429911Z","caller":"traceutil/trace.go:171","msg":"trace[582476535] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"106.270192ms","start":"2026-05-08T02:35:08.323619Z","end":"2026-05-08T02:35:08.429889Z","steps":["trace[582476535] 'process raft request' (duration: 105.533864ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:08.429885Z","caller":"traceutil/trace.go:171","msg":"trace[863583225] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jzd8p; range_end:; response_count:1; response_revision:690; }","duration":"106.928887ms","start":"2026-05-08T02:35:08.322925Z","end":"2026-05-08T02:35:08.429854Z","steps":["trace[863583225] 'agreement among raft nodes before linearized reading' (duration: 106.538878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:08.797172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.165825ms","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-08T02:35:08.797303Z","caller":"traceutil/trace.go:171","msg":"trace[263817213] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:690; }","duration":"203.310148ms","start":"2026-05-08T02:35:08.593977Z","end":"2026-05-08T02:35:08.797287Z","steps":["trace[263817213] 'range keys from in-memory index tree' (duration: 203.149805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:08.797758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.38138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:35:08.797888Z","caller":"traceutil/trace.go:171","msg":"trace[21206265] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"354.77897ms","start":"2026-05-08T02:35:08.44308Z","end":"2026-05-08T02:35:08.797859Z","steps":["trace[21206265] 'process raft request' (duration: 203.177235ms)","trace[21206265] 'compare' (duration: 151.150444ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:08.797959Z","caller":"traceutil/trace.go:171","msg":"trace[1389852857] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"176.97099ms","start":"2026-05-08T02:35:08.620862Z","end":"2026-05-08T02:35:08.797833Z","steps":["trace[1389852857] 'read index received' (duration: 25.393746ms)","trace[1389852857] 'applied index is now lower than readState.Index' (duration: 151.576014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:35:08.79805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:08.443056Z","time spent":"354.903083ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:35:08.798144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.256867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-08T02:35:08.79824Z","caller":"traceutil/trace.go:171","msg":"trace[963150310] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"177.39696ms","start":"2026-05-08T02:35:08.620822Z","end":"2026-05-08T02:35:08.798219Z","steps":["trace[963150310] 'agreement among raft nodes before linearized reading' (duration: 177.153914ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:17.973788Z","caller":"traceutil/trace.go:171","msg":"trace[485629158] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"227.97217ms","start":"2026-05-08T02:35:17.745791Z","end":"2026-05-08T02:35:17.973763Z","steps":["trace[485629158] 'read index received' (duration: 227.749865ms)","trace[485629158] 'applied index is now lower than readState.Index' (duration: 221.165µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:17.973846Z","caller":"traceutil/trace.go:171","msg":"trace[788000632] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"236.064438ms","start":"2026-05-08T02:35:17.737746Z","end":"2026-05-08T02:35:17.97381Z","steps":["trace[788000632] 'process raft request' (duration: 235.867763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:17.974144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.226592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-08T02:35:17.974149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.006221ms","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-08T02:35:17.974188Z","caller":"traceutil/trace.go:171","msg":"trace[2016818784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"227.301684ms","start":"2026-05-08T02:35:17.746874Z","end":"2026-05-08T02:35:17.974176Z","steps":["trace[2016818784] 'agreement among raft nodes before linearized reading' (duration: 227.199872ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:17.974211Z","caller":"traceutil/trace.go:171","msg":"trace[1171569147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"228.090973ms","start":"2026-05-08T02:35:17.746107Z","end":"2026-05-08T02:35:17.974198Z","steps":["trace[1171569147] 'agreement among raft nodes before linearized reading' (duration: 227.939449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:17.974031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.209966ms","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-08T02:35:17.97437Z","caller":"traceutil/trace.go:171","msg":"trace[1773989130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"228.487632ms","start":"2026-05-08T02:35:17.745773Z","end":"2026-05-08T02:35:17.974261Z","steps":["trace[1773989130] 'agreement among raft nodes before linearized reading' (duration: 228.121123ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:18.163014Z","caller":"traceutil/trace.go:171","msg":"trace[1354172316] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"184.524016ms","start":"2026-05-08T02:35:17.978466Z","end":"2026-05-08T02:35:18.16299Z","steps":["trace[1354172316] 'process raft request' (duration: 173.2078ms)","trace[1354172316] 'compare' (duration: 11.204244ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:18.207985Z","caller":"traceutil/trace.go:171","msg":"trace[129706681] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"229.080057ms","start":"2026-05-08T02:35:17.978881Z","end":"2026-05-08T02:35:18.207961Z","steps":["trace[129706681] 'process raft request' (duration: 229.037376ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:18.208036Z","caller":"traceutil/trace.go:171","msg":"trace[917701408] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"229.389624ms","start":"2026-05-08T02:35:17.978617Z","end":"2026-05-08T02:35:18.208007Z","steps":["trace[917701408] 'process raft request' (duration: 229.181779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:21.445733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628038007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:35:21.781162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051486327s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T02:35:21.895186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.761685ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-08T02:35:21.915868Z","caller":"traceutil/trace.go:171","msg":"trace[843230370] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"971.050868ms","start":"2026-05-08T02:35:20.944788Z","end":"2026-05-08T02:35:21.915839Z","steps":["trace[843230370] 'read index received' (duration: 836.623718ms)","trace[843230370] 'applied index is now lower than readState.Index' (duration: 134.42567ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:21.91611Z","caller":"traceutil/trace.go:171","msg":"trace[88588857] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"509.960831ms","start":"2026-05-08T02:35:21.406119Z","end":"2026-05-08T02:35:21.91608Z","steps":["trace[88588857] 'process raft request' (duration: 509.535411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:21.916262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:21.406093Z","time spent":"510.087454ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:35:21.916304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.888811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:21.91655Z","caller":"traceutil/trace.go:171","msg":"trace[1113654526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"182.172468ms","start":"2026-05-08T02:35:21.734328Z","end":"2026-05-08T02:35:21.916501Z","steps":["trace[1113654526] 'agreement among raft nodes before linearized reading' (duration: 181.896941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:21.916791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.99023ms","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-08T02:35:21.916863Z","caller":"traceutil/trace.go:171","msg":"trace[188993296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"972.112323ms","start":"2026-05-08T02:35:20.944735Z","end":"2026-05-08T02:35:21.916847Z","steps":["trace[188993296] 'agreement among raft nodes before linearized reading' (duration: 971.330644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:21.916913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:20.944713Z","time spent":"972.191376ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T02:35:21.916451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.105392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-ncb2g\" ","response":"range_response_count:1 size:5047"} {"level":"info","ts":"2026-05-08T02:35:21.917044Z","caller":"traceutil/trace.go:171","msg":"trace[960856234] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-ncb2g; range_end:; response_count:1; response_revision:788; }","duration":"266.887862ms","start":"2026-05-08T02:35:21.650134Z","end":"2026-05-08T02:35:21.917022Z","steps":["trace[960856234] 'agreement among raft nodes before linearized reading' (duration: 265.964789ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:50.357294Z","caller":"traceutil/trace.go:171","msg":"trace[72114316] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"117.459195ms","start":"2026-05-08T02:35:50.239808Z","end":"2026-05-08T02:35:50.357267Z","steps":["trace[72114316] 'process raft request' (duration: 117.084206ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:56.556358Z","caller":"traceutil/trace.go:171","msg":"trace[11780013] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"174.172593ms","start":"2026-05-08T02:35:56.382165Z","end":"2026-05-08T02:35:56.556338Z","steps":["trace[11780013] 'process raft request' (duration: 174.03678ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:56.654829Z","caller":"traceutil/trace.go:171","msg":"trace[152209803] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"270.802218ms","start":"2026-05-08T02:35:56.383985Z","end":"2026-05-08T02:35:56.654787Z","steps":["trace[152209803] 'process raft request' (duration: 270.4707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:58.690792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.846325921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:35:58.690856Z","caller":"traceutil/trace.go:171","msg":"trace[1967125258] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1016; }","duration":"1.846461544s","start":"2026-05-08T02:35:56.844382Z","end":"2026-05-08T02:35:58.690844Z","steps":["trace[1967125258] 'count revisions from in-memory index tree' (duration: 1.846205218s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:58.690885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:56.844352Z","time spent":"1.846525716s","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:35:58.691363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.897924ms","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-08T02:35:58.691424Z","caller":"traceutil/trace.go:171","msg":"trace[939203697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"131.035867ms","start":"2026-05-08T02:35:58.560373Z","end":"2026-05-08T02:35:58.691409Z","steps":["trace[939203697] 'range keys from in-memory index tree' (duration: 130.70025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.19432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628038492,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:35:59.316865Z","caller":"traceutil/trace.go:171","msg":"trace[286287662] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"635.70917ms","start":"2026-05-08T02:35:58.681133Z","end":"2026-05-08T02:35:59.316842Z","steps":["trace[286287662] 'process raft request' (duration: 635.533896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.31701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.681109Z","time spent":"635.828063ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:35:59.351056Z","caller":"traceutil/trace.go:171","msg":"trace[1530317402] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1055; }","duration":"657.607016ms","start":"2026-05-08T02:35:58.693429Z","end":"2026-05-08T02:35:59.351036Z","steps":["trace[1530317402] 'read index received' (duration: 623.416479ms)","trace[1530317402] 'applied index is now lower than readState.Index' (duration: 34.189247ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:35:59.351074Z","caller":"traceutil/trace.go:171","msg":"trace[1206043855] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"655.022043ms","start":"2026-05-08T02:35:58.696035Z","end":"2026-05-08T02:35:59.351057Z","steps":["trace[1206043855] 'process raft request' (duration: 654.86719ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:35:59.35115Z","caller":"traceutil/trace.go:171","msg":"trace[1050817749] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"654.997583ms","start":"2026-05-08T02:35:58.696115Z","end":"2026-05-08T02:35:59.351113Z","steps":["trace[1050817749] 'process raft request' (duration: 654.88456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.35131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.707454ms","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-08T02:35:59.351321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.696098Z","time spent":"655.130046ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:35:59.351339Z","caller":"traceutil/trace.go:171","msg":"trace[1064463725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"656.761126ms","start":"2026-05-08T02:35:58.69457Z","end":"2026-05-08T02:35:59.351331Z","steps":["trace[1064463725] 'agreement among raft nodes before linearized reading' (duration: 656.673504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.351315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.92866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:35:59.351317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.696024Z","time spent":"655.175627ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:35:59.351363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.694553Z","time spent":"656.805187ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T02:35:59.35138Z","caller":"traceutil/trace.go:171","msg":"trace[1704013213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"619.034542ms","start":"2026-05-08T02:35:58.73233Z","end":"2026-05-08T02:35:59.351365Z","steps":["trace[1704013213] 'agreement among raft nodes before linearized reading' (duration: 618.884068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.35142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.732318Z","time spent":"619.091154ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:35:59.351304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.190766ms","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-08T02:35:59.351465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.008067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:35:59.351499Z","caller":"traceutil/trace.go:171","msg":"trace[910395994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1019; }","duration":"657.464263ms","start":"2026-05-08T02:35:58.694023Z","end":"2026-05-08T02:35:59.351488Z","steps":["trace[910395994] 'agreement among raft nodes before linearized reading' (duration: 657.132835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.351543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.694011Z","time spent":"657.521914ms","remote":"127.0.0.1:58444","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-08T02:35:59.351552Z","caller":"traceutil/trace.go:171","msg":"trace[283646736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"658.15345ms","start":"2026-05-08T02:35:58.693375Z","end":"2026-05-08T02:35:59.351529Z","steps":["trace[283646736] 'agreement among raft nodes before linearized reading' (duration: 657.882343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:35:59.351598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:35:58.693356Z","time spent":"658.233151ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:36:15.771475Z","caller":"traceutil/trace.go:171","msg":"trace[1063735785] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"272.426707ms","start":"2026-05-08T02:36:15.499024Z","end":"2026-05-08T02:36:15.771451Z","steps":["trace[1063735785] 'read index received' (duration: 272.256293ms)","trace[1063735785] 'applied index is now lower than readState.Index' (duration: 168.814µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:15.771515Z","caller":"traceutil/trace.go:171","msg":"trace[548191482] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"274.491798ms","start":"2026-05-08T02:36:15.496989Z","end":"2026-05-08T02:36:15.771481Z","steps":["trace[548191482] 'process raft request' (duration: 274.292863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:15.771728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.676823ms","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-08T02:36:15.771794Z","caller":"traceutil/trace.go:171","msg":"trace[640752853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1070; }","duration":"272.773996ms","start":"2026-05-08T02:36:15.499007Z","end":"2026-05-08T02:36:15.771781Z","steps":["trace[640752853] 'agreement among raft nodes before linearized reading' (duration: 272.593122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:15.771796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.420637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:36:15.771847Z","caller":"traceutil/trace.go:171","msg":"trace[1907619110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"272.489838ms","start":"2026-05-08T02:36:15.499342Z","end":"2026-05-08T02:36:15.771832Z","steps":["trace[1907619110] 'agreement among raft nodes before linearized reading' (duration: 272.345625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:15.771877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.877434ms","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-08T02:36:15.77191Z","caller":"traceutil/trace.go:171","msg":"trace[2059577460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"271.941445ms","start":"2026-05-08T02:36:15.499959Z","end":"2026-05-08T02:36:15.7719Z","steps":["trace[2059577460] 'agreement among raft nodes before linearized reading' (duration: 271.829222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:15.771978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.038528ms","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-08T02:36:15.772001Z","caller":"traceutil/trace.go:171","msg":"trace[111895556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"272.070268ms","start":"2026-05-08T02:36:15.499923Z","end":"2026-05-08T02:36:15.771993Z","steps":["trace[111895556] 'agreement among raft nodes before linearized reading' (duration: 271.999857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:21.698142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628038679,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:36:21.933294Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.134317815s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T02:36:22.366504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.997489ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-08T02:36:22.512848Z","caller":"traceutil/trace.go:171","msg":"trace[209107407] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1131; }","duration":"1.315048778s","start":"2026-05-08T02:36:21.197768Z","end":"2026-05-08T02:36:22.512816Z","steps":["trace[209107407] 'read index received' (duration: 735.788029ms)","trace[209107407] 'applied index is now lower than readState.Index' (duration: 579.259549ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:22.512932Z","caller":"traceutil/trace.go:171","msg":"trace[1607546962] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.106179246s","start":"2026-05-08T02:36:21.406715Z","end":"2026-05-08T02:36:22.512895Z","steps":["trace[1607546962] 'process raft request' (duration: 1.10594819s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:22.513024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315225033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T02:36:22.513069Z","caller":"traceutil/trace.go:171","msg":"trace[141717608] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1090; }","duration":"1.315333265s","start":"2026-05-08T02:36:21.197723Z","end":"2026-05-08T02:36:22.513057Z","steps":["trace[141717608] 'agreement among raft nodes before linearized reading' (duration: 1.315239542s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:22.513112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.197706Z","time spent":"1.315395147s","remote":"127.0.0.1:59584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:36:22.51311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.406687Z","time spent":"1.106318959s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:22.513182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.105851ms","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-08T02:36:22.513236Z","caller":"traceutil/trace.go:171","msg":"trace[2031254924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"719.207424ms","start":"2026-05-08T02:36:21.794014Z","end":"2026-05-08T02:36:22.513221Z","steps":["trace[2031254924] 'agreement among raft nodes before linearized reading' (duration: 719.012119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:22.513273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.793993Z","time spent":"719.270136ms","remote":"127.0.0.1:58444","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-08T02:36:22.51334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.667168ms","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-08T02:36:22.51339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.228007ms","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-08T02:36:22.513446Z","caller":"traceutil/trace.go:171","msg":"trace[79143751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"628.814692ms","start":"2026-05-08T02:36:21.884603Z","end":"2026-05-08T02:36:22.513418Z","steps":["trace[79143751] 'agreement among raft nodes before linearized reading' (duration: 628.484273ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:22.513472Z","caller":"traceutil/trace.go:171","msg":"trace[1187790504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"628.34954ms","start":"2026-05-08T02:36:21.885102Z","end":"2026-05-08T02:36:22.513452Z","steps":["trace[1187790504] 'agreement among raft nodes before linearized reading' (duration: 628.074174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:22.513499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.884584Z","time spent":"628.905753ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:22.513522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.885089Z","time spent":"628.424052ms","remote":"127.0.0.1:58444","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-08T02:36:22.513514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.920603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:36:22.51362Z","caller":"traceutil/trace.go:171","msg":"trace[2873754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"649.065137ms","start":"2026-05-08T02:36:21.864536Z","end":"2026-05-08T02:36:22.513602Z","steps":["trace[2873754] 'agreement among raft nodes before linearized reading' (duration: 648.575775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:22.513674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.864519Z","time spent":"649.1421ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:22.513941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.639221ms","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-08T02:36:22.514003Z","caller":"traceutil/trace.go:171","msg":"trace[477889411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"649.737563ms","start":"2026-05-08T02:36:21.864249Z","end":"2026-05-08T02:36:22.513986Z","steps":["trace[477889411] 'agreement among raft nodes before linearized reading' (duration: 648.909203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:22.514045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:21.864219Z","time spent":"649.815226ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T02:36:24.923779Z","caller":"traceutil/trace.go:171","msg":"trace[1806287743] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"395.162532ms","start":"2026-05-08T02:36:24.52859Z","end":"2026-05-08T02:36:24.923753Z","steps":["trace[1806287743] 'read index received' (duration: 394.940657ms)","trace[1806287743] 'applied index is now lower than readState.Index' (duration: 220.295µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:24.924087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.013468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:36:24.924145Z","caller":"traceutil/trace.go:171","msg":"trace[1345308921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"185.135032ms","start":"2026-05-08T02:36:24.738996Z","end":"2026-05-08T02:36:24.924131Z","steps":["trace[1345308921] 'agreement among raft nodes before linearized reading' (duration: 185.015049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:24.924124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.712895ms","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-08T02:36:24.924115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.991227ms","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-08T02:36:24.924238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.304525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:36:24.92428Z","caller":"traceutil/trace.go:171","msg":"trace[2143143840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"301.822978ms","start":"2026-05-08T02:36:24.622395Z","end":"2026-05-08T02:36:24.924218Z","steps":["trace[2143143840] 'agreement among raft nodes before linearized reading' (duration: 301.559871ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:24.924324Z","caller":"traceutil/trace.go:171","msg":"trace[1787429784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"301.404988ms","start":"2026-05-08T02:36:24.622901Z","end":"2026-05-08T02:36:24.924306Z","steps":["trace[1787429784] 'agreement among raft nodes before linearized reading' (duration: 301.154782ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:24.92427Z","caller":"traceutil/trace.go:171","msg":"trace[881714268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"301.219652ms","start":"2026-05-08T02:36:24.62303Z","end":"2026-05-08T02:36:24.92425Z","steps":["trace[881714268] 'agreement among raft nodes before linearized reading' (duration: 300.930525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:24.924449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.622999Z","time spent":"301.440698ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:24.924396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.622167Z","time spent":"302.204957ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:24.924395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.622822Z","time spent":"301.549161ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:24.924561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.619752ms","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-08T02:36:24.924635Z","caller":"traceutil/trace.go:171","msg":"trace[813417678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"301.846838ms","start":"2026-05-08T02:36:24.62277Z","end":"2026-05-08T02:36:24.924617Z","steps":["trace[813417678] 'agreement among raft nodes before linearized reading' (duration: 301.333856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:24.9247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.622763Z","time spent":"301.92083ms","remote":"127.0.0.1:58444","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-08T02:36:24.924958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.357381ms","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-08T02:36:24.924935Z","caller":"traceutil/trace.go:171","msg":"trace[1030404479] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"404.299436ms","start":"2026-05-08T02:36:24.520513Z","end":"2026-05-08T02:36:24.924813Z","steps":["trace[1030404479] 'process raft request' (duration: 403.067256ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:24.925053Z","caller":"traceutil/trace.go:171","msg":"trace[56396930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"396.485395ms","start":"2026-05-08T02:36:24.528553Z","end":"2026-05-08T02:36:24.925039Z","steps":["trace[56396930] 'agreement among raft nodes before linearized reading' (duration: 395.305656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:24.925101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.528541Z","time spent":"396.549856ms","remote":"127.0.0.1:58444","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-08T02:36:24.925225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.520488Z","time spent":"404.611054ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:36:25.46178Z","caller":"traceutil/trace.go:171","msg":"trace[1309899117] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"531.225931ms","start":"2026-05-08T02:36:24.93053Z","end":"2026-05-08T02:36:25.461756Z","steps":["trace[1309899117] 'process raft request' (duration: 531.010196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:25.462011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.930504Z","time spent":"531.356785ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:36:25.638029Z","caller":"traceutil/trace.go:171","msg":"trace[957508954] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"707.184384ms","start":"2026-05-08T02:36:24.930792Z","end":"2026-05-08T02:36:25.637977Z","steps":["trace[957508954] 'process raft request' (duration: 706.759284ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:25.638067Z","caller":"traceutil/trace.go:171","msg":"trace[371962099] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"707.097801ms","start":"2026-05-08T02:36:24.930931Z","end":"2026-05-08T02:36:25.638029Z","steps":["trace[371962099] 'process raft request' (duration: 706.922157ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:25.638072Z","caller":"traceutil/trace.go:171","msg":"trace[1894541544] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"707.04023ms","start":"2026-05-08T02:36:24.930991Z","end":"2026-05-08T02:36:25.638031Z","steps":["trace[1894541544] 'process raft request' (duration: 706.918087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:25.638206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.930778Z","time spent":"707.340247ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:25.638297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.930899Z","time spent":"707.243565ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:25.638328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.930977Z","time spent":"707.210944ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:36:25.638501Z","caller":"traceutil/trace.go:171","msg":"trace[344831206] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1139; }","duration":"302.347326ms","start":"2026-05-08T02:36:25.336139Z","end":"2026-05-08T02:36:25.638486Z","steps":["trace[344831206] 'read index received' (duration: 125.536503ms)","trace[344831206] 'applied index is now lower than readState.Index' (duration: 176.809763ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:25.638606Z","caller":"traceutil/trace.go:171","msg":"trace[1247300892] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"707.408049ms","start":"2026-05-08T02:36:24.931157Z","end":"2026-05-08T02:36:25.638565Z","steps":["trace[1247300892] 'process raft request' (duration: 706.796484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:25.638648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.501139ms","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":"info","ts":"2026-05-08T02:36:25.638717Z","caller":"traceutil/trace.go:171","msg":"trace[1709149886] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1101; }","duration":"302.608842ms","start":"2026-05-08T02:36:25.336094Z","end":"2026-05-08T02:36:25.638703Z","steps":["trace[1709149886] 'agreement among raft nodes before linearized reading' (duration: 302.454358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:25.638754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:25.336072Z","time spent":"302.673093ms","remote":"127.0.0.1:58510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:36:25.638799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:24.931149Z","time spent":"707.556102ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:27.3029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.96725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:36:27.303746Z","caller":"traceutil/trace.go:171","msg":"trace[1670537286] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"372.139032ms","start":"2026-05-08T02:36:26.931017Z","end":"2026-05-08T02:36:27.303156Z","steps":["trace[1670537286] 'process raft request' (duration: 107.860325ms)","trace[1670537286] 'compare' (duration: 263.889468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:27.303991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:26.930991Z","time spent":"372.822299ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:36:40.174208Z","caller":"traceutil/trace.go:171","msg":"trace[2111179104] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"831.124685ms","start":"2026-05-08T02:36:39.343059Z","end":"2026-05-08T02:36:40.174184Z","steps":["trace[2111179104] 'process raft request' (duration: 830.986982ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:40.174891Z","caller":"traceutil/trace.go:171","msg":"trace[1465272026] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1242; }","duration":"478.721659ms","start":"2026-05-08T02:36:39.696143Z","end":"2026-05-08T02:36:40.174865Z","steps":["trace[1465272026] 'read index received' (duration: 478.711528ms)","trace[1465272026] 'applied index is now lower than readState.Index' (duration: 8.27µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:40.174952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.343043Z","time spent":"831.818851ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:41.026911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330766526s","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-08T02:36:41.026927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092252287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T02:36:41.026977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295521374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:36:41.026982Z","caller":"traceutil/trace.go:171","msg":"trace[1182491809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"1.330874899s","start":"2026-05-08T02:36:39.696089Z","end":"2026-05-08T02:36:41.026964Z","steps":["trace[1182491809] 'agreement among raft nodes before linearized reading' (duration: 478.912143ms)","trace[1182491809] 'range keys from in-memory index tree' (duration: 851.806802ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:41.027008Z","caller":"traceutil/trace.go:171","msg":"trace[1620628851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"1.295568655s","start":"2026-05-08T02:36:39.731427Z","end":"2026-05-08T02:36:41.026996Z","steps":["trace[1620628851] 'agreement among raft nodes before linearized reading' (duration: 443.61062ms)","trace[1620628851] 'range keys from in-memory index tree' (duration: 851.929455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:41.026926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259897483s","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-08T02:36:41.027016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.696066Z","time spent":"1.330943561s","remote":"127.0.0.1:58444","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-08T02:36:41.027036Z","caller":"traceutil/trace.go:171","msg":"trace[1858144086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"1.260051967s","start":"2026-05-08T02:36:39.766976Z","end":"2026-05-08T02:36:41.027028Z","steps":["trace[1858144086] 'agreement among raft nodes before linearized reading' (duration: 408.05617ms)","trace[1858144086] 'range keys from in-memory index tree' (duration: 851.779601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:41.027039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.731416Z","time spent":"1.295617506s","remote":"127.0.0.1:58170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:36:41.027035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328614885s","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-08T02:36:41.027025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258617383s","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-08T02:36:41.027116Z","caller":"traceutil/trace.go:171","msg":"trace[850350427] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"684.888263ms","start":"2026-05-08T02:36:40.342222Z","end":"2026-05-08T02:36:41.02711Z","steps":["trace[850350427] 'process raft request' (duration: 681.931863ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:41.027117Z","caller":"traceutil/trace.go:171","msg":"trace[1432464863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"1.258716256s","start":"2026-05-08T02:36:39.76838Z","end":"2026-05-08T02:36:41.027096Z","steps":["trace[1432464863] 'agreement among raft nodes before linearized reading' (duration: 406.640127ms)","trace[1432464863] 'range keys from in-memory index tree' (duration: 851.953806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:41.027166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.768368Z","time spent":"1.258788887s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:41.027173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:40.342194Z","time spent":"684.939065ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:36:41.027002Z","caller":"traceutil/trace.go:171","msg":"trace[1983738795] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1194; }","duration":"1.092347759s","start":"2026-05-08T02:36:39.934632Z","end":"2026-05-08T02:36:41.02698Z","steps":["trace[1983738795] 'agreement among raft nodes before linearized reading' (duration: 240.379423ms)","trace[1983738795] 'count revisions from in-memory index tree' (duration: 851.848393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:41.027241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.934563Z","time spent":"1.092671487s","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:36:41.027066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.766953Z","time spent":"1.260104168s","remote":"127.0.0.1:58444","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-08T02:36:41.026916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258494741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:36:41.027348Z","caller":"traceutil/trace.go:171","msg":"trace[1333377629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"1.258976191s","start":"2026-05-08T02:36:39.768362Z","end":"2026-05-08T02:36:41.027338Z","steps":["trace[1333377629] 'agreement among raft nodes before linearized reading' (duration: 406.664857ms)","trace[1333377629] 'range keys from in-memory index tree' (duration: 851.70875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:41.027375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.768351Z","time spent":"1.259016872s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:36:41.027074Z","caller":"traceutil/trace.go:171","msg":"trace[196578100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"1.328693397s","start":"2026-05-08T02:36:39.698369Z","end":"2026-05-08T02:36:41.027063Z","steps":["trace[196578100] 'agreement among raft nodes before linearized reading' (duration: 476.615558ms)","trace[196578100] 'range keys from in-memory index tree' (duration: 851.992696ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:41.027426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:39.698349Z","time spent":"1.329071536s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T02:36:41.027505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.844494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:36:41.027539Z","caller":"traceutil/trace.go:171","msg":"trace[623416327] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1194; }","duration":"705.641093ms","start":"2026-05-08T02:36:40.32189Z","end":"2026-05-08T02:36:41.027531Z","steps":["trace[623416327] 'count revisions from in-memory index tree' (duration: 704.768832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:41.027564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:40.321871Z","time spent":"705.686204ms","remote":"127.0.0.1:44042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:36:47.214514Z","caller":"traceutil/trace.go:171","msg":"trace[540906135] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"131.671788ms","start":"2026-05-08T02:36:47.082812Z","end":"2026-05-08T02:36:47.214484Z","steps":["trace[540906135] 'read index received' (duration: 131.536915ms)","trace[540906135] 'applied index is now lower than readState.Index' (duration: 133.523µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:47.214653Z","caller":"traceutil/trace.go:171","msg":"trace[1053978119] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"144.108331ms","start":"2026-05-08T02:36:47.070504Z","end":"2026-05-08T02:36:47.214612Z","steps":["trace[1053978119] 'process raft request' (duration: 143.796544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:47.214738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.906374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-08T02:36:47.214789Z","caller":"traceutil/trace.go:171","msg":"trace[1479120618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"131.990555ms","start":"2026-05-08T02:36:47.082786Z","end":"2026-05-08T02:36:47.214777Z","steps":["trace[1479120618] 'agreement among raft nodes before linearized reading' (duration: 131.815411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:47.214834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.705438ms","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-08T02:36:47.214904Z","caller":"traceutil/trace.go:171","msg":"trace[1606373116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"128.851512ms","start":"2026-05-08T02:36:47.086037Z","end":"2026-05-08T02:36:47.214888Z","steps":["trace[1606373116] 'agreement among raft nodes before linearized reading' (duration: 128.637507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:47.214959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.613665ms","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-08T02:36:47.214835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.778036ms","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-08T02:36:47.215019Z","caller":"traceutil/trace.go:171","msg":"trace[590772477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"125.682996ms","start":"2026-05-08T02:36:47.089321Z","end":"2026-05-08T02:36:47.215004Z","steps":["trace[590772477] 'agreement among raft nodes before linearized reading' (duration: 125.562013ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:47.215043Z","caller":"traceutil/trace.go:171","msg":"trace[1603054178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"125.067762ms","start":"2026-05-08T02:36:47.089969Z","end":"2026-05-08T02:36:47.215037Z","steps":["trace[1603054178] 'agreement among raft nodes before linearized reading' (duration: 124.734544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:47.21508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.453194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-08T02:36:47.215139Z","caller":"traceutil/trace.go:171","msg":"trace[1091011652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"126.548967ms","start":"2026-05-08T02:36:47.08856Z","end":"2026-05-08T02:36:47.215109Z","steps":["trace[1091011652] 'agreement among raft nodes before linearized reading' (duration: 126.335982ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:47.349029Z","caller":"traceutil/trace.go:171","msg":"trace[1309545941] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"128.206906ms","start":"2026-05-08T02:36:47.220791Z","end":"2026-05-08T02:36:47.348998Z","steps":["trace[1309545941] 'process raft request' (duration: 115.464335ms)","trace[1309545941] 'compare' (duration: 12.543296ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:47.34936Z","caller":"traceutil/trace.go:171","msg":"trace[533021724] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"128.035981ms","start":"2026-05-08T02:36:47.221292Z","end":"2026-05-08T02:36:47.349328Z","steps":["trace[533021724] 'process raft request' (duration: 127.659192ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:47.34936Z","caller":"traceutil/trace.go:171","msg":"trace[768139949] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"127.492648ms","start":"2026-05-08T02:36:47.221839Z","end":"2026-05-08T02:36:47.349332Z","steps":["trace[768139949] 'process raft request' (duration: 127.346405ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:47.34942Z","caller":"traceutil/trace.go:171","msg":"trace[1586286092] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"125.222765ms","start":"2026-05-08T02:36:47.224187Z","end":"2026-05-08T02:36:47.349409Z","steps":["trace[1586286092] 'process raft request' (duration: 125.180314ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:47.349518Z","caller":"traceutil/trace.go:171","msg":"trace[375121383] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"127.370115ms","start":"2026-05-08T02:36:47.22213Z","end":"2026-05-08T02:36:47.3495Z","steps":["trace[375121383] 'process raft request' (duration: 127.15923ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:53.562364Z","caller":"traceutil/trace.go:171","msg":"trace[68742910] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"174.665972ms","start":"2026-05-08T02:36:53.387674Z","end":"2026-05-08T02:36:53.56234Z","steps":["trace[68742910] 'read index received' (duration: 174.489158ms)","trace[68742910] 'applied index is now lower than readState.Index' (duration: 175.774µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:53.562396Z","caller":"traceutil/trace.go:171","msg":"trace[609378365] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"177.744185ms","start":"2026-05-08T02:36:53.384615Z","end":"2026-05-08T02:36:53.562359Z","steps":["trace[609378365] 'process raft request' (duration: 177.562821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:53.562505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.804546ms","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-08T02:36:53.562575Z","caller":"traceutil/trace.go:171","msg":"trace[268358547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"174.921798ms","start":"2026-05-08T02:36:53.38764Z","end":"2026-05-08T02:36:53.562562Z","steps":["trace[268358547] 'agreement among raft nodes before linearized reading' (duration: 174.720433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:53.56261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.914868ms","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-08T02:36:53.562698Z","caller":"traceutil/trace.go:171","msg":"trace[6667062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"175.072782ms","start":"2026-05-08T02:36:53.387611Z","end":"2026-05-08T02:36:53.562684Z","steps":["trace[6667062] 'agreement among raft nodes before linearized reading' (duration: 174.852896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:53.56271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.578061ms","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-08T02:36:53.562753Z","caller":"traceutil/trace.go:171","msg":"trace[547627840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"174.655122ms","start":"2026-05-08T02:36:53.388087Z","end":"2026-05-08T02:36:53.562742Z","steps":["trace[547627840] 'agreement among raft nodes before linearized reading' (duration: 174.456517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:53.563051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.291587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:36:53.563085Z","caller":"traceutil/trace.go:171","msg":"trace[1787231303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"175.338088ms","start":"2026-05-08T02:36:53.387736Z","end":"2026-05-08T02:36:53.563074Z","steps":["trace[1787231303] 'agreement among raft nodes before linearized reading' (duration: 174.750064ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:53.687379Z","caller":"traceutil/trace.go:171","msg":"trace[2142521656] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"118.505967ms","start":"2026-05-08T02:36:53.568851Z","end":"2026-05-08T02:36:53.687357Z","steps":["trace[2142521656] 'process raft request' (duration: 104.138058ms)","trace[2142521656] 'compare' (duration: 14.063152ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:53.727292Z","caller":"traceutil/trace.go:171","msg":"trace[320025050] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"157.814735ms","start":"2026-05-08T02:36:53.569457Z","end":"2026-05-08T02:36:53.727272Z","steps":["trace[320025050] 'process raft request' (duration: 157.777084ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:53.727347Z","caller":"traceutil/trace.go:171","msg":"trace[1886274995] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"157.889046ms","start":"2026-05-08T02:36:53.569426Z","end":"2026-05-08T02:36:53.727315Z","steps":["trace[1886274995] 'process raft request' (duration: 157.65813ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:53.727516Z","caller":"traceutil/trace.go:171","msg":"trace[1890211488] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"158.03646ms","start":"2026-05-08T02:36:53.569458Z","end":"2026-05-08T02:36:53.727494Z","steps":["trace[1890211488] 'process raft request' (duration: 157.738753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:55.431941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.469294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-08T02:36:55.432014Z","caller":"traceutil/trace.go:171","msg":"trace[1469991620] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1242; }","duration":"323.598538ms","start":"2026-05-08T02:36:55.108401Z","end":"2026-05-08T02:36:55.432Z","steps":["trace[1469991620] 'range keys from in-memory index tree' (duration: 323.27951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:55.432045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:55.108376Z","time spent":"323.662198ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5355,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-08T02:36:57.604632Z","caller":"traceutil/trace.go:171","msg":"trace[278971917] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1314; }","duration":"146.430797ms","start":"2026-05-08T02:36:57.458178Z","end":"2026-05-08T02:36:57.604609Z","steps":["trace[278971917] 'read index received' (duration: 146.421036ms)","trace[278971917] 'applied index is now lower than readState.Index' (duration: 8.271µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:57.60464Z","caller":"traceutil/trace.go:171","msg":"trace[1248640426] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"243.752182ms","start":"2026-05-08T02:36:57.360852Z","end":"2026-05-08T02:36:57.604604Z","steps":["trace[1248640426] 'process raft request' (duration: 243.575218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:57.60476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.56973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:36:57.60478Z","caller":"traceutil/trace.go:171","msg":"trace[604294550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1262; }","duration":"146.649762ms","start":"2026-05-08T02:36:57.458125Z","end":"2026-05-08T02:36:57.604775Z","steps":["trace[604294550] 'agreement among raft nodes before linearized reading' (duration: 146.557799ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:57.691438Z","caller":"traceutil/trace.go:171","msg":"trace[923742753] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"190.681911ms","start":"2026-05-08T02:36:57.500671Z","end":"2026-05-08T02:36:57.691352Z","steps":["trace[923742753] 'process raft request' (duration: 163.058929ms)","trace[923742753] 'compare' (duration: 27.384356ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:57.967139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.541276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:36:57.967278Z","caller":"traceutil/trace.go:171","msg":"trace[2076867687] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1315; }","duration":"266.09535ms","start":"2026-05-08T02:36:57.701169Z","end":"2026-05-08T02:36:57.967264Z","steps":["trace[2076867687] 'read index received' (duration: 92.36381ms)","trace[2076867687] 'applied index is now lower than readState.Index' (duration: 173.73082ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:57.967354Z","caller":"traceutil/trace.go:171","msg":"trace[1481484175] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"271.765594ms","start":"2026-05-08T02:36:57.695581Z","end":"2026-05-08T02:36:57.967347Z","steps":["trace[1481484175] 'process raft request' (duration: 97.956852ms)","trace[1481484175] 'compare' (duration: 173.456674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:57.967532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.349986ms","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-08T02:36:57.967579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.720013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-n6nsx\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-08T02:36:57.96755Z","caller":"traceutil/trace.go:171","msg":"trace[1932181034] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"271.906307ms","start":"2026-05-08T02:36:57.695618Z","end":"2026-05-08T02:36:57.967524Z","steps":["trace[1932181034] 'process raft request' (duration: 271.60423ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:57.967601Z","caller":"traceutil/trace.go:171","msg":"trace[1614835455] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-n6nsx; range_end:; response_count:1; response_revision:1265; }","duration":"232.775264ms","start":"2026-05-08T02:36:57.734819Z","end":"2026-05-08T02:36:57.967594Z","steps":["trace[1614835455] 'agreement among raft nodes before linearized reading' (duration: 232.678372ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:57.967595Z","caller":"traceutil/trace.go:171","msg":"trace[887574205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"266.49144ms","start":"2026-05-08T02:36:57.701088Z","end":"2026-05-08T02:36:57.967579Z","steps":["trace[887574205] 'agreement among raft nodes before linearized reading' (duration: 266.266274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:57.967748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.611639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:36:57.967779Z","caller":"traceutil/trace.go:171","msg":"trace[18293060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"226.674129ms","start":"2026-05-08T02:36:57.741099Z","end":"2026-05-08T02:36:57.967773Z","steps":["trace[18293060] 'agreement among raft nodes before linearized reading' (duration: 226.609598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:57.967949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.877265ms","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-08T02:36:57.967986Z","caller":"traceutil/trace.go:171","msg":"trace[1234497131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"226.947036ms","start":"2026-05-08T02:36:57.741028Z","end":"2026-05-08T02:36:57.967975Z","steps":["trace[1234497131] 'agreement among raft nodes before linearized reading' (duration: 226.805583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:57.968036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.925345ms","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-08T02:36:57.968059Z","caller":"traceutil/trace.go:171","msg":"trace[73921123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"226.963397ms","start":"2026-05-08T02:36:57.741089Z","end":"2026-05-08T02:36:57.968053Z","steps":["trace[73921123] 'agreement among raft nodes before linearized reading' (duration: 226.899715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:58.705208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628039072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:36:59.20623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628039072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:36:59.237449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.62771ms","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-08T02:36:59.23755Z","caller":"traceutil/trace.go:171","msg":"trace[857940514] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1271; }","duration":"643.743113ms","start":"2026-05-08T02:36:58.593793Z","end":"2026-05-08T02:36:59.237536Z","steps":["trace[857940514] 'range keys from in-memory index tree' (duration: 643.61375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:59.27514Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.242422821s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:36:59.275916Z","caller":"traceutil/trace.go:171","msg":"trace[982255639] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.248972856s","start":"2026-05-08T02:36:58.026923Z","end":"2026-05-08T02:36:59.275896Z","steps":["trace[982255639] 'process raft request' (duration: 1.248920115s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:36:59.275961Z","caller":"traceutil/trace.go:171","msg":"trace[1117206259] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.254102857s","start":"2026-05-08T02:36:58.021821Z","end":"2026-05-08T02:36:59.275924Z","steps":["trace[1117206259] 'process raft request' (duration: 1.25384003s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:59.276027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:58.026913Z","time spent":"1.249049758s","remote":"127.0.0.1:58636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:59.276127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:58.021802Z","time spent":"1.254232839s","remote":"127.0.0.1:58454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:36:59.458249Z","caller":"traceutil/trace.go:171","msg":"trace[1992195485] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1323; }","duration":"1.253792499s","start":"2026-05-08T02:36:58.204431Z","end":"2026-05-08T02:36:59.458224Z","steps":["trace[1992195485] 'read index received' (duration: 1.071181529s)","trace[1992195485] 'applied index is now lower than readState.Index' (duration: 182.61022ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:36:59.458343Z","caller":"traceutil/trace.go:171","msg":"trace[580650846] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.188067969s","start":"2026-05-08T02:36:58.270266Z","end":"2026-05-08T02:36:59.458334Z","steps":["trace[580650846] 'process raft request' (duration: 1.129686191s)","trace[580650846] 'compare' (duration: 58.201523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:36:59.458431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:58.270239Z","time spent":"1.18812521s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:36:59.458538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.649218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:36:59.458564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254090516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:36:59.458599Z","caller":"traceutil/trace.go:171","msg":"trace[473947666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"723.768551ms","start":"2026-05-08T02:36:58.734815Z","end":"2026-05-08T02:36:59.458584Z","steps":["trace[473947666] 'agreement among raft nodes before linearized reading' (duration: 723.655759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:59.458637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:58.734801Z","time spent":"723.829843ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:36:59.458638Z","caller":"traceutil/trace.go:171","msg":"trace[597955613] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1274; }","duration":"1.254226799s","start":"2026-05-08T02:36:58.204393Z","end":"2026-05-08T02:36:59.45862Z","steps":["trace[597955613] 'agreement among raft nodes before linearized reading' (duration: 1.254057155s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:59.458687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:58.204375Z","time spent":"1.254302152s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:36:59.458939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.019886ms","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-08T02:36:59.458972Z","caller":"traceutil/trace.go:171","msg":"trace[776669208] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1274; }","duration":"782.098337ms","start":"2026-05-08T02:36:58.676866Z","end":"2026-05-08T02:36:59.458964Z","steps":["trace[776669208] 'agreement among raft nodes before linearized reading' (duration: 781.552195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:36:59.458993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:36:58.67682Z","time spent":"782.16788ms","remote":"127.0.0.1:59596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:37:00.170997Z","caller":"traceutil/trace.go:171","msg":"trace[1998576848] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"199.844046ms","start":"2026-05-08T02:36:59.97113Z","end":"2026-05-08T02:37:00.170974Z","steps":["trace[1998576848] 'read index received' (duration: 199.60158ms)","trace[1998576848] 'applied index is now lower than readState.Index' (duration: 241.546µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:00.171483Z","caller":"traceutil/trace.go:171","msg":"trace[467189993] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"222.336476ms","start":"2026-05-08T02:36:59.94912Z","end":"2026-05-08T02:37:00.171457Z","steps":["trace[467189993] 'process raft request' (duration: 221.712922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:00.171817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.152669ms","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-08T02:37:00.171902Z","caller":"traceutil/trace.go:171","msg":"trace[1890060692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"182.287492ms","start":"2026-05-08T02:36:59.989597Z","end":"2026-05-08T02:37:00.171885Z","steps":["trace[1890060692] 'agreement among raft nodes before linearized reading' (duration: 182.055887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:00.172203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.015454ms","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-08T02:37:00.172244Z","caller":"traceutil/trace.go:171","msg":"trace[1482049579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"151.097786ms","start":"2026-05-08T02:37:00.021136Z","end":"2026-05-08T02:37:00.172234Z","steps":["trace[1482049579] 'agreement among raft nodes before linearized reading' (duration: 150.987463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:00.172453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.847144ms","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-08T02:37:00.172498Z","caller":"traceutil/trace.go:171","msg":"trace[1659971979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"151.918736ms","start":"2026-05-08T02:37:00.020564Z","end":"2026-05-08T02:37:00.172483Z","steps":["trace[1659971979] 'agreement among raft nodes before linearized reading' (duration: 151.829043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:00.173636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.395569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:37:00.173698Z","caller":"traceutil/trace.go:171","msg":"trace[345252285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"150.466641ms","start":"2026-05-08T02:37:00.023215Z","end":"2026-05-08T02:37:00.173681Z","steps":["trace[345252285] 'agreement among raft nodes before linearized reading' (duration: 148.628187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:00.173759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.588972ms","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-08T02:37:00.17412Z","caller":"traceutil/trace.go:171","msg":"trace[1337839972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"203.007191ms","start":"2026-05-08T02:36:59.971095Z","end":"2026-05-08T02:37:00.174102Z","steps":["trace[1337839972] 'agreement among raft nodes before linearized reading' (duration: 202.391286ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:00.278963Z","caller":"traceutil/trace.go:171","msg":"trace[1885877540] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"101.27979ms","start":"2026-05-08T02:37:00.177659Z","end":"2026-05-08T02:37:00.278939Z","steps":["trace[1885877540] 'process raft request' (duration: 101.210599ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:00.279218Z","caller":"traceutil/trace.go:171","msg":"trace[675051295] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"101.686601ms","start":"2026-05-08T02:37:00.177518Z","end":"2026-05-08T02:37:00.279205Z","steps":["trace[675051295] 'process raft request' (duration: 58.286706ms)","trace[675051295] 'compare' (duration: 42.946624ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:00.360501Z","caller":"traceutil/trace.go:171","msg":"trace[1355899116] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"135.791685ms","start":"2026-05-08T02:37:00.224688Z","end":"2026-05-08T02:37:00.36048Z","steps":["trace[1355899116] 'process raft request' (duration: 135.694263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:01.761724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.103461ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-08T02:37:01.766067Z","caller":"traceutil/trace.go:171","msg":"trace[1775444256] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"299.245393ms","start":"2026-05-08T02:37:01.466787Z","end":"2026-05-08T02:37:01.766033Z","steps":["trace[1775444256] 'process raft request' (duration: 299.021707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:02.196849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.35235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:37:02.196922Z","caller":"traceutil/trace.go:171","msg":"trace[1752707055] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1334; }","duration":"462.838913ms","start":"2026-05-08T02:37:01.734072Z","end":"2026-05-08T02:37:02.196911Z","steps":["trace[1752707055] 'read index received' (duration: 31.718629ms)","trace[1752707055] 'applied index is now lower than readState.Index' (duration: 431.119374ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:02.197014Z","caller":"traceutil/trace.go:171","msg":"trace[1736022581] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"548.573216ms","start":"2026-05-08T02:37:01.648423Z","end":"2026-05-08T02:37:02.196996Z","steps":["trace[1736022581] 'process raft request' (duration: 292.013982ms)","trace[1736022581] 'compare' (duration: 256.214336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:37:02.197364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.912154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:37:02.197394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:01.648404Z","time spent":"548.642688ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:37:02.197408Z","caller":"traceutil/trace.go:171","msg":"trace[570272722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"463.378715ms","start":"2026-05-08T02:37:01.734009Z","end":"2026-05-08T02:37:02.197387Z","steps":["trace[570272722] 'agreement among raft nodes before linearized reading' (duration: 462.943345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:02.197485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:01.733984Z","time spent":"463.493728ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:37:02.442695Z","caller":"traceutil/trace.go:171","msg":"trace[1734776946] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"154.764963ms","start":"2026-05-08T02:37:02.287907Z","end":"2026-05-08T02:37:02.442672Z","steps":["trace[1734776946] 'process raft request' (duration: 154.694061ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:02.442731Z","caller":"traceutil/trace.go:171","msg":"trace[1344752276] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"154.848415ms","start":"2026-05-08T02:37:02.287853Z","end":"2026-05-08T02:37:02.442701Z","steps":["trace[1344752276] 'process raft request' (duration: 154.63759ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:02.442905Z","caller":"traceutil/trace.go:171","msg":"trace[1445231932] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"154.013856ms","start":"2026-05-08T02:37:02.28882Z","end":"2026-05-08T02:37:02.442834Z","steps":["trace[1445231932] 'process raft request' (duration: 153.818691ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:02.590139Z","caller":"traceutil/trace.go:171","msg":"trace[342888491] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"141.51448ms","start":"2026-05-08T02:37:02.448585Z","end":"2026-05-08T02:37:02.590099Z","steps":["trace[342888491] 'process raft request' (duration: 42.79975ms)","trace[342888491] 'compare' (duration: 98.570257ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:03.015229Z","caller":"traceutil/trace.go:171","msg":"trace[1545485667] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"372.798158ms","start":"2026-05-08T02:37:02.642401Z","end":"2026-05-08T02:37:03.015199Z","steps":["trace[1545485667] 'process raft request' (duration: 372.591983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:03.015399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:02.64239Z","time spent":"372.922051ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:37:03.262133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.399389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:37:03.262262Z","caller":"traceutil/trace.go:171","msg":"trace[1373776774] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"580.006589ms","start":"2026-05-08T02:37:02.68222Z","end":"2026-05-08T02:37:03.262227Z","steps":["trace[1373776774] 'process raft request' (duration: 418.448836ms)","trace[1373776774] 'compare' (duration: 161.302527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:37:03.262349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:02.682203Z","time spent":"580.098051ms","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:37:04.064883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.812886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:37:04.065065Z","caller":"traceutil/trace.go:171","msg":"trace[346361199] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"293.582519ms","start":"2026-05-08T02:37:03.771469Z","end":"2026-05-08T02:37:04.065052Z","steps":["trace[346361199] 'process raft request' (duration: 293.498857ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:04.065181Z","caller":"traceutil/trace.go:171","msg":"trace[516337151] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"329.985507ms","start":"2026-05-08T02:37:03.735168Z","end":"2026-05-08T02:37:04.065153Z","steps":["trace[516337151] 'read index received' (duration: 57.051µs)","trace[516337151] 'applied index is now lower than readState.Index' (duration: 329.925896ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:04.065264Z","caller":"traceutil/trace.go:171","msg":"trace[333047352] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"799.001887ms","start":"2026-05-08T02:37:03.266234Z","end":"2026-05-08T02:37:04.065236Z","steps":["trace[333047352] 'process raft request' (duration: 384.779271ms)","trace[333047352] 'compare' (duration: 413.621762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:37:04.065347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.160402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:37:04.065383Z","caller":"traceutil/trace.go:171","msg":"trace[1180264369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"330.234053ms","start":"2026-05-08T02:37:03.735138Z","end":"2026-05-08T02:37:04.065372Z","steps":["trace[1180264369] 'agreement among raft nodes before linearized reading' (duration: 330.148231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:04.065387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:03.266212Z","time spent":"799.114699ms","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:37:04.065416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:03.735124Z","time spent":"330.284644ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:37:04.06547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.195851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:37:04.06555Z","caller":"traceutil/trace.go:171","msg":"trace[370547293] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1295; }","duration":"271.348704ms","start":"2026-05-08T02:37:03.794183Z","end":"2026-05-08T02:37:04.065532Z","steps":["trace[370547293] 'agreement among raft nodes before linearized reading' (duration: 271.202421ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:04.779604Z","caller":"traceutil/trace.go:171","msg":"trace[907150199] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"332.916027ms","start":"2026-05-08T02:37:04.446666Z","end":"2026-05-08T02:37:04.779582Z","steps":["trace[907150199] 'read index received' (duration: 332.709003ms)","trace[907150199] 'applied index is now lower than readState.Index' (duration: 206.074µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:04.779711Z","caller":"traceutil/trace.go:171","msg":"trace[906292138] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"390.872215ms","start":"2026-05-08T02:37:04.388828Z","end":"2026-05-08T02:37:04.7797Z","steps":["trace[906292138] 'process raft request' (duration: 390.570878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:04.779801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:04.388812Z","time spent":"390.923936ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:37:04.780019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.358768ms","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-08T02:37:04.780056Z","caller":"traceutil/trace.go:171","msg":"trace[1262180922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"333.426109ms","start":"2026-05-08T02:37:04.44662Z","end":"2026-05-08T02:37:04.780046Z","steps":["trace[1262180922] 'agreement among raft nodes before linearized reading' (duration: 333.283256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:04.780086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:04.446601Z","time spent":"333.47682ms","remote":"127.0.0.1:58444","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-08T02:37:04.780194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.302576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:37:04.780224Z","caller":"traceutil/trace.go:171","msg":"trace[1368790080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"330.352846ms","start":"2026-05-08T02:37:04.449861Z","end":"2026-05-08T02:37:04.780214Z","steps":["trace[1368790080] 'agreement among raft nodes before linearized reading' (duration: 330.273854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:04.780244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:04.449849Z","time spent":"330.389638ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:37:04.780256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.259385ms","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-08T02:37:04.78031Z","caller":"traceutil/trace.go:171","msg":"trace[1559500447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"330.358657ms","start":"2026-05-08T02:37:04.449936Z","end":"2026-05-08T02:37:04.780295Z","steps":["trace[1559500447] 'agreement among raft nodes before linearized reading' (duration: 330.203324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:04.780349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:04.449914Z","time spent":"330.424648ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T02:37:11.427637Z","caller":"traceutil/trace.go:171","msg":"trace[2035869211] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"138.288484ms","start":"2026-05-08T02:37:11.289318Z","end":"2026-05-08T02:37:11.427606Z","steps":["trace[2035869211] 'process raft request' (duration: 138.12529ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:11.953928Z","caller":"traceutil/trace.go:171","msg":"trace[1345875380] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"283.190344ms","start":"2026-05-08T02:37:11.670711Z","end":"2026-05-08T02:37:11.953901Z","steps":["trace[1345875380] 'read index received' (duration: 219.781677ms)","trace[1345875380] 'applied index is now lower than readState.Index' (duration: 63.407617ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:11.954057Z","caller":"traceutil/trace.go:171","msg":"trace[905971174] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"521.730753ms","start":"2026-05-08T02:37:11.432312Z","end":"2026-05-08T02:37:11.954042Z","steps":["trace[905971174] 'process raft request' (duration: 458.285316ms)","trace[905971174] 'compare' (duration: 63.182541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:37:11.954169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:11.432293Z","time spent":"521.800645ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:37:11.954329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.149895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:37:11.954358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.614763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:37:11.954414Z","caller":"traceutil/trace.go:171","msg":"trace[1048315506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"220.268408ms","start":"2026-05-08T02:37:11.734124Z","end":"2026-05-08T02:37:11.954393Z","steps":["trace[1048315506] 'agreement among raft nodes before linearized reading' (duration: 220.150256ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:11.954444Z","caller":"traceutil/trace.go:171","msg":"trace[1047321777] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1356; }","duration":"283.762248ms","start":"2026-05-08T02:37:11.670638Z","end":"2026-05-08T02:37:11.9544Z","steps":["trace[1047321777] 'agreement among raft nodes before linearized reading' (duration: 283.618514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:12.891816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628039236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:37:13.286785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195434372s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:37:13.287499Z","caller":"traceutil/trace.go:171","msg":"trace[1563545474] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.196254541s","start":"2026-05-08T02:37:12.091207Z","end":"2026-05-08T02:37:13.287462Z","steps":["trace[1563545474] 'process raft request' (duration: 1.196013886s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.287695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.091182Z","time spent":"1.196381204s","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:37:13.343987Z","caller":"traceutil/trace.go:171","msg":"trace[802833623] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1411; }","duration":"952.532619ms","start":"2026-05-08T02:37:12.391429Z","end":"2026-05-08T02:37:13.343961Z","steps":["trace[802833623] 'read index received' (duration: 895.847321ms)","trace[802833623] 'applied index is now lower than readState.Index' (duration: 56.684208ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:13.344038Z","caller":"traceutil/trace.go:171","msg":"trace[1871119702] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"843.142688ms","start":"2026-05-08T02:37:12.500863Z","end":"2026-05-08T02:37:13.344005Z","steps":["trace[1871119702] 'process raft request' (duration: 842.942823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.344232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.850932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:37:13.344277Z","caller":"traceutil/trace.go:171","msg":"trace[291664870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"609.915323ms","start":"2026-05-08T02:37:12.734351Z","end":"2026-05-08T02:37:13.344266Z","steps":["trace[291664870] 'agreement among raft nodes before linearized reading' (duration: 609.822881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.344289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.500826Z","time spent":"843.325502ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:37:13.34443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.583734ms","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-08T02:37:13.344466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.0865ms","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-08T02:37:13.344512Z","caller":"traceutil/trace.go:171","msg":"trace[875935065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"533.166392ms","start":"2026-05-08T02:37:12.811337Z","end":"2026-05-08T02:37:13.344503Z","steps":["trace[875935065] 'agreement among raft nodes before linearized reading' (duration: 533.008148ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:13.344518Z","caller":"traceutil/trace.go:171","msg":"trace[914724888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"503.726498ms","start":"2026-05-08T02:37:12.840773Z","end":"2026-05-08T02:37:13.344499Z","steps":["trace[914724888] 'agreement among raft nodes before linearized reading' (duration: 503.438741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.344545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.811309Z","time spent":"533.229944ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:37:13.344323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.7343Z","time spent":"610.015895ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:37:13.344511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.245804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-05-08T02:37:13.344577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.865931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-08T02:37:13.344438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.71626ms","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-08T02:37:13.344662Z","caller":"traceutil/trace.go:171","msg":"trace[1945721416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"512.996516ms","start":"2026-05-08T02:37:12.831656Z","end":"2026-05-08T02:37:13.344652Z","steps":["trace[1945721416] 'agreement among raft nodes before linearized reading' (duration: 512.614917ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:13.344658Z","caller":"traceutil/trace.go:171","msg":"trace[2050635891] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"316.451359ms","start":"2026-05-08T02:37:13.028174Z","end":"2026-05-08T02:37:13.344626Z","steps":["trace[2050635891] 'agreement among raft nodes before linearized reading' (duration: 316.117491ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:13.344668Z","caller":"traceutil/trace.go:171","msg":"trace[1909551506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"503.963453ms","start":"2026-05-08T02:37:12.840666Z","end":"2026-05-08T02:37:13.34463Z","steps":["trace[1909551506] 'agreement among raft nodes before linearized reading' (duration: 503.820519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.3447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.831633Z","time spent":"513.055967ms","remote":"127.0.0.1:58444","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-08T02:37:13.34472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:13.028152Z","time spent":"316.55386ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T02:37:13.344568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.840749Z","time spent":"503.809549ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T02:37:13.34476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.840645Z","time spent":"504.085426ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:37:13.345038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.055968ms","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-08T02:37:13.34543Z","caller":"traceutil/trace.go:171","msg":"trace[1773400564] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"566.451427ms","start":"2026-05-08T02:37:12.778921Z","end":"2026-05-08T02:37:13.345372Z","steps":["trace[1773400564] 'agreement among raft nodes before linearized reading' (duration: 565.527415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.346226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.778899Z","time spent":"567.304258ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T02:37:13.345081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.654516ms","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-08T02:37:13.346678Z","caller":"traceutil/trace.go:171","msg":"trace[583516837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"955.276354ms","start":"2026-05-08T02:37:12.391386Z","end":"2026-05-08T02:37:13.346663Z","steps":["trace[583516837] 'agreement among raft nodes before linearized reading' (duration: 952.763984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:13.34692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:37:12.391372Z","time spent":"955.52716ms","remote":"127.0.0.1:58444","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-08T02:37:33.54146Z","caller":"traceutil/trace.go:171","msg":"trace[1054356261] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1510; }","duration":"112.921642ms","start":"2026-05-08T02:37:33.428515Z","end":"2026-05-08T02:37:33.541436Z","steps":["trace[1054356261] 'read index received' (duration: 109.405049ms)","trace[1054356261] 'applied index is now lower than readState.Index' (duration: 3.515543ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:33.541616Z","caller":"traceutil/trace.go:171","msg":"trace[2113017467] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"122.835597ms","start":"2026-05-08T02:37:33.418763Z","end":"2026-05-08T02:37:33.541599Z","steps":["trace[2113017467] 'process raft request' (duration: 119.241702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:33.541828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.742185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:37:33.54194Z","caller":"traceutil/trace.go:171","msg":"trace[1944440782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1452; }","duration":"103.883018ms","start":"2026-05-08T02:37:33.438039Z","end":"2026-05-08T02:37:33.541922Z","steps":["trace[1944440782] 'agreement among raft nodes before linearized reading' (duration: 103.617402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:33.541977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.453605ms","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-08T02:37:33.542034Z","caller":"traceutil/trace.go:171","msg":"trace[1439255010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1452; }","duration":"113.532207ms","start":"2026-05-08T02:37:33.42849Z","end":"2026-05-08T02:37:33.542022Z","steps":["trace[1439255010] 'agreement among raft nodes before linearized reading' (duration: 113.431554ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:43.733604Z","caller":"traceutil/trace.go:171","msg":"trace[777239452] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"112.072343ms","start":"2026-05-08T02:37:43.621484Z","end":"2026-05-08T02:37:43.733556Z","steps":["trace[777239452] 'process raft request' (duration: 74.876902ms)","trace[777239452] 'compare' (duration: 37.051657ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:43.733991Z","caller":"traceutil/trace.go:171","msg":"trace[929725196] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"111.023137ms","start":"2026-05-08T02:37:43.622882Z","end":"2026-05-08T02:37:43.733906Z","steps":["trace[929725196] 'process raft request' (duration: 110.635998ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:43.868162Z","caller":"traceutil/trace.go:171","msg":"trace[1375413378] linearizableReadLoop","detail":"{readStateIndex:1563; appliedIndex:1562; }","duration":"130.132339ms","start":"2026-05-08T02:37:43.738008Z","end":"2026-05-08T02:37:43.868141Z","steps":["trace[1375413378] 'read index received' (duration: 128.694626ms)","trace[1375413378] 'applied index is now lower than readState.Index' (duration: 1.436613ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:37:43.86828Z","caller":"traceutil/trace.go:171","msg":"trace[946307430] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"130.301063ms","start":"2026-05-08T02:37:43.737952Z","end":"2026-05-08T02:37:43.868254Z","steps":["trace[946307430] 'process raft request' (duration: 128.737196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:37:43.868299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.264973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:37:43.868367Z","caller":"traceutil/trace.go:171","msg":"trace[1374699242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1502; }","duration":"130.384855ms","start":"2026-05-08T02:37:43.737965Z","end":"2026-05-08T02:37:43.86835Z","steps":["trace[1374699242] 'agreement among raft nodes before linearized reading' (duration: 130.275483ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:37:43.870826Z","caller":"traceutil/trace.go:171","msg":"trace[838726947] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"132.062155ms","start":"2026-05-08T02:37:43.738738Z","end":"2026-05-08T02:37:43.8708Z","steps":["trace[838726947] 'process raft request' (duration: 131.702307ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:38:03.67269Z","caller":"traceutil/trace.go:171","msg":"trace[1906816078] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"120.403649ms","start":"2026-05-08T02:38:03.552259Z","end":"2026-05-08T02:38:03.672662Z","steps":["trace[1906816078] 'process raft request' (duration: 51.526819ms)","trace[1906816078] 'compare' (duration: 68.508822ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:38:04.252931Z","caller":"traceutil/trace.go:171","msg":"trace[1389840067] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"174.601662ms","start":"2026-05-08T02:38:04.078298Z","end":"2026-05-08T02:38:04.252899Z","steps":["trace[1389840067] 'process raft request' (duration: 115.472873ms)","trace[1389840067] 'compare' (duration: 59.005346ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:38:04.618257Z","caller":"traceutil/trace.go:171","msg":"trace[27117457] linearizableReadLoop","detail":"{readStateIndex:1661; appliedIndex:1660; }","duration":"223.471378ms","start":"2026-05-08T02:38:04.394765Z","end":"2026-05-08T02:38:04.618236Z","steps":["trace[27117457] 'read index received' (duration: 212.073018ms)","trace[27117457] 'applied index is now lower than readState.Index' (duration: 11.39738ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:38:04.618508Z","caller":"traceutil/trace.go:171","msg":"trace[182035849] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"260.180857ms","start":"2026-05-08T02:38:04.358292Z","end":"2026-05-08T02:38:04.618473Z","steps":["trace[182035849] 'process raft request' (duration: 248.573552ms)","trace[182035849] 'compare' (duration: 10.592071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:38:04.618605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.834667ms","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:525"} {"level":"warn","ts":"2026-05-08T02:38:04.618667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.76758ms","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-08T02:38:04.618714Z","caller":"traceutil/trace.go:171","msg":"trace[1162879511] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1596; }","duration":"223.990821ms","start":"2026-05-08T02:38:04.394712Z","end":"2026-05-08T02:38:04.618703Z","steps":["trace[1162879511] 'agreement among raft nodes before linearized reading' (duration: 223.795356ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:38:04.618753Z","caller":"traceutil/trace.go:171","msg":"trace[49886612] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1596; }","duration":"167.897014ms","start":"2026-05-08T02:38:04.450831Z","end":"2026-05-08T02:38:04.618728Z","steps":["trace[49886612] 'agreement among raft nodes before linearized reading' (duration: 167.642058ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:38:05.159547Z","caller":"traceutil/trace.go:171","msg":"trace[1440624561] linearizableReadLoop","detail":"{readStateIndex:1663; appliedIndex:1662; }","duration":"211.193848ms","start":"2026-05-08T02:38:04.94833Z","end":"2026-05-08T02:38:05.159524Z","steps":["trace[1440624561] 'read index received' (duration: 211.002753ms)","trace[1440624561] 'applied index is now lower than readState.Index' (duration: 190.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:38:05.159627Z","caller":"traceutil/trace.go:171","msg":"trace[620530712] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"536.276851ms","start":"2026-05-08T02:38:04.623326Z","end":"2026-05-08T02:38:05.159603Z","steps":["trace[620530712] 'process raft request' (duration: 536.059516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:05.159725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.356272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:38:05.15977Z","caller":"traceutil/trace.go:171","msg":"trace[395194982] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1598; }","duration":"211.459694ms","start":"2026-05-08T02:38:04.948296Z","end":"2026-05-08T02:38:05.159755Z","steps":["trace[395194982] 'agreement among raft nodes before linearized reading' (duration: 211.357022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:05.159792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:38:04.623305Z","time spent":"536.378153ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:38:06.365349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.141669ms","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-08T02:38:06.365502Z","caller":"traceutil/trace.go:171","msg":"trace[424924565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1604; }","duration":"108.372954ms","start":"2026-05-08T02:38:06.257111Z","end":"2026-05-08T02:38:06.365484Z","steps":["trace[424924565] 'range keys from in-memory index tree' (duration: 107.858782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:07.098573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.421639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:38:07.098728Z","caller":"traceutil/trace.go:171","msg":"trace[728191091] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"361.576146ms","start":"2026-05-08T02:38:06.737126Z","end":"2026-05-08T02:38:07.098702Z","steps":["trace[728191091] 'process raft request' (duration: 180.898761ms)","trace[728191091] 'compare' (duration: 180.280876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:38:07.09881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:38:06.73711Z","time spent":"361.666709ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:38:08.235686Z","caller":"traceutil/trace.go:171","msg":"trace[531711499] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"110.632678ms","start":"2026-05-08T02:38:08.125022Z","end":"2026-05-08T02:38:08.235655Z","steps":["trace[531711499] 'process raft request' (duration: 110.437433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:08.937735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.461372ms","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-08T02:38:08.937809Z","caller":"traceutil/trace.go:171","msg":"trace[998255429] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1614; }","duration":"246.581685ms","start":"2026-05-08T02:38:08.691211Z","end":"2026-05-08T02:38:08.937793Z","steps":["trace[998255429] 'range keys from in-memory index tree' (duration: 246.225236ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:38:19.361641Z","caller":"traceutil/trace.go:171","msg":"trace[734250099] linearizableReadLoop","detail":"{readStateIndex:1729; appliedIndex:1728; }","duration":"320.862703ms","start":"2026-05-08T02:38:19.040755Z","end":"2026-05-08T02:38:19.361618Z","steps":["trace[734250099] 'read index received' (duration: 320.667238ms)","trace[734250099] 'applied index is now lower than readState.Index' (duration: 194.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:38:19.361773Z","caller":"traceutil/trace.go:171","msg":"trace[1069407965] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"326.981558ms","start":"2026-05-08T02:38:19.03478Z","end":"2026-05-08T02:38:19.361762Z","steps":["trace[1069407965] 'process raft request' (duration: 326.66235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:19.361872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:38:19.034753Z","time spent":"327.047529ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:38:19.361926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.132159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:38:19.362017Z","caller":"traceutil/trace.go:171","msg":"trace[738644481] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1661; }","duration":"321.270463ms","start":"2026-05-08T02:38:19.040728Z","end":"2026-05-08T02:38:19.361998Z","steps":["trace[738644481] 'agreement among raft nodes before linearized reading' (duration: 321.104679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:19.362011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.863055ms","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:531"} {"level":"warn","ts":"2026-05-08T02:38:19.362073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:38:19.040715Z","time spent":"321.344984ms","remote":"127.0.0.1:58622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:38:19.362132Z","caller":"traceutil/trace.go:171","msg":"trace[1496571487] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1661; }","duration":"176.996548ms","start":"2026-05-08T02:38:19.185078Z","end":"2026-05-08T02:38:19.362074Z","steps":["trace[1496571487] 'agreement among raft nodes before linearized reading' (duration: 176.747512ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:38:48.857505Z","caller":"traceutil/trace.go:171","msg":"trace[1012815456] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"202.688512ms","start":"2026-05-08T02:38:48.654788Z","end":"2026-05-08T02:38:48.857476Z","steps":["trace[1012815456] 'process raft request' (duration: 198.598108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:38:48.857643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.248095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:38:48.857722Z","caller":"traceutil/trace.go:171","msg":"trace[1646041809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1797; }","duration":"124.334507ms","start":"2026-05-08T02:38:48.733371Z","end":"2026-05-08T02:38:48.857706Z","steps":["trace[1646041809] 'agreement among raft nodes before linearized reading' (duration: 124.223295ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:38:48.857378Z","caller":"traceutil/trace.go:171","msg":"trace[1405224323] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"123.966719ms","start":"2026-05-08T02:38:48.733388Z","end":"2026-05-08T02:38:48.857355Z","steps":["trace[1405224323] 'read index received' (duration: 119.978718ms)","trace[1405224323] 'applied index is now lower than readState.Index' (duration: 3.986761ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:38:54.276306Z","caller":"traceutil/trace.go:171","msg":"trace[1109989349] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"139.71938ms","start":"2026-05-08T02:38:54.136562Z","end":"2026-05-08T02:38:54.276282Z","steps":["trace[1109989349] 'process raft request' (duration: 139.552466ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:40:30.829172Z","caller":"traceutil/trace.go:171","msg":"trace[10205482] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"138.37194ms","start":"2026-05-08T02:40:30.690768Z","end":"2026-05-08T02:40:30.82914Z","steps":["trace[10205482] 'process raft request' (duration: 138.234387ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:40:30.906024Z","caller":"traceutil/trace.go:171","msg":"trace[2095611717] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"212.76697ms","start":"2026-05-08T02:40:30.693228Z","end":"2026-05-08T02:40:30.905995Z","steps":["trace[2095611717] 'process raft request' (duration: 193.059965ms)","trace[2095611717] 'compare' (duration: 19.534701ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:30.906214Z","caller":"traceutil/trace.go:171","msg":"trace[1108259050] linearizableReadLoop","detail":"{readStateIndex:2386; appliedIndex:2384; }","duration":"172.211453ms","start":"2026-05-08T02:40:30.733971Z","end":"2026-05-08T02:40:30.906182Z","steps":["trace[1108259050] 'read index received' (duration: 95.094529ms)","trace[1108259050] 'applied index is now lower than readState.Index' (duration: 77.115584ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:30.906372Z","caller":"traceutil/trace.go:171","msg":"trace[1361285734] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"151.054264ms","start":"2026-05-08T02:40:30.755303Z","end":"2026-05-08T02:40:30.906358Z","steps":["trace[1361285734] 'process raft request' (duration: 150.652994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:30.906423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.435708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:40:30.906484Z","caller":"traceutil/trace.go:171","msg":"trace[560809873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2292; }","duration":"172.51697ms","start":"2026-05-08T02:40:30.733931Z","end":"2026-05-08T02:40:30.906448Z","steps":["trace[560809873] 'agreement among raft nodes before linearized reading' (duration: 172.440348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:32.542144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.184456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-08T02:40:32.542216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.579278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T02:40:32.542246Z","caller":"traceutil/trace.go:171","msg":"trace[2088610727] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2299; }","duration":"205.332689ms","start":"2026-05-08T02:40:32.336896Z","end":"2026-05-08T02:40:32.542229Z","steps":["trace[2088610727] 'range keys from in-memory index tree' (duration: 205.026572ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:40:32.542289Z","caller":"traceutil/trace.go:171","msg":"trace[752833750] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2299; }","duration":"331.702771ms","start":"2026-05-08T02:40:32.210567Z","end":"2026-05-08T02:40:32.54227Z","steps":["trace[752833750] 'range keys from in-memory index tree' (duration: 331.421145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:32.543095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:32.210547Z","time spent":"332.440329ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-08T02:40:33.033919Z","caller":"traceutil/trace.go:171","msg":"trace[1633402573] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"486.911561ms","start":"2026-05-08T02:40:32.546984Z","end":"2026-05-08T02:40:33.033896Z","steps":["trace[1633402573] 'process raft request' (duration: 486.765838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:33.03409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:32.546959Z","time spent":"487.059004ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:40:33.130439Z","caller":"traceutil/trace.go:171","msg":"trace[462101746] linearizableReadLoop","detail":"{readStateIndex:2397; appliedIndex:2395; }","duration":"396.262244ms","start":"2026-05-08T02:40:32.734149Z","end":"2026-05-08T02:40:33.130411Z","steps":["trace[462101746] 'read index received' (duration: 299.718831ms)","trace[462101746] 'applied index is now lower than readState.Index' (duration: 96.542093ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:33.130504Z","caller":"traceutil/trace.go:171","msg":"trace[101995907] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"583.047904ms","start":"2026-05-08T02:40:32.547421Z","end":"2026-05-08T02:40:33.130469Z","steps":["trace[101995907] 'process raft request' (duration: 537.492351ms)","trace[101995907] 'compare' (duration: 45.268497ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:33.130585Z","caller":"traceutil/trace.go:171","msg":"trace[1210598317] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"219.983288ms","start":"2026-05-08T02:40:32.910589Z","end":"2026-05-08T02:40:33.130572Z","steps":["trace[1210598317] 'process raft request' (duration: 219.715302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:33.130763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.611133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:40:33.1308Z","caller":"traceutil/trace.go:171","msg":"trace[1243246515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2302; }","duration":"396.693525ms","start":"2026-05-08T02:40:32.734096Z","end":"2026-05-08T02:40:33.130789Z","steps":["trace[1243246515] 'agreement among raft nodes before linearized reading' (duration: 396.624623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:33.130828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:32.734078Z","time spent":"396.742976ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:40:33.131117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.624534ms","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-08T02:40:33.131166Z","caller":"traceutil/trace.go:171","msg":"trace[1368825747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2302; }","duration":"112.709786ms","start":"2026-05-08T02:40:33.018443Z","end":"2026-05-08T02:40:33.131153Z","steps":["trace[1368825747] 'agreement among raft nodes before linearized reading' (duration: 112.532852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:33.130895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:32.547406Z","time spent":"583.348121ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:40:33.34128Z","caller":"traceutil/trace.go:171","msg":"trace[1598936799] linearizableReadLoop","detail":"{readStateIndex:2399; appliedIndex:2398; }","duration":"153.745914ms","start":"2026-05-08T02:40:33.187508Z","end":"2026-05-08T02:40:33.341254Z","steps":["trace[1598936799] 'read index received' (duration: 74.192025ms)","trace[1598936799] 'applied index is now lower than readState.Index' (duration: 79.538349ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:33.341549Z","caller":"traceutil/trace.go:171","msg":"trace[1437450901] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"206.191897ms","start":"2026-05-08T02:40:33.135317Z","end":"2026-05-08T02:40:33.341509Z","steps":["trace[1437450901] 'process raft request' (duration: 126.524986ms)","trace[1437450901] 'compare' (duration: 79.267732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:40:33.34189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.369869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:40:33.341971Z","caller":"traceutil/trace.go:171","msg":"trace[1968036156] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2303; }","duration":"154.496842ms","start":"2026-05-08T02:40:33.187454Z","end":"2026-05-08T02:40:33.341951Z","steps":["trace[1968036156] 'agreement among raft nodes before linearized reading' (duration: 154.38624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:33.896464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378822628041591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:40:34.066076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.785351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:40:34.066208Z","caller":"traceutil/trace.go:171","msg":"trace[1130264006] linearizableReadLoop","detail":"{readStateIndex:2402; appliedIndex:2400; }","duration":"669.821381ms","start":"2026-05-08T02:40:33.396376Z","end":"2026-05-08T02:40:34.066197Z","steps":["trace[1130264006] 'read index received' (duration: 520.841616ms)","trace[1130264006] 'applied index is now lower than readState.Index' (duration: 148.979055ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:34.066422Z","caller":"traceutil/trace.go:171","msg":"trace[737195574] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"718.460516ms","start":"2026-05-08T02:40:33.347951Z","end":"2026-05-08T02:40:34.066412Z","steps":["trace[737195574] 'process raft request' (duration: 718.19504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:34.066421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.087161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:40:34.066455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.086057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-08T02:40:34.06647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:33.347919Z","time spent":"718.528747ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:40:34.066478Z","caller":"traceutil/trace.go:171","msg":"trace[297616023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2306; }","duration":"670.110838ms","start":"2026-05-08T02:40:33.396361Z","end":"2026-05-08T02:40:34.066472Z","steps":["trace[297616023] 'agreement among raft nodes before linearized reading' (duration: 669.974704ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:40:34.066475Z","caller":"traceutil/trace.go:171","msg":"trace[2136433821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2306; }","duration":"332.173542ms","start":"2026-05-08T02:40:33.734287Z","end":"2026-05-08T02:40:34.06646Z","steps":["trace[2136433821] 'agreement among raft nodes before linearized reading' (duration: 332.08313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:34.066507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:33.734269Z","time spent":"332.232654ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:40:34.066507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:33.396307Z","time spent":"670.195399ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:40:34.066471Z","caller":"traceutil/trace.go:171","msg":"trace[778420613] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"718.608689ms","start":"2026-05-08T02:40:33.347815Z","end":"2026-05-08T02:40:34.066424Z","steps":["trace[778420613] 'process raft request' (duration: 569.418539ms)","trace[778420613] 'compare' (duration: 148.709969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:40:34.066424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.999492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:40:34.066615Z","caller":"traceutil/trace.go:171","msg":"trace[1339190185] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2306; }","duration":"602.223847ms","start":"2026-05-08T02:40:33.464373Z","end":"2026-05-08T02:40:34.066597Z","steps":["trace[1339190185] 'agreement among raft nodes before linearized reading' (duration: 601.980712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:34.066648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:33.347805Z","time spent":"718.748272ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:40:34.066678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:33.464355Z","time spent":"602.30905ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:40:34.515125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.748489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:40:34.515188Z","caller":"traceutil/trace.go:171","msg":"trace[1066240810] linearizableReadLoop","detail":"{readStateIndex:2403; appliedIndex:2402; }","duration":"432.341668ms","start":"2026-05-08T02:40:34.082836Z","end":"2026-05-08T02:40:34.515178Z","steps":["trace[1066240810] 'read index received' (duration: 136.437846ms)","trace[1066240810] 'applied index is now lower than readState.Index' (duration: 295.902932ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:40:34.515239Z","caller":"traceutil/trace.go:171","msg":"trace[384159508] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"444.218773ms","start":"2026-05-08T02:40:34.07098Z","end":"2026-05-08T02:40:34.515198Z","steps":["trace[384159508] 'process raft request' (duration: 148.348061ms)","trace[384159508] 'compare' (duration: 295.680677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:40:34.515319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.481292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-08T02:40:34.515334Z","caller":"traceutil/trace.go:171","msg":"trace[865458839] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2307; }","duration":"432.519883ms","start":"2026-05-08T02:40:34.082808Z","end":"2026-05-08T02:40:34.515328Z","steps":["trace[865458839] 'agreement among raft nodes before linearized reading' (duration: 432.422041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:40:34.515351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:34.082798Z","time spent":"432.548873ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T02:40:34.51546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.042336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:40:34.515649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:40:34.070965Z","time spent":"444.343877ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:40:34.515668Z","caller":"traceutil/trace.go:171","msg":"trace[209241366] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2307; }","duration":"113.300643ms","start":"2026-05-08T02:40:34.402349Z","end":"2026-05-08T02:40:34.515649Z","steps":["trace[209241366] 'agreement among raft nodes before linearized reading' (duration: 113.030746ms)"],"step_count":1}