{"level":"warn","ts":"2026-03-20T05:32:47.116893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:32:47.116972Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-03-20T05:32:47.117027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:32:47.11704Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-03-20T05:32:47.117056Z","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-03-20T05:32:47.117907Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-03-20T05:32:47.118169Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-03-20T05:32:47.12359Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.206456ms"} {"level":"info","ts":"2026-03-20T05:32:47.131291Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-03-20T05:32:47.131354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:32:47.131376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-03-20T05:32:47.131392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:32:47.131407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-03-20T05:32:47.131442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-03-20T05:32:47.139093Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:32:47.140502Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:32:47.141599Z","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-03-20T05:32:47.143464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:32:47.144178Z","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-03-20T05:32:47.144454Z","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-03-20T05:32:47.144472Z","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-03-20T05:32:47.14452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:32:47.145737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-03-20T05:32:47.145972Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-03-20T05:32:47.152792Z","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-03-20T05:32:47.15289Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-03-20T05:32:47.15294Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-03-20T05:32:47.152987Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:32:47.153033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:32:47.833233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:32:47.8333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:32:47.833325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-03-20T05:32:47.833355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:32:47.833368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-03-20T05:32:47.83339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-03-20T05:32:47.833409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-03-20T05:32:47.834563Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:32:47.8348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:32:47.834841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:32:47.834978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:32:47.835042Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:32:47.83528Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:47.837946Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-03-20T05:32:47.838277Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:47.838372Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:47.838411Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:32:47.839631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T05:33:11.973062Z","caller":"traceutil/trace.go:171","msg":"trace[133566930] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"416.707008ms","start":"2026-03-20T05:33:11.556337Z","end":"2026-03-20T05:33:11.973044Z","steps":["trace[133566930] 'process raft request' (duration: 416.577495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:11.973421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:11.556312Z","time spent":"416.81773ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:12.505014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.961005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:12.505135Z","caller":"traceutil/trace.go:171","msg":"trace[1940978223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"262.134189ms","start":"2026-03-20T05:33:12.242978Z","end":"2026-03-20T05:33:12.505112Z","steps":["trace[1940978223] 'range keys from in-memory index tree' (duration: 261.872812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:19.03374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187687999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:19.534128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187687999,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:33:19.86019Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.086660698s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:33:19.860525Z","caller":"traceutil/trace.go:171","msg":"trace[1573669986] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.327817699s","start":"2026-03-20T05:33:18.532683Z","end":"2026-03-20T05:33:19.860501Z","steps":["trace[1573669986] 'process raft request' (duration: 1.327664966s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:19.860645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:18.532659Z","time spent":"1.327932022s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:33:20.450378Z","caller":"traceutil/trace.go:171","msg":"trace[1884792770] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"1.917432524s","start":"2026-03-20T05:33:18.532922Z","end":"2026-03-20T05:33:20.450354Z","steps":["trace[1884792770] 'read index received' (duration: 1.327455971s)","trace[1884792770] 'applied index is now lower than readState.Index' (duration: 589.975693ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:20.450403Z","caller":"traceutil/trace.go:171","msg":"trace[144310128] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.715143861s","start":"2026-03-20T05:33:18.735212Z","end":"2026-03-20T05:33:20.450356Z","steps":["trace[144310128] 'process raft request' (duration: 1.714954377s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.450567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:18.735175Z","time spent":"1.715306944s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:33:20.450621Z","caller":"traceutil/trace.go:171","msg":"trace[316838207] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"616.358402ms","start":"2026-03-20T05:33:19.834225Z","end":"2026-03-20T05:33:20.450583Z","steps":["trace[316838207] 'process raft request' (duration: 616.071696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.45072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917790831s","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-03-20T05:33:20.450753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.834203Z","time spent":"616.482284ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:20.450782Z","caller":"traceutil/trace.go:171","msg":"trace[1184368322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"1.917881603s","start":"2026-03-20T05:33:18.532866Z","end":"2026-03-20T05:33:20.450747Z","steps":["trace[1184368322] 'agreement among raft nodes before linearized reading' (duration: 1.91771901s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.450818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:18.532845Z","time spent":"1.917964536s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:33:20.459175Z","caller":"traceutil/trace.go:171","msg":"trace[2063990894] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"322.759582ms","start":"2026-03-20T05:33:20.13634Z","end":"2026-03-20T05:33:20.459099Z","steps":["trace[2063990894] 'process raft request' (duration: 322.722241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.459285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:20.136319Z","time spent":"322.913595ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:33:20.459501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613877871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:33:20.459545Z","caller":"traceutil/trace.go:171","msg":"trace[1744494817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"1.613964764s","start":"2026-03-20T05:33:18.84557Z","end":"2026-03-20T05:33:20.459534Z","steps":["trace[1744494817] 'agreement among raft nodes before linearized reading' (duration: 1.61381814s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.459572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:18.845524Z","time spent":"1.614041426s","remote":"127.0.0.1:35318","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-03-20T05:33:20.459765Z","caller":"traceutil/trace.go:171","msg":"trace[1382385200] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"594.898284ms","start":"2026-03-20T05:33:19.864854Z","end":"2026-03-20T05:33:20.459753Z","steps":["trace[1382385200] 'process raft request' (duration: 594.106626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.459835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.864834Z","time spent":"594.962185ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:20.459891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.824305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-20T05:33:20.459932Z","caller":"traceutil/trace.go:171","msg":"trace[956991911] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"128.898776ms","start":"2026-03-20T05:33:20.331022Z","end":"2026-03-20T05:33:20.459921Z","steps":["trace[956991911] 'agreement among raft nodes before linearized reading' (duration: 128.803194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.459939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210211413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:20.459968Z","caller":"traceutil/trace.go:171","msg":"trace[669022440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"1.210271915s","start":"2026-03-20T05:33:19.249687Z","end":"2026-03-20T05:33:20.459959Z","steps":["trace[669022440] 'agreement among raft nodes before linearized reading' (duration: 1.210226614s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.249672Z","time spent":"1.210321655s","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328948373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:20.460058Z","caller":"traceutil/trace.go:171","msg":"trace[664672956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:502; }","duration":"1.329018465s","start":"2026-03-20T05:33:19.131031Z","end":"2026-03-20T05:33:20.46005Z","steps":["trace[664672956] 'agreement among raft nodes before linearized reading' (duration: 1.328972224s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.46009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.13101Z","time spent":"1.329071436s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.947551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-20T05:33:20.460182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526106292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:20.460199Z","caller":"traceutil/trace.go:171","msg":"trace[2019764014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"455.001703ms","start":"2026-03-20T05:33:20.005189Z","end":"2026-03-20T05:33:20.460191Z","steps":["trace[2019764014] 'agreement among raft nodes before linearized reading' (duration: 454.919911ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:20.46021Z","caller":"traceutil/trace.go:171","msg":"trace[379587777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:502; }","duration":"1.526178874s","start":"2026-03-20T05:33:18.934022Z","end":"2026-03-20T05:33:20.460201Z","steps":["trace[379587777] 'agreement among raft nodes before linearized reading' (duration: 1.526129753s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:20.005175Z","time spent":"455.041303ms","remote":"127.0.0.1:35318","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-03-20T05:33:20.460237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:18.934001Z","time spent":"1.526227934s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.439983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-20T05:33:20.460376Z","caller":"traceutil/trace.go:171","msg":"trace[1279091204] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"529.539344ms","start":"2026-03-20T05:33:19.930826Z","end":"2026-03-20T05:33:20.460366Z","steps":["trace[1279091204] 'agreement among raft nodes before linearized reading' (duration: 529.481583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.930812Z","time spent":"529.588455ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609225187s","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-03-20T05:33:20.460444Z","caller":"traceutil/trace.go:171","msg":"trace[484268675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"1.609281829s","start":"2026-03-20T05:33:18.851154Z","end":"2026-03-20T05:33:20.460436Z","steps":["trace[484268675] 'agreement among raft nodes before linearized reading' (duration: 1.609200767s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:18.851141Z","time spent":"1.60932936s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117213277s","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-03-20T05:33:20.460508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129410529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-20T05:33:20.460537Z","caller":"traceutil/trace.go:171","msg":"trace[1270086752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:502; }","duration":"1.11730742s","start":"2026-03-20T05:33:19.343217Z","end":"2026-03-20T05:33:20.460525Z","steps":["trace[1270086752] 'agreement among raft nodes before linearized reading' (duration: 1.117113646s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.343205Z","time spent":"1.117361291s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:33:20.460538Z","caller":"traceutil/trace.go:171","msg":"trace[634021348] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"1.129498002s","start":"2026-03-20T05:33:19.331031Z","end":"2026-03-20T05:33:20.460529Z","steps":["trace[634021348] 'agreement among raft nodes before linearized reading' (duration: 1.129445861s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.33101Z","time spent":"1.129719106s","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.802414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-20T05:33:20.460794Z","caller":"traceutil/trace.go:171","msg":"trace[1897835938] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"729.867804ms","start":"2026-03-20T05:33:19.730908Z","end":"2026-03-20T05:33:20.460776Z","steps":["trace[1897835938] 'agreement among raft nodes before linearized reading' (duration: 729.812373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.730891Z","time spent":"729.928876ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-03-20T05:33:20.460871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.733684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-20T05:33:20.460908Z","caller":"traceutil/trace.go:171","msg":"trace[1503141482] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"929.806505ms","start":"2026-03-20T05:33:19.531092Z","end":"2026-03-20T05:33:20.460899Z","steps":["trace[1503141482] 'agreement among raft nodes before linearized reading' (duration: 929.745244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:20.460939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:19.531074Z","time spent":"929.857247ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-03-20T05:33:26.79824Z","caller":"traceutil/trace.go:171","msg":"trace[866398614] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"309.882014ms","start":"2026-03-20T05:33:26.488335Z","end":"2026-03-20T05:33:26.798217Z","steps":["trace[866398614] 'read index received' (duration: 309.619928ms)","trace[866398614] 'applied index is now lower than readState.Index' (duration: 261.026µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:26.798297Z","caller":"traceutil/trace.go:171","msg":"trace[1427809068] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"358.502159ms","start":"2026-03-20T05:33:26.439761Z","end":"2026-03-20T05:33:26.798263Z","steps":["trace[1427809068] 'process raft request' (duration: 358.265614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:26.798681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.43974Z","time spent":"358.769415ms","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:26.79872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.880312ms","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-03-20T05:33:26.798725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.25124ms","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-03-20T05:33:26.798703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.217708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-20T05:33:26.798822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.391143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:33:26.798858Z","caller":"traceutil/trace.go:171","msg":"trace[1790076663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"283.384063ms","start":"2026-03-20T05:33:26.515428Z","end":"2026-03-20T05:33:26.798812Z","steps":["trace[1790076663] 'agreement among raft nodes before linearized reading' (duration: 283.139268ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:26.798904Z","caller":"traceutil/trace.go:171","msg":"trace[1359944049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"283.499816ms","start":"2026-03-20T05:33:26.515385Z","end":"2026-03-20T05:33:26.798885Z","steps":["trace[1359944049] 'agreement among raft nodes before linearized reading' (duration: 283.23105ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:26.79881Z","caller":"traceutil/trace.go:171","msg":"trace[1889612325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:535; }","duration":"283.017665ms","start":"2026-03-20T05:33:26.515769Z","end":"2026-03-20T05:33:26.798787Z","steps":["trace[1889612325] 'agreement among raft nodes before linearized reading' (duration: 282.732898ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:26.798929Z","caller":"traceutil/trace.go:171","msg":"trace[555965130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"287.486024ms","start":"2026-03-20T05:33:26.511426Z","end":"2026-03-20T05:33:26.798912Z","steps":["trace[555965130] 'agreement among raft nodes before linearized reading' (duration: 287.030644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:26.799188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.842956ms","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-03-20T05:33:26.79926Z","caller":"traceutil/trace.go:171","msg":"trace[1218162404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"310.975429ms","start":"2026-03-20T05:33:26.488268Z","end":"2026-03-20T05:33:26.799243Z","steps":["trace[1218162404] 'agreement among raft nodes before linearized reading' (duration: 310.115779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:26.799308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.488228Z","time spent":"311.067951ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:27.151464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.609962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:27.151568Z","caller":"traceutil/trace.go:171","msg":"trace[450408685] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"347.613046ms","start":"2026-03-20T05:33:26.803937Z","end":"2026-03-20T05:33:27.15155Z","steps":["trace[450408685] 'process raft request' (duration: 70.717048ms)","trace[450408685] 'compare' (duration: 276.427348ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:27.15163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.803917Z","time spent":"347.684318ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:27.157728Z","caller":"traceutil/trace.go:171","msg":"trace[720236579] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"352.76332ms","start":"2026-03-20T05:33:26.804647Z","end":"2026-03-20T05:33:27.15741Z","steps":["trace[720236579] 'process raft request' (duration: 352.540616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.157947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.804623Z","time spent":"353.242401ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:27.15814Z","caller":"traceutil/trace.go:171","msg":"trace[572837760] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"352.141066ms","start":"2026-03-20T05:33:26.805312Z","end":"2026-03-20T05:33:27.157453Z","steps":["trace[572837760] 'process raft request' (duration: 351.999193ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.158089Z","caller":"traceutil/trace.go:171","msg":"trace[335741983] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"351.806579ms","start":"2026-03-20T05:33:26.805636Z","end":"2026-03-20T05:33:27.157442Z","steps":["trace[335741983] 'process raft request' (duration: 351.716647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.158322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.805624Z","time spent":"352.589777ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:27.158394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.805291Z","time spent":"352.911155ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:27.157814Z","caller":"traceutil/trace.go:171","msg":"trace[215164366] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"351.741848ms","start":"2026-03-20T05:33:26.805692Z","end":"2026-03-20T05:33:27.157434Z","steps":["trace[215164366] 'process raft request' (duration: 351.694627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.159046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:26.805686Z","time spent":"353.301413ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:27.695883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.728593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.695975Z","caller":"traceutil/trace.go:171","msg":"trace[1885387115] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:540; }","duration":"219.879466ms","start":"2026-03-20T05:33:27.476074Z","end":"2026-03-20T05:33:27.695954Z","steps":["trace[1885387115] 'range keys from in-memory index tree' (duration: 219.61981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.695936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.275909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.696093Z","caller":"traceutil/trace.go:171","msg":"trace[837570130] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:540; }","duration":"192.449754ms","start":"2026-03-20T05:33:27.503622Z","end":"2026-03-20T05:33:27.696072Z","steps":["trace[837570130] 'count revisions from in-memory index tree' (duration: 192.237429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.696147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.768193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.696246Z","caller":"traceutil/trace.go:171","msg":"trace[652191892] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:540; }","duration":"236.945156ms","start":"2026-03-20T05:33:27.459278Z","end":"2026-03-20T05:33:27.696223Z","steps":["trace[652191892] 'range keys from in-memory index tree' (duration: 235.893913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.696291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.241374ms","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-03-20T05:33:27.696324Z","caller":"traceutil/trace.go:171","msg":"trace[1581475483] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:540; }","duration":"207.292294ms","start":"2026-03-20T05:33:27.489022Z","end":"2026-03-20T05:33:27.696314Z","steps":["trace[1581475483] 'count revisions from in-memory index tree' (duration: 207.202373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.696301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.646048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.696382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.296408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.696413Z","caller":"traceutil/trace.go:171","msg":"trace[2007138119] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:540; }","duration":"192.834842ms","start":"2026-03-20T05:33:27.503552Z","end":"2026-03-20T05:33:27.696387Z","steps":["trace[2007138119] 'range keys from in-memory index tree' (duration: 192.520146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.69642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.517729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.696496Z","caller":"traceutil/trace.go:171","msg":"trace[630549922] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:540; }","duration":"237.611151ms","start":"2026-03-20T05:33:27.458868Z","end":"2026-03-20T05:33:27.696479Z","steps":["trace[630549922] 'count revisions from in-memory index tree' (duration: 237.463268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.696445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.437514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.696444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.342397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.696345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.175185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:27.696559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.721934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.69658Z","caller":"traceutil/trace.go:171","msg":"trace[1639248025] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:540; }","duration":"192.609718ms","start":"2026-03-20T05:33:27.503935Z","end":"2026-03-20T05:33:27.696544Z","steps":["trace[1639248025] 'range keys from in-memory index tree' (duration: 192.379582ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.696416Z","caller":"traceutil/trace.go:171","msg":"trace[1052240209] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:540; }","duration":"221.357158ms","start":"2026-03-20T05:33:27.475048Z","end":"2026-03-20T05:33:27.696406Z","steps":["trace[1052240209] 'range keys from in-memory index tree' (duration: 221.167005ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.696594Z","caller":"traceutil/trace.go:171","msg":"trace[1202125086] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:540; }","duration":"203.529491ms","start":"2026-03-20T05:33:27.493046Z","end":"2026-03-20T05:33:27.696575Z","steps":["trace[1202125086] 'range keys from in-memory index tree' (duration: 203.256256ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.696609Z","caller":"traceutil/trace.go:171","msg":"trace[1648871505] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:540; }","duration":"237.827487ms","start":"2026-03-20T05:33:27.458767Z","end":"2026-03-20T05:33:27.696595Z","steps":["trace[1648871505] 'range keys from in-memory index tree' (duration: 237.570111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.696632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.659853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.696667Z","caller":"traceutil/trace.go:171","msg":"trace[338610198] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:540; }","duration":"207.731135ms","start":"2026-03-20T05:33:27.488926Z","end":"2026-03-20T05:33:27.696657Z","steps":["trace[338610198] 'range keys from in-memory index tree' (duration: 207.52577ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:27.696589Z","caller":"traceutil/trace.go:171","msg":"trace[960609123] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:540; }","duration":"221.43907ms","start":"2026-03-20T05:33:27.47514Z","end":"2026-03-20T05:33:27.696579Z","steps":["trace[960609123] 'count revisions from in-memory index tree' (duration: 221.135894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.66216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187688118,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:33:29.938577Z","caller":"traceutil/trace.go:171","msg":"trace[1002471496] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"776.650999ms","start":"2026-03-20T05:33:29.161904Z","end":"2026-03-20T05:33:29.938555Z","steps":["trace[1002471496] 'read index received' (duration: 776.494356ms)","trace[1002471496] 'applied index is now lower than readState.Index' (duration: 155.613µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:29.938694Z","caller":"traceutil/trace.go:171","msg":"trace[1078863113] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"780.511815ms","start":"2026-03-20T05:33:29.158169Z","end":"2026-03-20T05:33:29.93868Z","steps":["trace[1078863113] 'process raft request' (duration: 780.27171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.938807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:29.158151Z","time spent":"780.576046ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:29.938975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.034697ms","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-03-20T05:33:29.939022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.757271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:33:29.939039Z","caller":"traceutil/trace.go:171","msg":"trace[1450361081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"777.13739ms","start":"2026-03-20T05:33:29.161885Z","end":"2026-03-20T05:33:29.939022Z","steps":["trace[1450361081] 'agreement among raft nodes before linearized reading' (duration: 776.957606ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:29.939054Z","caller":"traceutil/trace.go:171","msg":"trace[993775382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"776.807602ms","start":"2026-03-20T05:33:29.162236Z","end":"2026-03-20T05:33:29.939044Z","steps":["trace[993775382] 'agreement among raft nodes before linearized reading' (duration: 776.69375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.939079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:29.161869Z","time spent":"777.201421ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:29.939056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.377493ms","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-03-20T05:33:29.939082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:29.162226Z","time spent":"776.850544ms","remote":"127.0.0.1:35318","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-03-20T05:33:29.939132Z","caller":"traceutil/trace.go:171","msg":"trace[1988112091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:541; }","duration":"776.487676ms","start":"2026-03-20T05:33:29.162627Z","end":"2026-03-20T05:33:29.939115Z","steps":["trace[1988112091] 'agreement among raft nodes before linearized reading' (duration: 776.25228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.939176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:29.162618Z","time spent":"776.549277ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:29.939148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.212011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:33:29.939239Z","caller":"traceutil/trace.go:171","msg":"trace[164873814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"777.350004ms","start":"2026-03-20T05:33:29.161867Z","end":"2026-03-20T05:33:29.939217Z","steps":["trace[164873814] 'agreement among raft nodes before linearized reading' (duration: 777.074908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:29.939294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:29.161849Z","time spent":"777.435516ms","remote":"127.0.0.1:35318","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-03-20T05:33:30.123829Z","caller":"traceutil/trace.go:171","msg":"trace[443422235] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"180.860745ms","start":"2026-03-20T05:33:29.942938Z","end":"2026-03-20T05:33:30.123799Z","steps":["trace[443422235] 'process raft request' (duration: 105.847461ms)","trace[443422235] 'compare' (duration: 74.778368ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:30.123849Z","caller":"traceutil/trace.go:171","msg":"trace[1996925565] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"180.563288ms","start":"2026-03-20T05:33:29.943256Z","end":"2026-03-20T05:33:30.123819Z","steps":["trace[1996925565] 'process raft request' (duration: 180.472596ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:30.123877Z","caller":"traceutil/trace.go:171","msg":"trace[522489608] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"180.095918ms","start":"2026-03-20T05:33:29.943757Z","end":"2026-03-20T05:33:30.123853Z","steps":["trace[522489608] 'process raft request' (duration: 180.011246ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:30.12386Z","caller":"traceutil/trace.go:171","msg":"trace[1520138287] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"179.834612ms","start":"2026-03-20T05:33:29.944001Z","end":"2026-03-20T05:33:30.123835Z","steps":["trace[1520138287] 'process raft request' (duration: 179.799412ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:40.166226Z","caller":"traceutil/trace.go:171","msg":"trace[383140045] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"109.037253ms","start":"2026-03-20T05:33:40.057161Z","end":"2026-03-20T05:33:40.166198Z","steps":["trace[383140045] 'process raft request' (duration: 108.91978ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:40.327979Z","caller":"traceutil/trace.go:171","msg":"trace[2066064322] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"118.896303ms","start":"2026-03-20T05:33:40.209058Z","end":"2026-03-20T05:33:40.327954Z","steps":["trace[2066064322] 'process raft request' (duration: 118.690538ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:40.328158Z","caller":"traceutil/trace.go:171","msg":"trace[1101976231] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"121.752496ms","start":"2026-03-20T05:33:40.206388Z","end":"2026-03-20T05:33:40.328141Z","steps":["trace[1101976231] 'process raft request' (duration: 28.282351ms)","trace[1101976231] 'compare' (duration: 92.974874ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:46.282359Z","caller":"traceutil/trace.go:171","msg":"trace[667767878] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"273.308998ms","start":"2026-03-20T05:33:46.009027Z","end":"2026-03-20T05:33:46.282336Z","steps":["trace[667767878] 'process raft request' (duration: 273.035882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.103756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311220995s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:48.103944Z","caller":"traceutil/trace.go:171","msg":"trace[674128553] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"1.753204277s","start":"2026-03-20T05:33:46.350723Z","end":"2026-03-20T05:33:48.103928Z","steps":["trace[674128553] 'read index received' (duration: 441.724506ms)","trace[674128553] 'applied index is now lower than readState.Index' (duration: 1.311478771s)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:48.104098Z","caller":"traceutil/trace.go:171","msg":"trace[2041495938] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.817822639s","start":"2026-03-20T05:33:46.286231Z","end":"2026-03-20T05:33:48.104054Z","steps":["trace[2041495938] 'process raft request' (duration: 1.817620585s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.104245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749184298s","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-03-20T05:33:48.104285Z","caller":"traceutil/trace.go:171","msg":"trace[248274409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"1.749243669s","start":"2026-03-20T05:33:46.35503Z","end":"2026-03-20T05:33:48.104273Z","steps":["trace[248274409] 'agreement among raft nodes before linearized reading' (duration: 1.749165427s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.104318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.355018Z","time spent":"1.74929298s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:33:48.104462Z","caller":"traceutil/trace.go:171","msg":"trace[1663022769] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.819175299s","start":"2026-03-20T05:33:46.285251Z","end":"2026-03-20T05:33:48.104426Z","steps":["trace[1663022769] 'process raft request' (duration: 507.204537ms)","trace[1663022769] 'compare' (duration: 1.311048292s)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:48.105282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.285231Z","time spent":"1.819988498s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:48.104507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.257168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:48.105692Z","caller":"traceutil/trace.go:171","msg":"trace[779899632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"863.474525ms","start":"2026-03-20T05:33:47.242195Z","end":"2026-03-20T05:33:48.10567Z","steps":["trace[779899632] 'agreement among raft nodes before linearized reading' (duration: 862.290229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.104166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.753445342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-20T05:33:48.10575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:47.242171Z","time spent":"863.564038ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:33:48.105769Z","caller":"traceutil/trace.go:171","msg":"trace[2075831371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"1.755062839s","start":"2026-03-20T05:33:46.350682Z","end":"2026-03-20T05:33:48.105745Z","steps":["trace[2075831371] 'agreement among raft nodes before linearized reading' (duration: 1.753359481s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.105091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.28622Z","time spent":"1.817991013s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:48.105846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.350669Z","time spent":"1.755152s","remote":"127.0.0.1:35318","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-03-20T05:33:48.443773Z","caller":"traceutil/trace.go:171","msg":"trace[751485716] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"335.825889ms","start":"2026-03-20T05:33:48.107918Z","end":"2026-03-20T05:33:48.443743Z","steps":["trace[751485716] 'process raft request' (duration: 335.660006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.44395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:48.107899Z","time spent":"335.957912ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:48.445536Z","caller":"traceutil/trace.go:171","msg":"trace[498145462] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"160.267776ms","start":"2026-03-20T05:33:48.285242Z","end":"2026-03-20T05:33:48.44551Z","steps":["trace[498145462] 'read index received' (duration: 158.332672ms)","trace[498145462] 'applied index is now lower than readState.Index' (duration: 1.934214ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:48.44571Z","caller":"traceutil/trace.go:171","msg":"trace[81013281] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"335.180694ms","start":"2026-03-20T05:33:48.110516Z","end":"2026-03-20T05:33:48.445697Z","steps":["trace[81013281] 'process raft request' (duration: 334.881368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.445744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.480571ms","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-03-20T05:33:48.445788Z","caller":"traceutil/trace.go:171","msg":"trace[1670257706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"160.565153ms","start":"2026-03-20T05:33:48.285213Z","end":"2026-03-20T05:33:48.445778Z","steps":["trace[1670257706] 'agreement among raft nodes before linearized reading' (duration: 160.41194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:48.44585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:48.110488Z","time spent":"335.279697ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:51.314533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205013099s","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-03-20T05:33:51.314599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.93989ms","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-03-20T05:33:51.314623Z","caller":"traceutil/trace.go:171","msg":"trace[208836389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"866.990721ms","start":"2026-03-20T05:33:50.447626Z","end":"2026-03-20T05:33:51.314617Z","steps":["trace[208836389] 'range keys from in-memory index tree' (duration: 866.689374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.314607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204382205s","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-03-20T05:33:51.31465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.447611Z","time spent":"867.032542ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:33:51.314671Z","caller":"traceutil/trace.go:171","msg":"trace[582516245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"1.204495448s","start":"2026-03-20T05:33:50.11016Z","end":"2026-03-20T05:33:51.314655Z","steps":["trace[582516245] 'range keys from in-memory index tree' (duration: 1.204235832s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.314676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.976663ms","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-03-20T05:33:51.31471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.11014Z","time spent":"1.204561309s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:33:51.314749Z","caller":"traceutil/trace.go:171","msg":"trace[1634620752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"857.086075ms","start":"2026-03-20T05:33:50.457644Z","end":"2026-03-20T05:33:51.31473Z","steps":["trace[1634620752] 'range keys from in-memory index tree' (duration: 856.82065ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:51.314599Z","caller":"traceutil/trace.go:171","msg":"trace[1923954834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"1.205097411s","start":"2026-03-20T05:33:50.109485Z","end":"2026-03-20T05:33:51.314582Z","steps":["trace[1923954834] 'range keys from in-memory index tree' (duration: 1.204757573s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.314791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.457624Z","time spent":"857.156317ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:33:51.314803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.10946Z","time spent":"1.205331327s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:33:51.314812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.823702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:33:51.314881Z","caller":"traceutil/trace.go:171","msg":"trace[505896396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"864.903603ms","start":"2026-03-20T05:33:50.449956Z","end":"2026-03-20T05:33:51.31486Z","steps":["trace[505896396] 'range keys from in-memory index tree' (duration: 864.647388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.314532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.696366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-20T05:33:51.314971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.44995Z","time spent":"865.007965ms","remote":"127.0.0.1:35318","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-03-20T05:33:51.314981Z","caller":"traceutil/trace.go:171","msg":"trace[1313596946] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:691; }","duration":"660.206248ms","start":"2026-03-20T05:33:50.654762Z","end":"2026-03-20T05:33:51.314969Z","steps":["trace[1313596946] 'range keys from in-memory index tree' (duration: 659.532713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.315106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.654745Z","time spent":"660.34894ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-03-20T05:33:51.31549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.121519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:51.31555Z","caller":"traceutil/trace.go:171","msg":"trace[1437596717] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"374.724823ms","start":"2026-03-20T05:33:50.940815Z","end":"2026-03-20T05:33:51.31554Z","steps":["trace[1437596717] 'read index received' (duration: 259.441001ms)","trace[1437596717] 'applied index is now lower than readState.Index' (duration: 115.282852ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:51.315632Z","caller":"traceutil/trace.go:171","msg":"trace[746394734] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"548.399714ms","start":"2026-03-20T05:33:50.767222Z","end":"2026-03-20T05:33:51.315621Z","steps":["trace[746394734] 'process raft request' (duration: 433.088271ms)","trace[746394734] 'compare' (duration: 114.338951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:51.315705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.767199Z","time spent":"548.475135ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:51.315808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.99535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:51.315843Z","caller":"traceutil/trace.go:171","msg":"trace[1925073715] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:692; }","duration":"375.065461ms","start":"2026-03-20T05:33:50.94077Z","end":"2026-03-20T05:33:51.315835Z","steps":["trace[1925073715] 'agreement among raft nodes before linearized reading' (duration: 375.00964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.315881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:50.940749Z","time spent":"375.122883ms","remote":"127.0.0.1:35288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:33:51.315903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.899801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:51.315957Z","caller":"traceutil/trace.go:171","msg":"trace[1136643766] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:692; }","duration":"146.958762ms","start":"2026-03-20T05:33:51.168986Z","end":"2026-03-20T05:33:51.315944Z","steps":["trace[1136643766] 'agreement among raft nodes before linearized reading' (duration: 146.876471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.683566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.734647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:51.683769Z","caller":"traceutil/trace.go:171","msg":"trace[360593373] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"363.979859ms","start":"2026-03-20T05:33:51.319773Z","end":"2026-03-20T05:33:51.683753Z","steps":["trace[360593373] 'process raft request' (duration: 363.888817ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:51.683791Z","caller":"traceutil/trace.go:171","msg":"trace[1428957500] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"364.081372ms","start":"2026-03-20T05:33:51.319679Z","end":"2026-03-20T05:33:51.68376Z","steps":["trace[1428957500] 'process raft request' (duration: 166.066597ms)","trace[1428957500] 'compare' (duration: 197.609805ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:51.683839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.319748Z","time spent":"364.058141ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:51.684135Z","caller":"traceutil/trace.go:171","msg":"trace[1737485448] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"364.05077ms","start":"2026-03-20T05:33:51.320062Z","end":"2026-03-20T05:33:51.684113Z","steps":["trace[1737485448] 'process raft request' (duration: 363.652632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.684811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.32005Z","time spent":"364.693755ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:51.684575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.319662Z","time spent":"364.191164ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:51.68512Z","caller":"traceutil/trace.go:171","msg":"trace[1889200097] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"364.812457ms","start":"2026-03-20T05:33:51.320279Z","end":"2026-03-20T05:33:51.685091Z","steps":["trace[1889200097] 'process raft request' (duration: 363.585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.685225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.320265Z","time spent":"364.90036ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:51.688365Z","caller":"traceutil/trace.go:171","msg":"trace[1932286252] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"366.947636ms","start":"2026-03-20T05:33:51.321398Z","end":"2026-03-20T05:33:51.688346Z","steps":["trace[1932286252] 'process raft request' (duration: 363.59349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.688459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.321386Z","time spent":"367.032579ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:51.695013Z","caller":"traceutil/trace.go:171","msg":"trace[1219621959] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"331.623353ms","start":"2026-03-20T05:33:51.363379Z","end":"2026-03-20T05:33:51.695002Z","steps":["trace[1219621959] 'process raft request' (duration: 331.558882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:51.695075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:51.36336Z","time spent":"331.684045ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:10.03918Z","caller":"traceutil/trace.go:171","msg":"trace[176330572] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"203.05709ms","start":"2026-03-20T05:34:09.836094Z","end":"2026-03-20T05:34:10.039151Z","steps":["trace[176330572] 'process raft request' (duration: 159.163651ms)","trace[176330572] 'compare' (duration: 43.767416ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:10.087541Z","caller":"traceutil/trace.go:171","msg":"trace[913708120] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"249.7204ms","start":"2026-03-20T05:34:09.837806Z","end":"2026-03-20T05:34:10.087526Z","steps":["trace[913708120] 'process raft request' (duration: 249.686059ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:10.087612Z","caller":"traceutil/trace.go:171","msg":"trace[358034122] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"250.522968ms","start":"2026-03-20T05:34:09.837058Z","end":"2026-03-20T05:34:10.087581Z","steps":["trace[358034122] 'process raft request' (duration: 250.324204ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:10.087779Z","caller":"traceutil/trace.go:171","msg":"trace[250709797] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"250.61877ms","start":"2026-03-20T05:34:09.837133Z","end":"2026-03-20T05:34:10.087752Z","steps":["trace[250709797] 'process raft request' (duration: 250.319143ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:11.975033Z","caller":"traceutil/trace.go:171","msg":"trace[1634132208] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"154.472044ms","start":"2026-03-20T05:34:11.820529Z","end":"2026-03-20T05:34:11.975001Z","steps":["trace[1634132208] 'process raft request' (duration: 154.255859ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:12.014359Z","caller":"traceutil/trace.go:171","msg":"trace[412005792] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"190.674717ms","start":"2026-03-20T05:34:11.823655Z","end":"2026-03-20T05:34:12.014329Z","steps":["trace[412005792] 'process raft request' (duration: 190.516104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:12.897252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.292397ms","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-03-20T05:34:12.89731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.363751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:34:12.897335Z","caller":"traceutil/trace.go:171","msg":"trace[46468420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"805.451031ms","start":"2026-03-20T05:34:12.091864Z","end":"2026-03-20T05:34:12.897315Z","steps":["trace[46468420] 'range keys from in-memory index tree' (duration: 805.072512ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:12.897387Z","caller":"traceutil/trace.go:171","msg":"trace[101494788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"853.513095ms","start":"2026-03-20T05:34:12.043854Z","end":"2026-03-20T05:34:12.897367Z","steps":["trace[101494788] 'range keys from in-memory index tree' (duration: 853.131595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:12.897392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.09184Z","time spent":"805.543373ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:12.897432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.043819Z","time spent":"853.603457ms","remote":"127.0.0.1:35318","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-03-20T05:34:12.897616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.677156ms","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-03-20T05:34:12.89766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.699788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:12.897695Z","caller":"traceutil/trace.go:171","msg":"trace[1623858447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"642.789941ms","start":"2026-03-20T05:34:12.254896Z","end":"2026-03-20T05:34:12.897686Z","steps":["trace[1623858447] 'range keys from in-memory index tree' (duration: 642.594136ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:12.897707Z","caller":"traceutil/trace.go:171","msg":"trace[1749552860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"805.812599ms","start":"2026-03-20T05:34:12.091862Z","end":"2026-03-20T05:34:12.897675Z","steps":["trace[1749552860] 'range keys from in-memory index tree' (duration: 805.388819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:12.897722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.254868Z","time spent":"642.848331ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:12.89775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.09184Z","time spent":"805.901421ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:12.897616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.197755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:34:12.897798Z","caller":"traceutil/trace.go:171","msg":"trace[498063259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"805.42821ms","start":"2026-03-20T05:34:12.092352Z","end":"2026-03-20T05:34:12.89778Z","steps":["trace[498063259] 'range keys from in-memory index tree' (duration: 804.99507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:12.897835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.092333Z","time spent":"805.493092ms","remote":"127.0.0.1:35318","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-03-20T05:34:13.123198Z","caller":"traceutil/trace.go:171","msg":"trace[1689854644] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"220.290901ms","start":"2026-03-20T05:34:12.902852Z","end":"2026-03-20T05:34:13.123143Z","steps":["trace[1689854644] 'process raft request' (duration: 219.996045ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:13.276865Z","caller":"traceutil/trace.go:171","msg":"trace[857025598] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"373.065727ms","start":"2026-03-20T05:34:12.903776Z","end":"2026-03-20T05:34:13.276842Z","steps":["trace[857025598] 'process raft request' (duration: 372.983395ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:13.276883Z","caller":"traceutil/trace.go:171","msg":"trace[2099193601] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"373.431645ms","start":"2026-03-20T05:34:12.90342Z","end":"2026-03-20T05:34:13.276851Z","steps":["trace[2099193601] 'process raft request' (duration: 369.085335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:13.277045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.903766Z","time spent":"373.177649ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:13.277058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.903385Z","time spent":"373.585188ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:13.277129Z","caller":"traceutil/trace.go:171","msg":"trace[1138309675] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"372.153605ms","start":"2026-03-20T05:34:12.904933Z","end":"2026-03-20T05:34:13.277086Z","steps":["trace[1138309675] 'process raft request' (duration: 371.872579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:13.277332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.904924Z","time spent":"372.332999ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:17.609198Z","caller":"traceutil/trace.go:171","msg":"trace[2014989000] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"218.433969ms","start":"2026-03-20T05:34:17.390736Z","end":"2026-03-20T05:34:17.60917Z","steps":["trace[2014989000] 'process raft request' (duration: 218.251345ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:17.653941Z","caller":"traceutil/trace.go:171","msg":"trace[397652920] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"262.594964ms","start":"2026-03-20T05:34:17.391327Z","end":"2026-03-20T05:34:17.653922Z","steps":["trace[397652920] 'process raft request' (duration: 262.45477ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:17.654135Z","caller":"traceutil/trace.go:171","msg":"trace[283737304] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"262.675405ms","start":"2026-03-20T05:34:17.391433Z","end":"2026-03-20T05:34:17.654108Z","steps":["trace[283737304] 'process raft request' (duration: 262.45121ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:26.226888Z","caller":"traceutil/trace.go:171","msg":"trace[591761559] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"139.41381ms","start":"2026-03-20T05:34:26.087433Z","end":"2026-03-20T05:34:26.226847Z","steps":["trace[591761559] 'process raft request' (duration: 139.276257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:29.340003Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104596946s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:34:29.3404Z","caller":"traceutil/trace.go:171","msg":"trace[1784301854] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"350.622686ms","start":"2026-03-20T05:34:28.989755Z","end":"2026-03-20T05:34:29.340377Z","steps":["trace[1784301854] 'read index received' (duration: 350.431101ms)","trace[1784301854] 'applied index is now lower than readState.Index' (duration: 190.434µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:29.340542Z","caller":"traceutil/trace.go:171","msg":"trace[1973807862] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"1.10521705s","start":"2026-03-20T05:34:28.235313Z","end":"2026-03-20T05:34:29.34053Z","steps":["trace[1973807862] 'process raft request' (duration: 1.104926083s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:29.34064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:28.235292Z","time spent":"1.105274402s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:29.341029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.247309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:29.341098Z","caller":"traceutil/trace.go:171","msg":"trace[699128397] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:851; }","duration":"351.369502ms","start":"2026-03-20T05:34:28.989709Z","end":"2026-03-20T05:34:29.341078Z","steps":["trace[699128397] 'agreement among raft nodes before linearized reading' (duration: 351.213819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:29.341137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:28.989691Z","time spent":"351.436513ms","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":88,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:34:29.571404Z","caller":"traceutil/trace.go:171","msg":"trace[1188301191] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"225.901377ms","start":"2026-03-20T05:34:29.345479Z","end":"2026-03-20T05:34:29.57138Z","steps":["trace[1188301191] 'process raft request' (duration: 134.633262ms)","trace[1188301191] 'compare' (duration: 91.04049ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:29.571397Z","caller":"traceutil/trace.go:171","msg":"trace[407495193] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"204.923751ms","start":"2026-03-20T05:34:29.366445Z","end":"2026-03-20T05:34:29.571369Z","steps":["trace[407495193] 'read index received' (duration: 113.709077ms)","trace[407495193] 'applied index is now lower than readState.Index' (duration: 91.213384ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:29.571501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.034763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:29.571542Z","caller":"traceutil/trace.go:171","msg":"trace[1228839325] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:852; }","duration":"205.130536ms","start":"2026-03-20T05:34:29.366401Z","end":"2026-03-20T05:34:29.571532Z","steps":["trace[1228839325] 'agreement among raft nodes before linearized reading' (duration: 205.042344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:30.049379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.25456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:30.049469Z","caller":"traceutil/trace.go:171","msg":"trace[1211203124] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:852; }","duration":"162.393174ms","start":"2026-03-20T05:34:29.887055Z","end":"2026-03-20T05:34:30.049448Z","steps":["trace[1211203124] 'count revisions from in-memory index tree' (duration: 162.152478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:30.049562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.855754ms","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-03-20T05:34:30.049663Z","caller":"traceutil/trace.go:171","msg":"trace[716675438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"352.034187ms","start":"2026-03-20T05:34:29.697612Z","end":"2026-03-20T05:34:30.049646Z","steps":["trace[716675438] 'range keys from in-memory index tree' (duration: 351.637689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:30.049705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:29.697595Z","time spent":"352.099809ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:30.049789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.547519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:34:30.0499Z","caller":"traceutil/trace.go:171","msg":"trace[1909081521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"334.720443ms","start":"2026-03-20T05:34:29.715154Z","end":"2026-03-20T05:34:30.049874Z","steps":["trace[1909081521] 'range keys from in-memory index tree' (duration: 334.349144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:30.049919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.988129ms","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-03-20T05:34:30.050003Z","caller":"traceutil/trace.go:171","msg":"trace[1219346472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"335.116703ms","start":"2026-03-20T05:34:29.714868Z","end":"2026-03-20T05:34:30.049985Z","steps":["trace[1219346472] 'range keys from in-memory index tree' (duration: 334.789556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:30.050052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:29.714854Z","time spent":"335.188814ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:30.049947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:29.715141Z","time spent":"334.796795ms","remote":"127.0.0.1:35318","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-03-20T05:34:41.786589Z","caller":"traceutil/trace.go:171","msg":"trace[1926252502] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"166.640331ms","start":"2026-03-20T05:34:41.619927Z","end":"2026-03-20T05:34:41.786568Z","steps":["trace[1926252502] 'process raft request' (duration: 166.523508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:42.281607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.780829ms","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-03-20T05:34:42.2817Z","caller":"traceutil/trace.go:171","msg":"trace[1997396216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"172.892912ms","start":"2026-03-20T05:34:42.108783Z","end":"2026-03-20T05:34:42.281676Z","steps":["trace[1997396216] 'range keys from in-memory index tree' (duration: 172.557815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:42.281774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.250973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:34:42.281861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.653874ms","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-03-20T05:34:42.281879Z","caller":"traceutil/trace.go:171","msg":"trace[583932759] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:886; }","duration":"192.419886ms","start":"2026-03-20T05:34:42.089429Z","end":"2026-03-20T05:34:42.281849Z","steps":["trace[583932759] 'count revisions from in-memory index tree' (duration: 191.956255ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:42.281933Z","caller":"traceutil/trace.go:171","msg":"trace[11616280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"175.774027ms","start":"2026-03-20T05:34:42.10614Z","end":"2026-03-20T05:34:42.281914Z","steps":["trace[11616280] 'range keys from in-memory index tree' (duration: 175.418449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:42.281978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.163979ms","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-03-20T05:34:42.282048Z","caller":"traceutil/trace.go:171","msg":"trace[1426993467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"155.275763ms","start":"2026-03-20T05:34:42.126755Z","end":"2026-03-20T05:34:42.282031Z","steps":["trace[1426993467] 'range keys from in-memory index tree' (duration: 155.021166ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:44.664421Z","caller":"traceutil/trace.go:171","msg":"trace[2033222911] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"137.037438ms","start":"2026-03-20T05:34:44.52735Z","end":"2026-03-20T05:34:44.664388Z","steps":["trace[2033222911] 'process raft request' (duration: 136.819303ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:45.619988Z","caller":"traceutil/trace.go:171","msg":"trace[981133440] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"213.443404ms","start":"2026-03-20T05:34:45.406527Z","end":"2026-03-20T05:34:45.619971Z","steps":["trace[981133440] 'process raft request' (duration: 213.332042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:46.777855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.758145ms","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-03-20T05:34:46.778003Z","caller":"traceutil/trace.go:171","msg":"trace[949539710] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:917; }","duration":"480.930538ms","start":"2026-03-20T05:34:46.297054Z","end":"2026-03-20T05:34:46.777985Z","steps":["trace[949539710] 'range keys from in-memory index tree' (duration: 480.736254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:47.043364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.502012ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-20T05:34:47.043474Z","caller":"traceutil/trace.go:171","msg":"trace[1791709275] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"1.229868914s","start":"2026-03-20T05:34:45.813588Z","end":"2026-03-20T05:34:47.043457Z","steps":["trace[1791709275] 'read index received' (duration: 482.338361ms)","trace[1791709275] 'applied index is now lower than readState.Index' (duration: 747.529053ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:47.043657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.978312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:34:47.043713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23011522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:34:47.043731Z","caller":"traceutil/trace.go:171","msg":"trace[1534471627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"802.132955ms","start":"2026-03-20T05:34:46.241578Z","end":"2026-03-20T05:34:47.043711Z","steps":["trace[1534471627] 'agreement among raft nodes before linearized reading' (duration: 802.002662ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:47.043746Z","caller":"traceutil/trace.go:171","msg":"trace[2082706644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"1.230226252s","start":"2026-03-20T05:34:45.81351Z","end":"2026-03-20T05:34:47.043736Z","steps":["trace[2082706644] 'agreement among raft nodes before linearized reading' (duration: 1.230035658s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:47.043774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:46.241502Z","time spent":"802.263778ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:47.043785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:45.813484Z","time spent":"1.230292654s","remote":"127.0.0.1:35318","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-03-20T05:34:47.043795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.675894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:34:47.043865Z","caller":"traceutil/trace.go:171","msg":"trace[1421010018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"702.812546ms","start":"2026-03-20T05:34:46.341032Z","end":"2026-03-20T05:34:47.043845Z","steps":["trace[1421010018] 'agreement among raft nodes before linearized reading' (duration: 702.595161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:47.043903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:46.341011Z","time spent":"702.883218ms","remote":"127.0.0.1:35318","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-03-20T05:34:47.044048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.320154ms","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-03-20T05:34:47.044072Z","caller":"traceutil/trace.go:171","msg":"trace[1153244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"702.359195ms","start":"2026-03-20T05:34:46.341705Z","end":"2026-03-20T05:34:47.044064Z","steps":["trace[1153244] 'agreement among raft nodes before linearized reading' (duration: 702.279693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:47.044095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.790625ms","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-03-20T05:34:47.0441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:46.341696Z","time spent":"702.398236ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:34:47.044127Z","caller":"traceutil/trace.go:171","msg":"trace[920691298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"713.873477ms","start":"2026-03-20T05:34:46.330244Z","end":"2026-03-20T05:34:47.044118Z","steps":["trace[920691298] 'agreement among raft nodes before linearized reading' (duration: 713.748324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:47.044153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:46.330219Z","time spent":"713.927918ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:34:47.952022Z","caller":"traceutil/trace.go:171","msg":"trace[216534762] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"322.770552ms","start":"2026-03-20T05:34:47.62923Z","end":"2026-03-20T05:34:47.952001Z","steps":["trace[216534762] 'process raft request' (duration: 322.644148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:47.952174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:47.629209Z","time spent":"322.888465ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:49.320422Z","caller":"traceutil/trace.go:171","msg":"trace[1947296736] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"196.360477ms","start":"2026-03-20T05:34:49.124038Z","end":"2026-03-20T05:34:49.320399Z","steps":["trace[1947296736] 'process raft request' (duration: 196.237784ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:49.320983Z","caller":"traceutil/trace.go:171","msg":"trace[340419634] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:964; }","duration":"189.375887ms","start":"2026-03-20T05:34:49.131597Z","end":"2026-03-20T05:34:49.320973Z","steps":["trace[340419634] 'read index received' (duration: 189.372036ms)","trace[340419634] 'applied index is now lower than readState.Index' (duration: 3.221µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:49.321498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.878849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:34:49.321632Z","caller":"traceutil/trace.go:171","msg":"trace[2047862969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"190.061982ms","start":"2026-03-20T05:34:49.131553Z","end":"2026-03-20T05:34:49.321615Z","steps":["trace[2047862969] 'agreement among raft nodes before linearized reading' (duration: 189.491009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:49.321763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.782006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-20T05:34:49.321841Z","caller":"traceutil/trace.go:171","msg":"trace[1074349436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"189.881539ms","start":"2026-03-20T05:34:49.131942Z","end":"2026-03-20T05:34:49.321824Z","steps":["trace[1074349436] 'agreement among raft nodes before linearized reading' (duration: 189.672904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:49.322198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.990352ms","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-03-20T05:34:49.322255Z","caller":"traceutil/trace.go:171","msg":"trace[2034439630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"190.078854ms","start":"2026-03-20T05:34:49.132166Z","end":"2026-03-20T05:34:49.322245Z","steps":["trace[2034439630] 'agreement among raft nodes before linearized reading' (duration: 189.941471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:50.132853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.604919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:50.133143Z","caller":"traceutil/trace.go:171","msg":"trace[1170899785] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:964; }","duration":"177.381815ms","start":"2026-03-20T05:34:49.955742Z","end":"2026-03-20T05:34:50.133124Z","steps":["trace[1170899785] 'read index received' (duration: 143.699009ms)","trace[1170899785] 'applied index is now lower than readState.Index' (duration: 33.681596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:50.133303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.569399ms","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-03-20T05:34:50.133323Z","caller":"traceutil/trace.go:171","msg":"trace[1600006052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"177.593079ms","start":"2026-03-20T05:34:49.955723Z","end":"2026-03-20T05:34:50.133316Z","steps":["trace[1600006052] 'agreement among raft nodes before linearized reading' (duration: 177.448926ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:50.133326Z","caller":"traceutil/trace.go:171","msg":"trace[1922822689] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"806.093666ms","start":"2026-03-20T05:34:49.327194Z","end":"2026-03-20T05:34:50.133288Z","steps":["trace[1922822689] 'process raft request' (duration: 805.796229ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:50.133353Z","caller":"traceutil/trace.go:171","msg":"trace[704250581] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"806.013953ms","start":"2026-03-20T05:34:49.327307Z","end":"2026-03-20T05:34:50.133321Z","steps":["trace[704250581] 'process raft request' (duration: 805.775108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:50.133499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:49.327152Z","time spent":"806.243269ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:50.133473Z","caller":"traceutil/trace.go:171","msg":"trace[136762500] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"806.318651ms","start":"2026-03-20T05:34:49.32711Z","end":"2026-03-20T05:34:50.133428Z","steps":["trace[136762500] 'process raft request' (duration: 60.017856ms)","trace[136762500] 'compare' (duration: 745.314313ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:50.133535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:49.327286Z","time spent":"806.144857ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:50.133721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:49.327074Z","time spent":"806.549706ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:51.022276Z","caller":"traceutil/trace.go:171","msg":"trace[172539065] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"364.46957ms","start":"2026-03-20T05:34:50.657784Z","end":"2026-03-20T05:34:51.022254Z","steps":["trace[172539065] 'read index received' (duration: 364.289916ms)","trace[172539065] 'applied index is now lower than readState.Index' (duration: 178.804µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:51.022503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.696987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T05:34:51.022541Z","caller":"traceutil/trace.go:171","msg":"trace[1794526844] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:928; }","duration":"364.777898ms","start":"2026-03-20T05:34:50.657755Z","end":"2026-03-20T05:34:51.022532Z","steps":["trace[1794526844] 'agreement among raft nodes before linearized reading' (duration: 364.590334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:51.022574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:50.65774Z","time spent":"364.826509ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-03-20T05:34:51.022776Z","caller":"traceutil/trace.go:171","msg":"trace[25840124] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"885.149494ms","start":"2026-03-20T05:34:50.137595Z","end":"2026-03-20T05:34:51.022745Z","steps":["trace[25840124] 'process raft request' (duration: 884.540279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:51.023101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:50.137537Z","time spent":"885.363528ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:51.216654Z","caller":"traceutil/trace.go:171","msg":"trace[1164196308] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"141.646633ms","start":"2026-03-20T05:34:51.07498Z","end":"2026-03-20T05:34:51.216626Z","steps":["trace[1164196308] 'process raft request' (duration: 44.765748ms)","trace[1164196308] 'compare' (duration: 96.762482ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:51.617157Z","caller":"traceutil/trace.go:171","msg":"trace[225105376] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"157.135894ms","start":"2026-03-20T05:34:51.459998Z","end":"2026-03-20T05:34:51.617134Z","steps":["trace[225105376] 'process raft request' (duration: 157.000991ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:51.655329Z","caller":"traceutil/trace.go:171","msg":"trace[1838582074] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"191.446205ms","start":"2026-03-20T05:34:51.463868Z","end":"2026-03-20T05:34:51.655314Z","steps":["trace[1838582074] 'process raft request' (duration: 191.317422ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:51.655511Z","caller":"traceutil/trace.go:171","msg":"trace[1849345633] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"182.03981ms","start":"2026-03-20T05:34:51.473447Z","end":"2026-03-20T05:34:51.655486Z","steps":["trace[1849345633] 'process raft request' (duration: 181.823655ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:51.812999Z","caller":"traceutil/trace.go:171","msg":"trace[1960930360] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"153.267726ms","start":"2026-03-20T05:34:51.659705Z","end":"2026-03-20T05:34:51.812973Z","steps":["trace[1960930360] 'process raft request' (duration: 53.429425ms)","trace[1960930360] 'compare' (duration: 99.659837ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:52.593525Z","caller":"traceutil/trace.go:171","msg":"trace[918998957] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"451.91711ms","start":"2026-03-20T05:34:52.141585Z","end":"2026-03-20T05:34:52.593502Z","steps":["trace[918998957] 'process raft request' (duration: 451.793197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:52.59369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.141535Z","time spent":"452.076943ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:53.323717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.394897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:53.323961Z","caller":"traceutil/trace.go:171","msg":"trace[1451238105] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:977; }","duration":"1.080974668s","start":"2026-03-20T05:34:52.242942Z","end":"2026-03-20T05:34:53.323916Z","steps":["trace[1451238105] 'read index received' (duration: 350.414272ms)","trace[1451238105] 'applied index is now lower than readState.Index' (duration: 730.559626ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:53.32395Z","caller":"traceutil/trace.go:171","msg":"trace[420612938] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.182010347s","start":"2026-03-20T05:34:52.141909Z","end":"2026-03-20T05:34:53.323919Z","steps":["trace[420612938] 'process raft request' (duration: 1.056308803s)","trace[420612938] 'compare' (duration: 125.272804ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:53.324107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.141897Z","time spent":"1.182134749s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:53.324249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081315826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:53.324291Z","caller":"traceutil/trace.go:171","msg":"trace[1051544088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"1.081353666s","start":"2026-03-20T05:34:52.242925Z","end":"2026-03-20T05:34:53.324279Z","steps":["trace[1051544088] 'agreement among raft nodes before linearized reading' (duration: 1.081293975s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:53.324318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.24286Z","time spent":"1.081452609s","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:34:53.324395Z","caller":"traceutil/trace.go:171","msg":"trace[1840445060] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.181655698s","start":"2026-03-20T05:34:52.142696Z","end":"2026-03-20T05:34:53.324351Z","steps":["trace[1840445060] 'process raft request' (duration: 1.181151217s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:53.324586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.142674Z","time spent":"1.181801732s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:53.824821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187689001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:54.987468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.897541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:54.987591Z","caller":"traceutil/trace.go:171","msg":"trace[1024278304] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"1.663553445s","start":"2026-03-20T05:34:53.324016Z","end":"2026-03-20T05:34:54.98757Z","steps":["trace[1024278304] 'read index received' (duration: 859.535612ms)","trace[1024278304] 'applied index is now lower than readState.Index' (duration: 804.016233ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:54.987847Z","caller":"traceutil/trace.go:171","msg":"trace[1377362879] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"2.135201188s","start":"2026-03-20T05:34:52.852627Z","end":"2026-03-20T05:34:54.987828Z","steps":["trace[1377362879] 'process raft request' (duration: 1.330830318s)","trace[1377362879] 'compare' (duration: 803.789067ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:54.987935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.852601Z","time spent":"2.135285899s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:54.988464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960607312s","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-03-20T05:34:54.98854Z","caller":"traceutil/trace.go:171","msg":"trace[231144208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"1.960721235s","start":"2026-03-20T05:34:53.027801Z","end":"2026-03-20T05:34:54.988522Z","steps":["trace[231144208] 'agreement among raft nodes before linearized reading' (duration: 1.960500621s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:54.988581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:53.027777Z","time spent":"1.960796406s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:54.988591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.279210986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:54.988684Z","caller":"traceutil/trace.go:171","msg":"trace[185689219] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:940; }","duration":"2.27936524s","start":"2026-03-20T05:34:52.709287Z","end":"2026-03-20T05:34:54.988652Z","steps":["trace[185689219] 'agreement among raft nodes before linearized reading' (duration: 2.278863529s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:54.98873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:52.70927Z","time spent":"2.279448552s","remote":"127.0.0.1:35370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:34:55.325833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000030885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-20T05:34:55.325957Z","caller":"traceutil/trace.go:171","msg":"trace[671264257] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000217399s","start":"2026-03-20T05:34:53.325709Z","end":"2026-03-20T05:34:55.325927Z","steps":["trace[671264257] 'agreement among raft nodes before linearized reading' (duration: 2.000071696s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:55.32603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:53.325686Z","time spent":"2.000327842s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/20 05:34:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-20T05:34:55.356483Z","caller":"traceutil/trace.go:171","msg":"trace[890307077] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.044784394s","start":"2026-03-20T05:34:54.311681Z","end":"2026-03-20T05:34:55.356465Z","steps":["trace[890307077] 'process raft request' (duration: 960.473868ms)","trace[890307077] 'compare' (duration: 83.965249ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:55.356596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:54.311663Z","time spent":"1.044868446s","remote":"127.0.0.1:35244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5958,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:55.356587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.653373ms","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-03-20T05:34:55.356659Z","caller":"traceutil/trace.go:171","msg":"trace[1845692814] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:941; }","duration":"718.805026ms","start":"2026-03-20T05:34:54.637841Z","end":"2026-03-20T05:34:55.356646Z","steps":["trace[1845692814] 'agreement among raft nodes before linearized reading' (duration: 718.646883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:55.356689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:54.637819Z","time spent":"718.863408ms","remote":"127.0.0.1:41886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:34:55.356899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.16173ms","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-03-20T05:34:55.356959Z","caller":"traceutil/trace.go:171","msg":"trace[502437400] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"368.796506ms","start":"2026-03-20T05:34:54.987624Z","end":"2026-03-20T05:34:55.356421Z","steps":["trace[502437400] 'read index received' (duration: 284.571412ms)","trace[502437400] 'applied index is now lower than readState.Index' (duration: 84.224154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:55.357027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926832885s","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-03-20T05:34:55.356978Z","caller":"traceutil/trace.go:171","msg":"trace[2064411341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"759.302563ms","start":"2026-03-20T05:34:54.597658Z","end":"2026-03-20T05:34:55.35696Z","steps":["trace[2064411341] 'agreement among raft nodes before linearized reading' (duration: 759.090848ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:55.357073Z","caller":"traceutil/trace.go:171","msg":"trace[492673005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"1.926928067s","start":"2026-03-20T05:34:53.430134Z","end":"2026-03-20T05:34:55.357062Z","steps":["trace[492673005] 'agreement among raft nodes before linearized reading' (duration: 1.926787864s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:55.357102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:54.597637Z","time spent":"759.452066ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:34:55.357105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:53.430109Z","time spent":"1.926988968s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:55.857911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187689007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:56.358032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187689007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:56.859152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276101126187689007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:57.029205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.756588935s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T05:34:57.187668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.779794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-20T05:34:57.187611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.154044ms","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-03-20T05:34:57.187972Z","caller":"traceutil/trace.go:171","msg":"trace[1264333552] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"2.130703799s","start":"2026-03-20T05:34:55.057236Z","end":"2026-03-20T05:34:57.18794Z","steps":["trace[1264333552] 'process raft request' (duration: 2.130551646s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.187958Z","caller":"traceutil/trace.go:171","msg":"trace[1018466670] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:941; }","duration":"892.554043ms","start":"2026-03-20T05:34:56.295378Z","end":"2026-03-20T05:34:57.187932Z","steps":["trace[1018466670] 'range keys from in-memory index tree' (duration: 892.131914ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.188134Z","caller":"traceutil/trace.go:171","msg":"trace[1942460499] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"2.195519765s","start":"2026-03-20T05:34:54.992599Z","end":"2026-03-20T05:34:57.188118Z","steps":["trace[1942460499] 'process raft request' (duration: 2.037228699s)","trace[1942460499] 'compare' (duration: 157.493187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:57.188233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:54.992573Z","time spent":"2.195602266s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:57.188078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.057205Z","time spent":"2.130824161s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:57.189443Z","caller":"traceutil/trace.go:171","msg":"trace[112301048] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.827837033s","start":"2026-03-20T05:34:55.361574Z","end":"2026-03-20T05:34:57.189411Z","steps":["trace[112301048] 'process raft request' (duration: 1.827610408s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:57.189585Z","caller":"traceutil/trace.go:171","msg":"trace[527879892] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.826727018s","start":"2026-03-20T05:34:55.362719Z","end":"2026-03-20T05:34:57.189446Z","steps":["trace[527879892] 'process raft request' (duration: 1.826525543s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.189702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.362695Z","time spent":"1.826949813s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:57.18944Z","caller":"traceutil/trace.go:171","msg":"trace[1445294355] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.85977811s","start":"2026-03-20T05:34:55.329631Z","end":"2026-03-20T05:34:57.18941Z","steps":["trace[1445294355] 'process raft request' (duration: 1.859424582s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.19001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.329613Z","time spent":"1.860348873s","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:34:57.19023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.361518Z","time spent":"1.82811902s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:57.224502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889565819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:57.224567Z","caller":"traceutil/trace.go:171","msg":"trace[215862112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"1.889672182s","start":"2026-03-20T05:34:55.334878Z","end":"2026-03-20T05:34:57.22455Z","steps":["trace[215862112] 'agreement among raft nodes before linearized reading' (duration: 1.889564899s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.224612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.334853Z","time spent":"1.889748943s","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:57.224753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895513642s","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-03-20T05:34:57.224819Z","caller":"traceutil/trace.go:171","msg":"trace[1340545894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"1.895732397s","start":"2026-03-20T05:34:55.32907Z","end":"2026-03-20T05:34:57.224803Z","steps":["trace[1340545894] 'agreement among raft nodes before linearized reading' (duration: 1.895456611s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.224906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.329042Z","time spent":"1.895854949s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:34:57.224903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895896999s","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-03-20T05:34:57.224893Z","caller":"traceutil/trace.go:171","msg":"trace[245389847] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:982; }","duration":"1.867166053s","start":"2026-03-20T05:34:55.357054Z","end":"2026-03-20T05:34:57.22422Z","steps":["trace[245389847] 'read index received' (duration: 1.672563595s)","trace[245389847] 'applied index is now lower than readState.Index' (duration: 194.600688ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:57.224983Z","caller":"traceutil/trace.go:171","msg":"trace[1644315145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"1.896099114s","start":"2026-03-20T05:34:55.328869Z","end":"2026-03-20T05:34:57.224969Z","steps":["trace[1644315145] 'agreement among raft nodes before linearized reading' (duration: 1.89589749s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.225059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.328845Z","time spent":"1.896202376s","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:57.225259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.403811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-03-20T05:34:57.225265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.41346ms","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":"info","ts":"2026-03-20T05:34:57.225287Z","caller":"traceutil/trace.go:171","msg":"trace[2062686523] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:946; }","duration":"566.471713ms","start":"2026-03-20T05:34:56.658808Z","end":"2026-03-20T05:34:57.22528Z","steps":["trace[2062686523] 'agreement among raft nodes before linearized reading' (duration: 566.33606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.225309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:56.658777Z","time spent":"566.527304ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-20T05:34:57.225315Z","caller":"traceutil/trace.go:171","msg":"trace[1295097123] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:946; }","duration":"178.527993ms","start":"2026-03-20T05:34:57.046772Z","end":"2026-03-20T05:34:57.2253Z","steps":["trace[1295097123] 'agreement among raft nodes before linearized reading' (duration: 178.437331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.22526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.796017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:57.225451Z","caller":"traceutil/trace.go:171","msg":"trace[1950292134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"827.023081ms","start":"2026-03-20T05:34:56.398409Z","end":"2026-03-20T05:34:57.225432Z","steps":["trace[1950292134] 'agreement among raft nodes before linearized reading' (duration: 826.801657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.225629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:56.398385Z","time spent":"827.225006ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:57.225277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624310356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:34:57.22581Z","caller":"traceutil/trace.go:171","msg":"trace[1700741780] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:946; }","duration":"1.624861038s","start":"2026-03-20T05:34:55.600917Z","end":"2026-03-20T05:34:57.225778Z","steps":["trace[1700741780] 'agreement among raft nodes before linearized reading' (duration: 1.624232274s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:57.225872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:55.600857Z","time spent":"1.625000682s","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:35:01.656012Z","caller":"traceutil/trace.go:171","msg":"trace[1796154066] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"428.218111ms","start":"2026-03-20T05:35:01.227768Z","end":"2026-03-20T05:35:01.655986Z","steps":["trace[1796154066] 'read index received' (duration: 427.971086ms)","trace[1796154066] 'applied index is now lower than readState.Index' (duration: 245.415µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:35:01.656045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:00.659602Z","time spent":"996.431382ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-20T05:35:01.656206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.359662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:35:01.656249Z","caller":"traceutil/trace.go:171","msg":"trace[682327953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"413.448484ms","start":"2026-03-20T05:35:01.242788Z","end":"2026-03-20T05:35:01.656236Z","steps":["trace[682327953] 'agreement among raft nodes before linearized reading' (duration: 413.353981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:01.656296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.24277Z","time spent":"413.517687ms","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:35:01.656301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.504557ms","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-03-20T05:35:01.656385Z","caller":"traceutil/trace.go:171","msg":"trace[967415105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"428.651971ms","start":"2026-03-20T05:35:01.227711Z","end":"2026-03-20T05:35:01.656363Z","steps":["trace[967415105] 'agreement among raft nodes before linearized reading' (duration: 428.396355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:01.656435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.227689Z","time spent":"428.735573ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:35:01.656414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.541969ms","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-03-20T05:35:01.656499Z","caller":"traceutil/trace.go:171","msg":"trace[1414723333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"400.661292ms","start":"2026-03-20T05:35:01.255815Z","end":"2026-03-20T05:35:01.656477Z","steps":["trace[1414723333] 'agreement among raft nodes before linearized reading' (duration: 400.392925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:01.656543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.255805Z","time spent":"400.729403ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:35:01.6566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.027672ms","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-03-20T05:35:01.656715Z","caller":"traceutil/trace.go:171","msg":"trace[1487352637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"402.211776ms","start":"2026-03-20T05:35:01.254476Z","end":"2026-03-20T05:35:01.656687Z","steps":["trace[1487352637] 'agreement among raft nodes before linearized reading' (duration: 401.789406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:01.656754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.917251ms","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-03-20T05:35:01.656771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.254456Z","time spent":"402.304428ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T05:35:01.656792Z","caller":"traceutil/trace.go:171","msg":"trace[1535207183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"308.991532ms","start":"2026-03-20T05:35:01.347791Z","end":"2026-03-20T05:35:01.656782Z","steps":["trace[1535207183] 'agreement among raft nodes before linearized reading' (duration: 308.848369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:01.656838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.347764Z","time spent":"309.052603ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:35:01.65703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.455628ms","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-03-20T05:35:01.657085Z","caller":"traceutil/trace.go:171","msg":"trace[942667966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"340.536211ms","start":"2026-03-20T05:35:01.316534Z","end":"2026-03-20T05:35:01.65707Z","steps":["trace[942667966] 'agreement among raft nodes before linearized reading' (duration: 340.408268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:01.657123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.316518Z","time spent":"340.598122ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:35:02.126592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.6224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:35:02.126754Z","caller":"traceutil/trace.go:171","msg":"trace[569740921] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"468.467742ms","start":"2026-03-20T05:35:01.658263Z","end":"2026-03-20T05:35:02.12673Z","steps":["trace[569740921] 'process raft request' (duration: 128.557976ms)","trace[569740921] 'compare' (duration: 339.189921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:35:02.12693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.65823Z","time spent":"468.661307ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:02.140124Z","caller":"traceutil/trace.go:171","msg":"trace[27879098] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"478.579167ms","start":"2026-03-20T05:35:01.6615Z","end":"2026-03-20T05:35:02.140079Z","steps":["trace[27879098] 'process raft request' (duration: 478.088437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:02.14044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.661485Z","time spent":"478.786741ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:02.140461Z","caller":"traceutil/trace.go:171","msg":"trace[1384185047] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"478.166447ms","start":"2026-03-20T05:35:01.662267Z","end":"2026-03-20T05:35:02.140433Z","steps":["trace[1384185047] 'process raft request' (duration: 477.725087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:02.140621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.662256Z","time spent":"478.284709ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:02.140769Z","caller":"traceutil/trace.go:171","msg":"trace[234217932] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"478.173737ms","start":"2026-03-20T05:35:01.662586Z","end":"2026-03-20T05:35:02.14076Z","steps":["trace[234217932] 'process raft request' (duration: 477.763988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:02.140828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.662548Z","time spent":"478.249329ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:02.140837Z","caller":"traceutil/trace.go:171","msg":"trace[253911196] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"478.130076ms","start":"2026-03-20T05:35:01.662695Z","end":"2026-03-20T05:35:02.140825Z","steps":["trace[253911196] 'process raft request' (duration: 477.709097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:02.140896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.662687Z","time spent":"478.177647ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:02.140969Z","caller":"traceutil/trace.go:171","msg":"trace[1590501712] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"478.453624ms","start":"2026-03-20T05:35:01.662503Z","end":"2026-03-20T05:35:02.140956Z","steps":["trace[1590501712] 'process raft request' (duration: 477.754608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:35:02.141041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:35:01.66248Z","time spent":"478.525135ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:35:04.275025Z","caller":"traceutil/trace.go:171","msg":"trace[420274534] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"125.650512ms","start":"2026-03-20T05:35:04.14935Z","end":"2026-03-20T05:35:04.275001Z","steps":["trace[420274534] 'process raft request' (duration: 125.486699ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:04.276491Z","caller":"traceutil/trace.go:171","msg":"trace[513827892] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"126.275766ms","start":"2026-03-20T05:35:04.150194Z","end":"2026-03-20T05:35:04.27647Z","steps":["trace[513827892] 'process raft request' (duration: 125.652253ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:04.276343Z","caller":"traceutil/trace.go:171","msg":"trace[1954259793] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"126.246505ms","start":"2026-03-20T05:35:04.150074Z","end":"2026-03-20T05:35:04.27632Z","steps":["trace[1954259793] 'process raft request' (duration: 125.651642ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:04.276503Z","caller":"traceutil/trace.go:171","msg":"trace[971048290] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"126.185704ms","start":"2026-03-20T05:35:04.15029Z","end":"2026-03-20T05:35:04.276476Z","steps":["trace[971048290] 'process raft request' (duration: 125.599531ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:04.276472Z","caller":"traceutil/trace.go:171","msg":"trace[737410230] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"125.769085ms","start":"2026-03-20T05:35:04.150671Z","end":"2026-03-20T05:35:04.27644Z","steps":["trace[737410230] 'process raft request' (duration: 125.257414ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:05.383794Z","caller":"traceutil/trace.go:171","msg":"trace[830705592] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"118.365851ms","start":"2026-03-20T05:35:05.265402Z","end":"2026-03-20T05:35:05.383768Z","steps":["trace[830705592] 'process raft request' (duration: 64.715113ms)","trace[830705592] 'compare' (duration: 53.142087ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:06.413611Z","caller":"traceutil/trace.go:171","msg":"trace[775430870] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"128.840982ms","start":"2026-03-20T05:35:06.284743Z","end":"2026-03-20T05:35:06.413584Z","steps":["trace[775430870] 'process raft request' (duration: 128.666489ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:06.413691Z","caller":"traceutil/trace.go:171","msg":"trace[2062621801] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"127.952913ms","start":"2026-03-20T05:35:06.285714Z","end":"2026-03-20T05:35:06.413667Z","steps":["trace[2062621801] 'process raft request' (duration: 127.747869ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:06.413606Z","caller":"traceutil/trace.go:171","msg":"trace[1653624044] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"129.072988ms","start":"2026-03-20T05:35:06.284496Z","end":"2026-03-20T05:35:06.413569Z","steps":["trace[1653624044] 'process raft request' (duration: 122.180605ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:06.413876Z","caller":"traceutil/trace.go:171","msg":"trace[1825703504] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"126.030541ms","start":"2026-03-20T05:35:06.287833Z","end":"2026-03-20T05:35:06.413863Z","steps":["trace[1825703504] 'process raft request' (duration: 125.690563ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:07.776938Z","caller":"traceutil/trace.go:171","msg":"trace[784967766] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"266.829968ms","start":"2026-03-20T05:35:07.510075Z","end":"2026-03-20T05:35:07.776905Z","steps":["trace[784967766] 'process raft request' (duration: 264.741302ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:08.498826Z","caller":"traceutil/trace.go:171","msg":"trace[1956340474] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"172.244423ms","start":"2026-03-20T05:35:08.326545Z","end":"2026-03-20T05:35:08.498789Z","steps":["trace[1956340474] 'process raft request' (duration: 172.042349ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:08.609118Z","caller":"traceutil/trace.go:171","msg":"trace[364533314] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"101.13216ms","start":"2026-03-20T05:35:08.507943Z","end":"2026-03-20T05:35:08.609075Z","steps":["trace[364533314] 'process raft request' (duration: 101.067378ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:08.609254Z","caller":"traceutil/trace.go:171","msg":"trace[235387971] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"103.272846ms","start":"2026-03-20T05:35:08.50593Z","end":"2026-03-20T05:35:08.609203Z","steps":["trace[235387971] 'process raft request' (duration: 36.1505ms)","trace[235387971] 'compare' (duration: 66.730267ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:35:08.609342Z","caller":"traceutil/trace.go:171","msg":"trace[194007305] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"101.54702ms","start":"2026-03-20T05:35:08.507752Z","end":"2026-03-20T05:35:08.609299Z","steps":["trace[194007305] 'process raft request' (duration: 101.212441ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:26.986525Z","caller":"traceutil/trace.go:171","msg":"trace[463235781] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"114.085187ms","start":"2026-03-20T05:35:26.872394Z","end":"2026-03-20T05:35:26.986479Z","steps":["trace[463235781] 'process raft request' (duration: 71.985815ms)","trace[463235781] 'compare' (duration: 41.942228ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:36:45.818228Z","caller":"traceutil/trace.go:171","msg":"trace[1282900664] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"114.800529ms","start":"2026-03-20T05:36:45.703401Z","end":"2026-03-20T05:36:45.818202Z","steps":["trace[1282900664] 'process raft request' (duration: 114.653526ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:11.940988Z","caller":"traceutil/trace.go:171","msg":"trace[668747080] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"181.453687ms","start":"2026-03-20T05:38:11.759511Z","end":"2026-03-20T05:38:11.940965Z","steps":["trace[668747080] 'process raft request' (duration: 181.13757ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:12.100774Z","caller":"traceutil/trace.go:171","msg":"trace[703450071] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"155.76577ms","start":"2026-03-20T05:38:11.944985Z","end":"2026-03-20T05:38:12.10075Z","steps":["trace[703450071] 'process raft request' (duration: 155.576586ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:12.102108Z","caller":"traceutil/trace.go:171","msg":"trace[1984968209] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"156.347033ms","start":"2026-03-20T05:38:11.945741Z","end":"2026-03-20T05:38:12.102088Z","steps":["trace[1984968209] 'process raft request' (duration: 156.147207ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:12.102209Z","caller":"traceutil/trace.go:171","msg":"trace[984282356] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"156.285561ms","start":"2026-03-20T05:38:11.945885Z","end":"2026-03-20T05:38:12.102171Z","steps":["trace[984282356] 'process raft request' (duration: 156.121048ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:12.102078Z","caller":"traceutil/trace.go:171","msg":"trace[562998522] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1864; }","duration":"152.654052ms","start":"2026-03-20T05:38:11.949392Z","end":"2026-03-20T05:38:12.102046Z","steps":["trace[562998522] 'read index received' (duration: 151.102027ms)","trace[562998522] 'applied index is now lower than readState.Index' (duration: 1.550925ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:38:12.102477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.05491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-03-20T05:38:12.102561Z","caller":"traceutil/trace.go:171","msg":"trace[262312540] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1785; }","duration":"153.169843ms","start":"2026-03-20T05:38:11.949375Z","end":"2026-03-20T05:38:12.102545Z","steps":["trace[262312540] 'agreement among raft nodes before linearized reading' (duration: 152.912137ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:18.696246Z","caller":"traceutil/trace.go:171","msg":"trace[1777137228] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"119.204621ms","start":"2026-03-20T05:38:18.577017Z","end":"2026-03-20T05:38:18.696221Z","steps":["trace[1777137228] 'process raft request' (duration: 119.067558ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:19.267819Z","caller":"traceutil/trace.go:171","msg":"trace[2047194154] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"485.251864ms","start":"2026-03-20T05:38:18.782546Z","end":"2026-03-20T05:38:19.267798Z","steps":["trace[2047194154] 'process raft request' (duration: 474.305833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:19.267953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:38:18.782519Z","time spent":"485.354338ms","remote":"127.0.0.1:35318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:38:50.477463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.749439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pp9nt\" ","response":"range_response_count:1 size:11390"} {"level":"info","ts":"2026-03-20T05:38:50.477556Z","caller":"traceutil/trace.go:171","msg":"trace[836204863] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-pp9nt; range_end:; response_count:1; response_revision:1950; }","duration":"136.877942ms","start":"2026-03-20T05:38:50.340645Z","end":"2026-03-20T05:38:50.477523Z","steps":["trace[836204863] 'range keys from in-memory index tree' (duration: 136.582175ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:38:51.209041Z","caller":"traceutil/trace.go:171","msg":"trace[195972973] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"126.525313ms","start":"2026-03-20T05:38:51.082491Z","end":"2026-03-20T05:38:51.209017Z","steps":["trace[195972973] 'process raft request' (duration: 126.146855ms)"],"step_count":1}