{"level":"warn","ts":"2026-05-18T00:39:36.308187Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:39:36.308293Z","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-05-18T00:39:36.308477Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T00:39:36.308512Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-18T00:39:36.308573Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-18T00:39:36.310291Z","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-05-18T00:39:36.310862Z","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-05-18T00:39:36.315864Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.402962ms"} {"level":"info","ts":"2026-05-18T00:39:36.329974Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-18T00:39:36.330054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T00:39:36.330074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-18T00:39:36.330118Z","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-05-18T00:39:36.330137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-18T00:39:36.330199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-18T00:39:36.336347Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T00:39:36.337758Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T00:39:36.340418Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-18T00:39:36.369274Z","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-05-18T00:39:36.394661Z","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-05-18T00:39:36.394998Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-18T00:39:36.395081Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-18T00:39:36.395275Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-18T00:39:36.397341Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-18T00:39:36.397744Z","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-05-18T00:39:36.397849Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T00:39:36.397995Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-18T00:39:36.39806Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-18T00:39:36.399386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-18T00:39:36.399495Z","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-05-18T00:39:36.531388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T00:39:36.531459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T00:39:36.531487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-18T00:39:36.531525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-18T00:39:36.531539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-18T00:39:36.53155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-18T00:39:36.53156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-18T00:39:36.536406Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:39:36.539267Z","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-05-18T00:39:36.539465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:39:36.539552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:39:36.539576Z","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-05-18T00:39:36.539884Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T00:39:36.539995Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T00:39:36.539826Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:39:36.540087Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:39:36.544862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-05-18T00:39:36.548409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-18T00:39:41.28144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670042099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T00:39:41.48883Z","caller":"traceutil/trace.go:171","msg":"trace[1615926280] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"707.736665ms","start":"2026-05-18T00:39:40.78107Z","end":"2026-05-18T00:39:41.488807Z","steps":["trace[1615926280] 'read index received' (duration: 707.698813ms)","trace[1615926280] 'applied index is now lower than readState.Index' (duration: 36.902µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:39:41.488975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.901989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:39:41.489027Z","caller":"traceutil/trace.go:171","msg":"trace[1166838123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:205; }","duration":"708.056664ms","start":"2026-05-18T00:39:40.780961Z","end":"2026-05-18T00:39:41.489017Z","steps":["trace[1166838123] 'agreement among raft nodes before linearized reading' (duration: 707.968982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:41.489061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:40.780908Z","time spent":"708.147078ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T00:39:41.489117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.91319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:39:41.489076Z","caller":"traceutil/trace.go:171","msg":"trace[1762707074] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"910.191366ms","start":"2026-05-18T00:39:40.578747Z","end":"2026-05-18T00:39:41.488938Z","steps":["trace[1762707074] 'process raft request' (duration: 909.952118ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:39:41.489214Z","caller":"traceutil/trace.go:171","msg":"trace[965201237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:205; }","duration":"169.069325ms","start":"2026-05-18T00:39:41.320122Z","end":"2026-05-18T00:39:41.489192Z","steps":["trace[965201237] 'agreement among raft nodes before linearized reading' (duration: 168.882109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:41.489969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:40.578732Z","time spent":"910.477476ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:39:42.661704Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.166873793s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-18T00:39:42.897387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.231849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:39:42.897656Z","caller":"traceutil/trace.go:171","msg":"trace[1339657271] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:210; }","duration":"655.831825ms","start":"2026-05-18T00:39:42.241807Z","end":"2026-05-18T00:39:42.897639Z","steps":["trace[1339657271] 'read index received' (duration: 420.05928ms)","trace[1339657271] 'applied index is now lower than readState.Index' (duration: 235.771705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:39:42.897748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.932648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:39:42.897768Z","caller":"traceutil/trace.go:171","msg":"trace[55493113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:210; }","duration":"656.016721ms","start":"2026-05-18T00:39:42.241744Z","end":"2026-05-18T00:39:42.897761Z","steps":["trace[55493113] 'agreement among raft nodes before linearized reading' (duration: 655.947399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:42.89779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:42.241725Z","time spent":"656.059042ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-18T00:39:42.897928Z","caller":"traceutil/trace.go:171","msg":"trace[1617538282] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"1.403148475s","start":"2026-05-18T00:39:41.494767Z","end":"2026-05-18T00:39:42.897916Z","steps":["trace[1617538282] 'process raft request' (duration: 1.167131712s)","trace[1617538282] 'compare' (duration: 235.005601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:39:42.897978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:41.494755Z","time spent":"1.403201206s","remote":"127.0.0.1:52668","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-05-18T00:39:42.898093Z","caller":"traceutil/trace.go:171","msg":"trace[421273330] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"1.403244798s","start":"2026-05-18T00:39:41.494841Z","end":"2026-05-18T00:39:42.898086Z","steps":["trace[421273330] 'process raft request' (duration: 1.402628629s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:42.898122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:41.494817Z","time spent":"1.403291099s","remote":"127.0.0.1:52668","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-05-18T00:39:42.898172Z","caller":"traceutil/trace.go:171","msg":"trace[276420989] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"727.706231ms","start":"2026-05-18T00:39:42.17046Z","end":"2026-05-18T00:39:42.898167Z","steps":["trace[276420989] 'process raft request' (duration: 727.149533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:42.898194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:42.170438Z","time spent":"727.745093ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T00:39:42.898186Z","caller":"traceutil/trace.go:171","msg":"trace[747881160] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"1.403230268s","start":"2026-05-18T00:39:41.49492Z","end":"2026-05-18T00:39:42.898151Z","steps":["trace[747881160] 'process raft request' (duration: 1.402602137s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:42.898277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:41.494913Z","time spent":"1.40332038s","remote":"127.0.0.1:52668","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-05-18T00:39:42.89841Z","caller":"traceutil/trace.go:171","msg":"trace[603704918] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"1.401681738s","start":"2026-05-18T00:39:41.49672Z","end":"2026-05-18T00:39:42.898402Z","steps":["trace[603704918] 'process raft request' (duration: 1.40082075s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:39:42.898447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:41.496709Z","time spent":"1.40172225s","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T00:39:42.898563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:39:41.571228Z","time spent":"1.327333533s","remote":"127.0.0.1:52484","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-18T00:39:42.900833Z","caller":"traceutil/trace.go:171","msg":"trace[11961176] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"193.395166ms","start":"2026-05-18T00:39:42.707383Z","end":"2026-05-18T00:39:42.900778Z","steps":["trace[11961176] 'process raft request' (duration: 193.284282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:08.759152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670042717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:40:08.998976Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092728213s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T00:40:09.002567Z","caller":"traceutil/trace.go:171","msg":"trace[1335584653] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.096446543s","start":"2026-05-18T00:40:07.906095Z","end":"2026-05-18T00:40:09.002542Z","steps":["trace[1335584653] 'process raft request' (duration: 1.09354231s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:09.002697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:07.906079Z","time spent":"1.096555506s","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T00:40:09.005822Z","caller":"traceutil/trace.go:171","msg":"trace[1451803335] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"747.007809ms","start":"2026-05-18T00:40:08.258775Z","end":"2026-05-18T00:40:09.005783Z","steps":["trace[1451803335] 'read index received' (duration: 740.81598ms)","trace[1451803335] 'applied index is now lower than readState.Index' (duration: 6.190959ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:09.006068Z","caller":"traceutil/trace.go:171","msg":"trace[1394803] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.09542603s","start":"2026-05-18T00:40:07.910618Z","end":"2026-05-18T00:40:09.006044Z","steps":["trace[1394803] 'process raft request' (duration: 1.094920074s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:09.00608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.294039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:40:09.006283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:07.910601Z","time spent":"1.095568634s","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:40:09.006253Z","caller":"traceutil/trace.go:171","msg":"trace[1894592224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"747.511316ms","start":"2026-05-18T00:40:08.258729Z","end":"2026-05-18T00:40:09.00624Z","steps":["trace[1894592224] 'agreement among raft nodes before linearized reading' (duration: 747.136594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:09.006555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:08.25871Z","time spent":"747.750633ms","remote":"127.0.0.1:52382","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-05-18T00:40:15.237782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.437207ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-18T00:40:15.237868Z","caller":"traceutil/trace.go:171","msg":"trace[676023413] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"202.297871ms","start":"2026-05-18T00:40:15.035556Z","end":"2026-05-18T00:40:15.237854Z","steps":["trace[676023413] 'read index received' (duration: 37.846724ms)","trace[676023413] 'applied index is now lower than readState.Index' (duration: 164.449967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:40:15.23807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.498758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hqr59\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-18T00:40:15.238157Z","caller":"traceutil/trace.go:171","msg":"trace[2104685403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hqr59; range_end:; response_count:1; response_revision:618; }","duration":"202.605522ms","start":"2026-05-18T00:40:15.035517Z","end":"2026-05-18T00:40:15.238123Z","steps":["trace[2104685403] 'agreement among raft nodes before linearized reading' (duration: 202.400985ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:15.283861Z","caller":"traceutil/trace.go:171","msg":"trace[1288048477] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"217.470179ms","start":"2026-05-18T00:40:15.066247Z","end":"2026-05-18T00:40:15.283717Z","steps":["trace[1288048477] 'process raft request' (duration: 216.294551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:15.283998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.904678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-18T00:40:15.284145Z","caller":"traceutil/trace.go:171","msg":"trace[320812429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"106.120315ms","start":"2026-05-18T00:40:15.178012Z","end":"2026-05-18T00:40:15.284132Z","steps":["trace[320812429] 'agreement among raft nodes before linearized reading' (duration: 105.761513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:15.284002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.42264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-18T00:40:15.284271Z","caller":"traceutil/trace.go:171","msg":"trace[1407057586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"185.75053ms","start":"2026-05-18T00:40:15.098504Z","end":"2026-05-18T00:40:15.284255Z","steps":["trace[1407057586] 'agreement among raft nodes before linearized reading' (duration: 185.104619ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:15.776097Z","caller":"traceutil/trace.go:171","msg":"trace[1486979825] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"524.627415ms","start":"2026-05-18T00:40:15.251165Z","end":"2026-05-18T00:40:15.775792Z","steps":["trace[1486979825] 'process raft request' (duration: 520.890595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:15.776231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.250924Z","time spent":"525.247865ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:40:15.814331Z","caller":"traceutil/trace.go:171","msg":"trace[359515409] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"525.500843ms","start":"2026-05-18T00:40:15.288806Z","end":"2026-05-18T00:40:15.814307Z","steps":["trace[359515409] 'process raft request' (duration: 525.238504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:15.814488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.288778Z","time spent":"525.629447ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:40:15.814689Z","caller":"traceutil/trace.go:171","msg":"trace[1831899317] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"522.351071ms","start":"2026-05-18T00:40:15.29231Z","end":"2026-05-18T00:40:15.814661Z","steps":["trace[1831899317] 'process raft request' (duration: 521.953148ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:15.814739Z","caller":"traceutil/trace.go:171","msg":"trace[1770468521] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"455.784475ms","start":"2026-05-18T00:40:15.358939Z","end":"2026-05-18T00:40:15.814723Z","steps":["trace[1770468521] 'process raft request' (duration: 455.750894ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:15.814842Z","caller":"traceutil/trace.go:171","msg":"trace[819984890] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"522.006791ms","start":"2026-05-18T00:40:15.292824Z","end":"2026-05-18T00:40:15.814831Z","steps":["trace[819984890] 'process raft request' (duration: 521.762683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:15.814884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.292299Z","time spent":"522.472846ms","remote":"127.0.0.1:52668","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-05-18T00:40:15.814907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.292817Z","time spent":"522.057422ms","remote":"127.0.0.1:52668","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-05-18T00:40:15.814991Z","caller":"traceutil/trace.go:171","msg":"trace[75965920] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"521.017519ms","start":"2026-05-18T00:40:15.293961Z","end":"2026-05-18T00:40:15.814979Z","steps":["trace[75965920] 'process raft request' (duration: 520.693909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:15.81508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.293942Z","time spent":"521.089651ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:40:15.815354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.358911Z","time spent":"456.415856ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T00:40:17.46556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.675549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:40:17.46571Z","caller":"traceutil/trace.go:171","msg":"trace[501696059] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"1.20635404s","start":"2026-05-18T00:40:16.259339Z","end":"2026-05-18T00:40:17.465693Z","steps":["trace[501696059] 'read index received' (duration: 436.385422ms)","trace[501696059] 'applied index is now lower than readState.Index' (duration: 769.967328ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:17.465818Z","caller":"traceutil/trace.go:171","msg":"trace[2009338337] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.63299676s","start":"2026-05-18T00:40:15.832793Z","end":"2026-05-18T00:40:17.46579Z","steps":["trace[2009338337] 'process raft request' (duration: 863.031923ms)","trace[2009338337] 'compare' (duration: 769.371738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:40:17.465876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206527556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:17.465918Z","caller":"traceutil/trace.go:171","msg":"trace[96800948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.206613189s","start":"2026-05-18T00:40:16.259292Z","end":"2026-05-18T00:40:17.465905Z","steps":["trace[96800948] 'agreement among raft nodes before linearized reading' (duration: 1.206509996s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:17.465946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:16.259271Z","time spent":"1.206669611s","remote":"127.0.0.1:52382","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-05-18T00:40:17.465951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:15.832774Z","time spent":"1.633123405s","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T00:40:17.933354Z","caller":"traceutil/trace.go:171","msg":"trace[785575456] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"109.773573ms","start":"2026-05-18T00:40:17.823556Z","end":"2026-05-18T00:40:17.93333Z","steps":["trace[785575456] 'process raft request' (duration: 109.635108ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:17.934134Z","caller":"traceutil/trace.go:171","msg":"trace[591155524] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"110.181265ms","start":"2026-05-18T00:40:17.823931Z","end":"2026-05-18T00:40:17.934112Z","steps":["trace[591155524] 'process raft request' (duration: 110.005539ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:17.934482Z","caller":"traceutil/trace.go:171","msg":"trace[1419644327] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"109.784452ms","start":"2026-05-18T00:40:17.824681Z","end":"2026-05-18T00:40:17.934466Z","steps":["trace[1419644327] 'process raft request' (duration: 109.40258ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:17.934707Z","caller":"traceutil/trace.go:171","msg":"trace[1914874133] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"109.850404ms","start":"2026-05-18T00:40:17.824835Z","end":"2026-05-18T00:40:17.934686Z","steps":["trace[1914874133] 'process raft request' (duration: 109.528844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:18.314082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.103177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:18.314345Z","caller":"traceutil/trace.go:171","msg":"trace[680846101] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:638; }","duration":"107.395976ms","start":"2026-05-18T00:40:18.206933Z","end":"2026-05-18T00:40:18.314329Z","steps":["trace[680846101] 'count revisions from in-memory index tree' (duration: 107.059465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:18.314108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.233118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:40:18.314201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.158478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:18.314626Z","caller":"traceutil/trace.go:171","msg":"trace[1651257729] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:638; }","duration":"107.592253ms","start":"2026-05-18T00:40:18.207019Z","end":"2026-05-18T00:40:18.314611Z","steps":["trace[1651257729] 'range keys from in-memory index tree' (duration: 107.120387ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:18.314551Z","caller":"traceutil/trace.go:171","msg":"trace[944757545] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:638; }","duration":"106.687013ms","start":"2026-05-18T00:40:18.207808Z","end":"2026-05-18T00:40:18.314494Z","steps":["trace[944757545] 'range keys from in-memory index tree' (duration: 106.170377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:19.416975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.223779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:19.417193Z","caller":"traceutil/trace.go:171","msg":"trace[810800700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"159.54174ms","start":"2026-05-18T00:40:19.257624Z","end":"2026-05-18T00:40:19.417166Z","steps":["trace[810800700] 'range keys from in-memory index tree' (duration: 159.100295ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:19.42276Z","caller":"traceutil/trace.go:171","msg":"trace[1866632498] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"161.016987ms","start":"2026-05-18T00:40:19.261727Z","end":"2026-05-18T00:40:19.422744Z","steps":["trace[1866632498] 'process raft request' (duration: 160.915014ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.109894Z","caller":"traceutil/trace.go:171","msg":"trace[1403050595] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"121.812879ms","start":"2026-05-18T00:40:29.988052Z","end":"2026-05-18T00:40:30.109865Z","steps":["trace[1403050595] 'read index received' (duration: 121.551941ms)","trace[1403050595] 'applied index is now lower than readState.Index' (duration: 259.948µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:30.109999Z","caller":"traceutil/trace.go:171","msg":"trace[2144938765] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"122.892134ms","start":"2026-05-18T00:40:29.987056Z","end":"2026-05-18T00:40:30.109948Z","steps":["trace[2144938765] 'process raft request' (duration: 122.628895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:30.110209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.134049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-18T00:40:30.110094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.024226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:40:30.110299Z","caller":"traceutil/trace.go:171","msg":"trace[1257547938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"122.236392ms","start":"2026-05-18T00:40:29.98804Z","end":"2026-05-18T00:40:30.110277Z","steps":["trace[1257547938] 'agreement among raft nodes before linearized reading' (duration: 121.963314ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.11036Z","caller":"traceutil/trace.go:171","msg":"trace[21743050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"122.284564ms","start":"2026-05-18T00:40:29.988025Z","end":"2026-05-18T00:40:30.11031Z","steps":["trace[21743050] 'agreement among raft nodes before linearized reading' (duration: 121.959484ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:30.139063Z","caller":"traceutil/trace.go:171","msg":"trace[1033049855] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"143.503195ms","start":"2026-05-18T00:40:29.995529Z","end":"2026-05-18T00:40:30.139032Z","steps":["trace[1033049855] 'process raft request' (duration: 143.371441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.27093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001079446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-18T00:40:34.271027Z","caller":"traceutil/trace.go:171","msg":"trace[292954984] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001218121s","start":"2026-05-18T00:40:32.269782Z","end":"2026-05-18T00:40:34.271Z","steps":["trace[292954984] 'agreement among raft nodes before linearized reading' (duration: 2.001103867s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.271158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:32.269766Z","time spent":"2.001375996s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/18 00:40:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-18T00:40:34.518906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.315640101s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:40:34.518984Z","caller":"traceutil/trace.go:171","msg":"trace[1764054527] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"2.376747062s","start":"2026-05-18T00:40:32.142224Z","end":"2026-05-18T00:40:34.518971Z","steps":["trace[1764054527] 'read index received' (duration: 60.992427ms)","trace[1764054527] 'applied index is now lower than readState.Index' (duration: 2.315753735s)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:34.519046Z","caller":"traceutil/trace.go:171","msg":"trace[1008716106] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"2.378453576s","start":"2026-05-18T00:40:32.140585Z","end":"2026-05-18T00:40:34.519039Z","steps":["trace[1008716106] 'process raft request' (duration: 62.567267ms)","trace[1008716106] 'compare' (duration: 2.315140725s)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:40:34.519116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:32.140555Z","time spent":"2.378508378s","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:40:34.519286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.372514386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:40:34.51934Z","caller":"traceutil/trace.go:171","msg":"trace[2021713208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"2.372591198s","start":"2026-05-18T00:40:32.146735Z","end":"2026-05-18T00:40:34.519326Z","steps":["trace[2021713208] 'agreement among raft nodes before linearized reading' (duration: 2.372445844s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.519367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:32.146728Z","time spent":"2.372633609s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T00:40:34.519401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.468781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-18T00:40:34.519425Z","caller":"traceutil/trace.go:171","msg":"trace[2097463963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"396.528744ms","start":"2026-05-18T00:40:34.122889Z","end":"2026-05-18T00:40:34.519418Z","steps":["trace[2097463963] 'agreement among raft nodes before linearized reading' (duration: 396.439911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.519446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:34.122873Z","time spent":"396.568555ms","remote":"127.0.0.1:52668","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-05-18T00:40:34.519523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600493049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-18T00:40:34.51957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.377343411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:40:34.519593Z","caller":"traceutil/trace.go:171","msg":"trace[1706084223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"2.377365832s","start":"2026-05-18T00:40:32.142221Z","end":"2026-05-18T00:40:34.519587Z","steps":["trace[1706084223] 'agreement among raft nodes before linearized reading' (duration: 2.37730108s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.519609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:32.142195Z","time spent":"2.377408903s","remote":"127.0.0.1:52668","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-05-18T00:40:34.519687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.496844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:34.519706Z","caller":"traceutil/trace.go:171","msg":"trace[628744911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"248.552666ms","start":"2026-05-18T00:40:34.271146Z","end":"2026-05-18T00:40:34.519699Z","steps":["trace[628744911] 'agreement among raft nodes before linearized reading' (duration: 248.525665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.519782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.372911169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:40:34.519811Z","caller":"traceutil/trace.go:171","msg":"trace[414524358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"2.372964011s","start":"2026-05-18T00:40:32.14684Z","end":"2026-05-18T00:40:34.519804Z","steps":["trace[414524358] 'agreement among raft nodes before linearized reading' (duration: 2.372871428s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.519829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:32.146825Z","time spent":"2.372999841s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T00:40:34.519544Z","caller":"traceutil/trace.go:171","msg":"trace[612787091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"1.600549071s","start":"2026-05-18T00:40:32.918989Z","end":"2026-05-18T00:40:34.519538Z","steps":["trace[612787091] 'agreement among raft nodes before linearized reading' (duration: 1.600489199s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:34.520034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:32.91896Z","time spent":"1.601068088s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:40:39.186037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.370587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-05-18T00:40:39.186114Z","caller":"traceutil/trace.go:171","msg":"trace[832302598] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:838; }","duration":"117.508641ms","start":"2026-05-18T00:40:39.068589Z","end":"2026-05-18T00:40:39.186098Z","steps":["trace[832302598] 'range keys from in-memory index tree' (duration: 117.200001ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:44.256629Z","caller":"traceutil/trace.go:171","msg":"trace[866387290] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"110.492736ms","start":"2026-05-18T00:40:44.146078Z","end":"2026-05-18T00:40:44.256571Z","steps":["trace[866387290] 'process raft request' (duration: 110.301899ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:49.443821Z","caller":"traceutil/trace.go:171","msg":"trace[455743954] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"208.481512ms","start":"2026-05-18T00:40:49.235316Z","end":"2026-05-18T00:40:49.443797Z","steps":["trace[455743954] 'read index received' (duration: 208.340067ms)","trace[455743954] 'applied index is now lower than readState.Index' (duration: 140.445µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:40:49.443847Z","caller":"traceutil/trace.go:171","msg":"trace[369568900] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"210.44729ms","start":"2026-05-18T00:40:49.23337Z","end":"2026-05-18T00:40:49.443817Z","steps":["trace[369568900] 'process raft request' (duration: 210.273414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:49.444026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.995442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:40:49.444058Z","caller":"traceutil/trace.go:171","msg":"trace[1848506229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"186.051484ms","start":"2026-05-18T00:40:49.257998Z","end":"2026-05-18T00:40:49.44405Z","steps":["trace[1848506229] 'agreement among raft nodes before linearized reading' (duration: 185.975641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:40:49.44406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.71972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9674w\" ","response":"range_response_count:1 size:5072"} {"level":"warn","ts":"2026-05-18T00:40:49.444051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.148582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:40:49.444104Z","caller":"traceutil/trace.go:171","msg":"trace[1521166424] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-9674w; range_end:; response_count:1; response_revision:929; }","duration":"208.782152ms","start":"2026-05-18T00:40:49.23531Z","end":"2026-05-18T00:40:49.444092Z","steps":["trace[1521166424] 'agreement among raft nodes before linearized reading' (duration: 208.615667ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:49.444122Z","caller":"traceutil/trace.go:171","msg":"trace[137774472] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:929; }","duration":"187.250606ms","start":"2026-05-18T00:40:49.256855Z","end":"2026-05-18T00:40:49.444106Z","steps":["trace[137774472] 'agreement among raft nodes before linearized reading' (duration: 187.117491ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:40:49.69235Z","caller":"traceutil/trace.go:171","msg":"trace[1039906689] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"240.383488ms","start":"2026-05-18T00:40:49.451942Z","end":"2026-05-18T00:40:49.692326Z","steps":["trace[1039906689] 'process raft request' (duration: 240.223693ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:00.87827Z","caller":"traceutil/trace.go:171","msg":"trace[1563164324] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"117.455364ms","start":"2026-05-18T00:41:00.760794Z","end":"2026-05-18T00:41:00.87825Z","steps":["trace[1563164324] 'process raft request' (duration: 115.995053ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:00.880496Z","caller":"traceutil/trace.go:171","msg":"trace[773236877] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"118.848392ms","start":"2026-05-18T00:41:00.761627Z","end":"2026-05-18T00:41:00.880476Z","steps":["trace[773236877] 'process raft request' (duration: 118.676196ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:00.880695Z","caller":"traceutil/trace.go:171","msg":"trace[49106811] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"118.595514ms","start":"2026-05-18T00:41:00.762074Z","end":"2026-05-18T00:41:00.880669Z","steps":["trace[49106811] 'process raft request' (duration: 118.355316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.251513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670043724,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T00:41:09.706077Z","caller":"traceutil/trace.go:171","msg":"trace[1339497880] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"955.38674ms","start":"2026-05-18T00:41:08.750654Z","end":"2026-05-18T00:41:09.706041Z","steps":["trace[1339497880] 'read index received' (duration: 955.253216ms)","trace[1339497880] 'applied index is now lower than readState.Index' (duration: 132.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:09.706177Z","caller":"traceutil/trace.go:171","msg":"trace[591483311] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"956.196499ms","start":"2026-05-18T00:41:08.749968Z","end":"2026-05-18T00:41:09.706165Z","steps":["trace[591483311] 'process raft request' (duration: 955.93808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.706299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:08.749946Z","time spent":"956.257641ms","remote":"127.0.0.1:52668","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-05-18T00:41:09.70685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.150187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:41:09.706924Z","caller":"traceutil/trace.go:171","msg":"trace[1280036780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"956.282021ms","start":"2026-05-18T00:41:08.750624Z","end":"2026-05-18T00:41:09.706906Z","steps":["trace[1280036780] 'agreement among raft nodes before linearized reading' (duration: 955.630519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.706972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:08.750611Z","time spent":"956.351544ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:41:09.886009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.462916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:41:09.88614Z","caller":"traceutil/trace.go:171","msg":"trace[2035363573] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"179.990212ms","start":"2026-05-18T00:41:09.706137Z","end":"2026-05-18T00:41:09.886127Z","steps":["trace[2035363573] 'read index received' (duration: 40.337739ms)","trace[2035363573] 'applied index is now lower than readState.Index' (duration: 139.651493ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:09.886319Z","caller":"traceutil/trace.go:171","msg":"trace[377188273] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"175.252758ms","start":"2026-05-18T00:41:09.711033Z","end":"2026-05-18T00:41:09.886286Z","steps":["trace[377188273] 'process raft request' (duration: 175.063361ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:09.886335Z","caller":"traceutil/trace.go:171","msg":"trace[1643370836] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"361.361004ms","start":"2026-05-18T00:41:09.524939Z","end":"2026-05-18T00:41:09.8863Z","steps":["trace[1643370836] 'process raft request' (duration: 221.526985ms)","trace[1643370836] 'compare' (duration: 139.356533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:41:09.88641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.349563ms","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-05-18T00:41:09.886447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.37913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:41:09.886457Z","caller":"traceutil/trace.go:171","msg":"trace[380569379] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1003; }","duration":"269.421435ms","start":"2026-05-18T00:41:09.617021Z","end":"2026-05-18T00:41:09.886443Z","steps":["trace[380569379] 'agreement among raft nodes before linearized reading' (duration: 269.26933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.886454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.69377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:41:09.886488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:09.524922Z","time spent":"361.479938ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:41:09.886526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.183468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:41:09.886532Z","caller":"traceutil/trace.go:171","msg":"trace[1365141881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"396.823685ms","start":"2026-05-18T00:41:09.48969Z","end":"2026-05-18T00:41:09.886513Z","steps":["trace[1365141881] 'agreement among raft nodes before linearized reading' (duration: 396.482743ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:09.886548Z","caller":"traceutil/trace.go:171","msg":"trace[958582892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"969.23941ms","start":"2026-05-18T00:41:08.917304Z","end":"2026-05-18T00:41:09.886543Z","steps":["trace[958582892] 'agreement among raft nodes before linearized reading' (duration: 968.903818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.886568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:08.917285Z","time spent":"969.276981ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T00:41:09.886568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:09.489668Z","time spent":"396.891426ms","remote":"127.0.0.1:52382","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-05-18T00:41:09.886477Z","caller":"traceutil/trace.go:171","msg":"trace[2000223341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"970.443152ms","start":"2026-05-18T00:41:08.916026Z","end":"2026-05-18T00:41:09.886469Z","steps":["trace[2000223341] 'agreement among raft nodes before linearized reading' (duration: 970.135952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.886649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:08.916008Z","time spent":"970.630558ms","remote":"127.0.0.1:52668","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-05-18T00:41:09.88671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.770934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:41:09.886731Z","caller":"traceutil/trace.go:171","msg":"trace[60977566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"966.809576ms","start":"2026-05-18T00:41:08.919915Z","end":"2026-05-18T00:41:09.886725Z","steps":["trace[60977566] 'agreement among raft nodes before linearized reading' (duration: 966.258087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:09.886748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:08.919904Z","time spent":"966.839967ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T00:41:10.826412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.712943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:41:10.827029Z","caller":"traceutil/trace.go:171","msg":"trace[548246364] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"927.645669ms","start":"2026-05-18T00:41:09.899357Z","end":"2026-05-18T00:41:10.827003Z","steps":["trace[548246364] 'process raft request' (duration: 474.268302ms)","trace[548246364] 'compare' (duration: 452.59957ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:10.827071Z","caller":"traceutil/trace.go:171","msg":"trace[113408285] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"267.139446ms","start":"2026-05-18T00:41:10.559915Z","end":"2026-05-18T00:41:10.827055Z","steps":["trace[113408285] 'process raft request' (duration: 267.088375ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:10.827113Z","caller":"traceutil/trace.go:171","msg":"trace[837937772] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"567.801676ms","start":"2026-05-18T00:41:10.259246Z","end":"2026-05-18T00:41:10.827048Z","steps":["trace[837937772] 'read index received' (duration: 114.454711ms)","trace[837937772] 'applied index is now lower than readState.Index' (duration: 453.344655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:41:10.827165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:09.899348Z","time spent":"927.734451ms","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:41:10.827228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.979262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:10.827267Z","caller":"traceutil/trace.go:171","msg":"trace[62581515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"568.058844ms","start":"2026-05-18T00:41:10.259197Z","end":"2026-05-18T00:41:10.827256Z","steps":["trace[62581515] 'agreement among raft nodes before linearized reading' (duration: 567.976372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:10.827297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:10.259182Z","time spent":"568.107707ms","remote":"127.0.0.1:52398","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-05-18T00:41:11.926405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.577984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T00:41:11.926532Z","caller":"traceutil/trace.go:171","msg":"trace[247909068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1008; }","duration":"210.757981ms","start":"2026-05-18T00:41:11.715755Z","end":"2026-05-18T00:41:11.926513Z","steps":["trace[247909068] 'range keys from in-memory index tree' (duration: 210.402179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:11.926623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.875413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:11.926687Z","caller":"traceutil/trace.go:171","msg":"trace[1268979164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"651.990707ms","start":"2026-05-18T00:41:11.274679Z","end":"2026-05-18T00:41:11.92667Z","steps":["trace[1268979164] 'range keys from in-memory index tree' (duration: 651.80864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:11.926721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:11.274662Z","time spent":"652.050039ms","remote":"127.0.0.1:52382","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-05-18T00:41:18.334962Z","caller":"traceutil/trace.go:171","msg":"trace[880247573] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"330.180764ms","start":"2026-05-18T00:41:18.004758Z","end":"2026-05-18T00:41:18.334939Z","steps":["trace[880247573] 'process raft request' (duration: 268.610018ms)","trace[880247573] 'compare' (duration: 61.243604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:41:18.335147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:18.004735Z","time spent":"330.289538ms","remote":"127.0.0.1:52668","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-05-18T00:41:18.335138Z","caller":"traceutil/trace.go:171","msg":"trace[743590226] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"330.297958ms","start":"2026-05-18T00:41:18.00476Z","end":"2026-05-18T00:41:18.335058Z","steps":["trace[743590226] 'process raft request' (duration: 330.134042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:18.335351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:18.004733Z","time spent":"330.496705ms","remote":"127.0.0.1:52668","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-05-18T00:41:18.338913Z","caller":"traceutil/trace.go:171","msg":"trace[146330402] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"333.408485ms","start":"2026-05-18T00:41:18.005488Z","end":"2026-05-18T00:41:18.338897Z","steps":["trace[146330402] 'process raft request' (duration: 333.281581ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:18.338924Z","caller":"traceutil/trace.go:171","msg":"trace[1880379066] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"333.460568ms","start":"2026-05-18T00:41:18.005426Z","end":"2026-05-18T00:41:18.338886Z","steps":["trace[1880379066] 'process raft request' (duration: 333.312833ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:18.33891Z","caller":"traceutil/trace.go:171","msg":"trace[837541202] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"333.910853ms","start":"2026-05-18T00:41:18.004956Z","end":"2026-05-18T00:41:18.338867Z","steps":["trace[837541202] 'process raft request' (duration: 333.725027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:18.339001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:18.00548Z","time spent":"333.471848ms","remote":"127.0.0.1:52668","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-05-18T00:41:18.339121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:18.005413Z","time spent":"333.561661ms","remote":"127.0.0.1:52668","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-05-18T00:41:18.339116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:18.004948Z","time spent":"334.048168ms","remote":"127.0.0.1:52668","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-05-18T00:41:32.227626Z","caller":"traceutil/trace.go:171","msg":"trace[1133951434] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"167.868124ms","start":"2026-05-18T00:41:32.059436Z","end":"2026-05-18T00:41:32.227304Z","steps":["trace[1133951434] 'process raft request' (duration: 167.711278ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:32.659698Z","caller":"traceutil/trace.go:171","msg":"trace[473641856] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"253.606058ms","start":"2026-05-18T00:41:32.40607Z","end":"2026-05-18T00:41:32.659676Z","steps":["trace[473641856] 'read index received' (duration: 253.3883ms)","trace[473641856] 'applied index is now lower than readState.Index' (duration: 216.778µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:32.659974Z","caller":"traceutil/trace.go:171","msg":"trace[672167190] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"257.426389ms","start":"2026-05-18T00:41:32.402533Z","end":"2026-05-18T00:41:32.659959Z","steps":["trace[672167190] 'process raft request' (duration: 256.966853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:32.660239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.144476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T00:41:32.660279Z","caller":"traceutil/trace.go:171","msg":"trace[636747625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"254.232839ms","start":"2026-05-18T00:41:32.406036Z","end":"2026-05-18T00:41:32.660269Z","steps":["trace[636747625] 'agreement among raft nodes before linearized reading' (duration: 254.094194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:32.660498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.324448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:41:32.660532Z","caller":"traceutil/trace.go:171","msg":"trace[1975376217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"251.38422ms","start":"2026-05-18T00:41:32.409139Z","end":"2026-05-18T00:41:32.660524Z","steps":["trace[1975376217] 'agreement among raft nodes before linearized reading' (duration: 251.301827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:32.660713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.084507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:41:32.660748Z","caller":"traceutil/trace.go:171","msg":"trace[1238658199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"244.151779ms","start":"2026-05-18T00:41:32.416588Z","end":"2026-05-18T00:41:32.66074Z","steps":["trace[1238658199] 'agreement among raft nodes before linearized reading' (duration: 244.057496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:32.660757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.231962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:41:32.66082Z","caller":"traceutil/trace.go:171","msg":"trace[332531001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1076; }","duration":"244.318165ms","start":"2026-05-18T00:41:32.416489Z","end":"2026-05-18T00:41:32.660808Z","steps":["trace[332531001] 'agreement among raft nodes before linearized reading' (duration: 244.160169ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:32.821911Z","caller":"traceutil/trace.go:171","msg":"trace[1352668020] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"154.361835ms","start":"2026-05-18T00:41:32.667529Z","end":"2026-05-18T00:41:32.821891Z","steps":["trace[1352668020] 'process raft request' (duration: 154.2175ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:32.827345Z","caller":"traceutil/trace.go:171","msg":"trace[2070742988] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"155.9569ms","start":"2026-05-18T00:41:32.671358Z","end":"2026-05-18T00:41:32.827315Z","steps":["trace[2070742988] 'process raft request' (duration: 155.6795ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:32.828737Z","caller":"traceutil/trace.go:171","msg":"trace[1343121496] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"157.37662ms","start":"2026-05-18T00:41:32.671345Z","end":"2026-05-18T00:41:32.828722Z","steps":["trace[1343121496] 'process raft request' (duration: 155.792405ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:32.828745Z","caller":"traceutil/trace.go:171","msg":"trace[382268681] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"142.724371ms","start":"2026-05-18T00:41:32.686004Z","end":"2026-05-18T00:41:32.828729Z","steps":["trace[382268681] 'process raft request' (duration: 142.600126ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:34.620361Z","caller":"traceutil/trace.go:171","msg":"trace[537046632] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"360.782885ms","start":"2026-05-18T00:41:34.259564Z","end":"2026-05-18T00:41:34.620347Z","steps":["trace[537046632] 'read index received' (duration: 360.64078ms)","trace[537046632] 'applied index is now lower than readState.Index' (duration: 141.635µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:34.620421Z","caller":"traceutil/trace.go:171","msg":"trace[50610963] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"388.29999ms","start":"2026-05-18T00:41:34.232088Z","end":"2026-05-18T00:41:34.620388Z","steps":["trace[50610963] 'process raft request' (duration: 388.175005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:34.620487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.487515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:34.620511Z","caller":"traceutil/trace.go:171","msg":"trace[1335789386] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1081; }","duration":"104.541937ms","start":"2026-05-18T00:41:34.515961Z","end":"2026-05-18T00:41:34.620503Z","steps":["trace[1335789386] 'agreement among raft nodes before linearized reading' (duration: 104.511246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:34.620447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.876248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:41:34.620583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:34.232069Z","time spent":"388.420604ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:41:34.620628Z","caller":"traceutil/trace.go:171","msg":"trace[1345922963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"361.075605ms","start":"2026-05-18T00:41:34.259526Z","end":"2026-05-18T00:41:34.620602Z","steps":["trace[1345922963] 'agreement among raft nodes before linearized reading' (duration: 360.892859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:34.620707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:34.25951Z","time spent":"361.169079ms","remote":"127.0.0.1:52382","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-05-18T00:41:34.968858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.255855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-18T00:41:34.968897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.068944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:41:34.968964Z","caller":"traceutil/trace.go:171","msg":"trace[303740177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"136.39637ms","start":"2026-05-18T00:41:34.832532Z","end":"2026-05-18T00:41:34.968928Z","steps":["trace[303740177] 'range keys from in-memory index tree' (duration: 136.055269ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:34.968974Z","caller":"traceutil/trace.go:171","msg":"trace[1821974739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"295.192399ms","start":"2026-05-18T00:41:34.673765Z","end":"2026-05-18T00:41:34.968958Z","steps":["trace[1821974739] 'range keys from in-memory index tree' (duration: 294.884948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:34.969043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.971385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:41:34.969071Z","caller":"traceutil/trace.go:171","msg":"trace[1984111469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"136.015186ms","start":"2026-05-18T00:41:34.833049Z","end":"2026-05-18T00:41:34.969064Z","steps":["trace[1984111469] 'range keys from in-memory index tree' (duration: 135.891522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:34.969187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.422113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:41:34.969282Z","caller":"traceutil/trace.go:171","msg":"trace[1695944008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"143.561748ms","start":"2026-05-18T00:41:34.825698Z","end":"2026-05-18T00:41:34.969259Z","steps":["trace[1695944008] 'range keys from in-memory index tree' (duration: 143.259168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:34.969596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.989371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T00:41:34.970399Z","caller":"traceutil/trace.go:171","msg":"trace[1152714133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"137.822769ms","start":"2026-05-18T00:41:34.832543Z","end":"2026-05-18T00:41:34.970366Z","steps":["trace[1152714133] 'range keys from in-memory index tree' (duration: 136.752863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:36.758024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670043931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T00:41:36.855618Z","caller":"traceutil/trace.go:171","msg":"trace[1621501309] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"935.894774ms","start":"2026-05-18T00:41:35.919704Z","end":"2026-05-18T00:41:36.855599Z","steps":["trace[1621501309] 'process raft request' (duration: 935.76084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:36.855731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:35.919675Z","time spent":"935.996248ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:41:37.244073Z","caller":"traceutil/trace.go:171","msg":"trace[1638898114] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"986.118317ms","start":"2026-05-18T00:41:36.257933Z","end":"2026-05-18T00:41:37.244051Z","steps":["trace[1638898114] 'read index received' (duration: 597.516057ms)","trace[1638898114] 'applied index is now lower than readState.Index' (duration: 388.60121ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:37.244194Z","caller":"traceutil/trace.go:171","msg":"trace[1817864069] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"619.21127ms","start":"2026-05-18T00:41:36.624952Z","end":"2026-05-18T00:41:37.244163Z","steps":["trace[1817864069] 'process raft request' (duration: 618.92163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:37.244329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:36.624929Z","time spent":"619.317763ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:41:37.244339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.285679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:41:37.244374Z","caller":"traceutil/trace.go:171","msg":"trace[1568572306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"260.343351ms","start":"2026-05-18T00:41:36.984021Z","end":"2026-05-18T00:41:37.244364Z","steps":["trace[1568572306] 'agreement among raft nodes before linearized reading' (duration: 260.226367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:37.244217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.272753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:37.244483Z","caller":"traceutil/trace.go:171","msg":"trace[2024022885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"986.582303ms","start":"2026-05-18T00:41:36.257883Z","end":"2026-05-18T00:41:37.244466Z","steps":["trace[2024022885] 'agreement among raft nodes before linearized reading' (duration: 986.287653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:37.244482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.811172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-18T00:41:37.244519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.887284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-18T00:41:37.244525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:36.257858Z","time spent":"986.657406ms","remote":"127.0.0.1:52382","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-05-18T00:41:37.244546Z","caller":"traceutil/trace.go:171","msg":"trace[1800655276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"214.920265ms","start":"2026-05-18T00:41:37.029617Z","end":"2026-05-18T00:41:37.244537Z","steps":["trace[1800655276] 'agreement among raft nodes before linearized reading' (duration: 214.854003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:37.244636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.957936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-18T00:41:37.244656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.086201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:41:37.244673Z","caller":"traceutil/trace.go:171","msg":"trace[204226864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"215.006118ms","start":"2026-05-18T00:41:37.029657Z","end":"2026-05-18T00:41:37.244663Z","steps":["trace[204226864] 'agreement among raft nodes before linearized reading' (duration: 214.925066ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:37.244684Z","caller":"traceutil/trace.go:171","msg":"trace[2035781053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"215.139363ms","start":"2026-05-18T00:41:37.029539Z","end":"2026-05-18T00:41:37.244678Z","steps":["trace[2035781053] 'agreement among raft nodes before linearized reading' (duration: 215.07047ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:37.244545Z","caller":"traceutil/trace.go:171","msg":"trace[318654763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"214.927066ms","start":"2026-05-18T00:41:37.029601Z","end":"2026-05-18T00:41:37.244528Z","steps":["trace[318654763] 'agreement among raft nodes before linearized reading' (duration: 214.745529ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:39.486491Z","caller":"traceutil/trace.go:171","msg":"trace[622213824] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"228.266078ms","start":"2026-05-18T00:41:39.258205Z","end":"2026-05-18T00:41:39.486471Z","steps":["trace[622213824] 'read index received' (duration: 228.094122ms)","trace[622213824] 'applied index is now lower than readState.Index' (duration: 171.026µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:39.486564Z","caller":"traceutil/trace.go:171","msg":"trace[448366985] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"237.493168ms","start":"2026-05-18T00:41:39.249038Z","end":"2026-05-18T00:41:39.486532Z","steps":["trace[448366985] 'process raft request' (duration: 237.323222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:39.486672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.442065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:39.486933Z","caller":"traceutil/trace.go:171","msg":"trace[1178837842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"228.750745ms","start":"2026-05-18T00:41:39.258164Z","end":"2026-05-18T00:41:39.486914Z","steps":["trace[1178837842] 'agreement among raft nodes before linearized reading' (duration: 228.420514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:39.487028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.529084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:41:39.487071Z","caller":"traceutil/trace.go:171","msg":"trace[824484567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"204.592167ms","start":"2026-05-18T00:41:39.282466Z","end":"2026-05-18T00:41:39.487058Z","steps":["trace[824484567] 'agreement among raft nodes before linearized reading' (duration: 204.446182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:39.487202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.026587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-18T00:41:39.487271Z","caller":"traceutil/trace.go:171","msg":"trace[1161392220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"221.132611ms","start":"2026-05-18T00:41:39.26613Z","end":"2026-05-18T00:41:39.487263Z","steps":["trace[1161392220] 'agreement among raft nodes before linearized reading' (duration: 220.767869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:39.487261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.862276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-18T00:41:39.487286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.333073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-18T00:41:39.48733Z","caller":"traceutil/trace.go:171","msg":"trace[784267596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"205.391714ms","start":"2026-05-18T00:41:39.28193Z","end":"2026-05-18T00:41:39.487321Z","steps":["trace[784267596] 'agreement among raft nodes before linearized reading' (duration: 205.309231ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:39.487335Z","caller":"traceutil/trace.go:171","msg":"trace[705050286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"204.98313ms","start":"2026-05-18T00:41:39.282335Z","end":"2026-05-18T00:41:39.487318Z","steps":["trace[705050286] 'agreement among raft nodes before linearized reading' (duration: 204.641378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:39.487215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.40857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-18T00:41:39.487492Z","caller":"traceutil/trace.go:171","msg":"trace[1792612221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"204.693231ms","start":"2026-05-18T00:41:39.282789Z","end":"2026-05-18T00:41:39.487482Z","steps":["trace[1792612221] 'agreement among raft nodes before linearized reading' (duration: 204.37506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:40.015505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.844131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:41:40.015783Z","caller":"traceutil/trace.go:171","msg":"trace[1010561118] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"522.838287ms","start":"2026-05-18T00:41:39.492919Z","end":"2026-05-18T00:41:40.015757Z","steps":["trace[1010561118] 'process raft request' (duration: 62.681605ms)","trace[1010561118] 'compare' (duration: 459.689696ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:40.015937Z","caller":"traceutil/trace.go:171","msg":"trace[1066827667] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"522.582898ms","start":"2026-05-18T00:41:39.493316Z","end":"2026-05-18T00:41:40.015899Z","steps":["trace[1066827667] 'process raft request' (duration: 522.428033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:40.01597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.40177ms","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-05-18T00:41:40.016027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:39.493305Z","time spent":"522.675181ms","remote":"127.0.0.1:52668","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-05-18T00:41:40.016042Z","caller":"traceutil/trace.go:171","msg":"trace[909469500] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1099; }","duration":"396.498164ms","start":"2026-05-18T00:41:39.619522Z","end":"2026-05-18T00:41:40.01602Z","steps":["trace[909469500] 'agreement among raft nodes before linearized reading' (duration: 396.311388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:40.016108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:39.619499Z","time spent":"396.601478ms","remote":"127.0.0.1:52436","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-05-18T00:41:40.016138Z","caller":"traceutil/trace.go:171","msg":"trace[1381403185] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"523.196349ms","start":"2026-05-18T00:41:39.492936Z","end":"2026-05-18T00:41:40.016132Z","steps":["trace[1381403185] 'process raft request' (duration: 522.774364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:40.016168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:39.492922Z","time spent":"523.23161ms","remote":"127.0.0.1:52668","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-05-18T00:41:40.016267Z","caller":"traceutil/trace.go:171","msg":"trace[706440522] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"522.525506ms","start":"2026-05-18T00:41:39.49369Z","end":"2026-05-18T00:41:40.016215Z","steps":["trace[706440522] 'process raft request' (duration: 522.087821ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:40.015817Z","caller":"traceutil/trace.go:171","msg":"trace[1412312863] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1137; }","duration":"396.223325ms","start":"2026-05-18T00:41:39.619582Z","end":"2026-05-18T00:41:40.015806Z","steps":["trace[1412312863] 'read index received' (duration: 64.163µs)","trace[1412312863] 'applied index is now lower than readState.Index' (duration: 396.158562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:41:40.016405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:39.493668Z","time spent":"522.675251ms","remote":"127.0.0.1:52668","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-05-18T00:41:40.016525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.619288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:40.016576Z","caller":"traceutil/trace.go:171","msg":"trace[1184962144] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1099; }","duration":"298.703161ms","start":"2026-05-18T00:41:39.717866Z","end":"2026-05-18T00:41:40.016569Z","steps":["trace[1184962144] 'agreement among raft nodes before linearized reading' (duration: 298.635209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:40.016664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:39.492894Z","time spent":"522.95141ms","remote":"127.0.0.1:52668","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-05-18T00:41:41.606913Z","caller":"traceutil/trace.go:171","msg":"trace[1998797660] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"113.340621ms","start":"2026-05-18T00:41:41.493548Z","end":"2026-05-18T00:41:41.606889Z","steps":["trace[1998797660] 'process raft request' (duration: 113.150345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:49.841596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.69636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:41:49.841734Z","caller":"traceutil/trace.go:171","msg":"trace[975969824] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"163.908776ms","start":"2026-05-18T00:41:49.677802Z","end":"2026-05-18T00:41:49.841711Z","steps":["trace[975969824] 'read index received' (duration: 52.876786ms)","trace[975969824] 'applied index is now lower than readState.Index' (duration: 111.03037ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:49.841846Z","caller":"traceutil/trace.go:171","msg":"trace[1004748681] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"192.772272ms","start":"2026-05-18T00:41:49.649047Z","end":"2026-05-18T00:41:49.841819Z","steps":["trace[1004748681] 'process raft request' (duration: 81.70502ms)","trace[1004748681] 'compare' (duration: 110.37312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:41:49.841886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.087882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-18T00:41:49.842028Z","caller":"traceutil/trace.go:171","msg":"trace[270622375] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1128; }","duration":"164.260558ms","start":"2026-05-18T00:41:49.677751Z","end":"2026-05-18T00:41:49.842012Z","steps":["trace[270622375] 'agreement among raft nodes before linearized reading' (duration: 164.073372ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:52.236774Z","caller":"traceutil/trace.go:171","msg":"trace[315301924] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"103.45551ms","start":"2026-05-18T00:41:52.133302Z","end":"2026-05-18T00:41:52.236757Z","steps":["trace[315301924] 'process raft request' (duration: 100.087803ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:52.236885Z","caller":"traceutil/trace.go:171","msg":"trace[2038913589] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"103.199741ms","start":"2026-05-18T00:41:52.13366Z","end":"2026-05-18T00:41:52.236859Z","steps":["trace[2038913589] 'process raft request' (duration: 102.85512ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:56.060445Z","caller":"traceutil/trace.go:171","msg":"trace[1704028868] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"185.002685ms","start":"2026-05-18T00:41:55.875418Z","end":"2026-05-18T00:41:56.06042Z","steps":["trace[1704028868] 'read index received' (duration: 184.665445ms)","trace[1704028868] 'applied index is now lower than readState.Index' (duration: 336.3µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:56.060637Z","caller":"traceutil/trace.go:171","msg":"trace[974512549] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"196.48255ms","start":"2026-05-18T00:41:55.864104Z","end":"2026-05-18T00:41:56.060587Z","steps":["trace[974512549] 'process raft request' (duration: 196.051996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:56.060813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.360566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:41:56.061439Z","caller":"traceutil/trace.go:171","msg":"trace[844740057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"186.055008ms","start":"2026-05-18T00:41:55.875369Z","end":"2026-05-18T00:41:56.061424Z","steps":["trace[844740057] 'agreement among raft nodes before linearized reading' (duration: 185.17829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:56.76868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:41:57.27033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:41:57.771191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:41:58.27214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:41:58.772268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:41:58.873342Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.611367748s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T00:41:58.873626Z","caller":"traceutil/trace.go:171","msg":"trace[629571084] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"2.61174374s","start":"2026-05-18T00:41:56.261866Z","end":"2026-05-18T00:41:58.873609Z","steps":["trace[629571084] 'process raft request' (duration: 2.611632067s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:58.873732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:56.261849Z","time spent":"2.611830203s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:41:58.970395Z","caller":"traceutil/trace.go:171","msg":"trace[993775624] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1247; }","duration":"2.702326891s","start":"2026-05-18T00:41:56.268046Z","end":"2026-05-18T00:41:58.970373Z","steps":["trace[993775624] 'read index received' (duration: 2.60542405s)","trace[993775624] 'applied index is now lower than readState.Index' (duration: 96.901791ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:58.970463Z","caller":"traceutil/trace.go:171","msg":"trace[667903433] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"905.225888ms","start":"2026-05-18T00:41:58.065211Z","end":"2026-05-18T00:41:58.970437Z","steps":["trace[667903433] 'process raft request' (duration: 905.117215ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:58.970457Z","caller":"traceutil/trace.go:171","msg":"trace[1184417699] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"1.967207057s","start":"2026-05-18T00:41:57.003214Z","end":"2026-05-18T00:41:58.970421Z","steps":["trace[1184417699] 'process raft request' (duration: 1.967064202s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:58.970597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.702540638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-18T00:41:58.970589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:58.065196Z","time spent":"905.324381ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:41:58.97065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:57.003191Z","time spent":"1.967361212s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:41:58.970778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945022074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:41:58.970789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.701184605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-18T00:41:58.970792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.260054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:41:58.970809Z","caller":"traceutil/trace.go:171","msg":"trace[742648204] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"2.707994832s","start":"2026-05-18T00:41:56.262794Z","end":"2026-05-18T00:41:58.970788Z","steps":["trace[742648204] 'process raft request' (duration: 2.693403099s)","trace[742648204] 'compare' (duration: 13.773797ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:58.970823Z","caller":"traceutil/trace.go:171","msg":"trace[1426686590] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1203; }","duration":"1.945109816s","start":"2026-05-18T00:41:57.025703Z","end":"2026-05-18T00:41:58.970812Z","steps":["trace[1426686590] 'agreement among raft nodes before linearized reading' (duration: 1.945032534s)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:58.970829Z","caller":"traceutil/trace.go:171","msg":"trace[976866980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1203; }","duration":"2.701237927s","start":"2026-05-18T00:41:56.26958Z","end":"2026-05-18T00:41:58.970818Z","steps":["trace[976866980] 'agreement among raft nodes before linearized reading' (duration: 2.701106043s)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:58.970832Z","caller":"traceutil/trace.go:171","msg":"trace[788259610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"902.315887ms","start":"2026-05-18T00:41:58.068506Z","end":"2026-05-18T00:41:58.970822Z","steps":["trace[788259610] 'agreement among raft nodes before linearized reading' (duration: 902.212403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:58.970857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:56.269573Z","time spent":"2.701279059s","remote":"127.0.0.1:52668","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-05-18T00:41:58.970858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:57.025681Z","time spent":"1.945167058s","remote":"127.0.0.1:37736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:41:58.970862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:58.068499Z","time spent":"902.356448ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:41:58.970866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.666617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:41:58.970884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:56.262785Z","time spent":"2.708057053s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:41:58.970878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.034689616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:41:58.970926Z","caller":"traceutil/trace.go:171","msg":"trace[1484629385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"714.77555ms","start":"2026-05-18T00:41:58.256135Z","end":"2026-05-18T00:41:58.97091Z","steps":["trace[1484629385] 'agreement among raft nodes before linearized reading' (duration: 714.626876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:58.970957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:58.256117Z","time spent":"714.835893ms","remote":"127.0.0.1:52382","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-05-18T00:41:58.970958Z","caller":"traceutil/trace.go:171","msg":"trace[1204120327] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1203; }","duration":"2.03481645s","start":"2026-05-18T00:41:56.936121Z","end":"2026-05-18T00:41:58.970938Z","steps":["trace[1204120327] 'agreement among raft nodes before linearized reading' (duration: 2.034660665s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:58.971002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:56.936108Z","time spent":"2.034883793s","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-18T00:41:58.970639Z","caller":"traceutil/trace.go:171","msg":"trace[1101561816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"2.70260793s","start":"2026-05-18T00:41:56.26802Z","end":"2026-05-18T00:41:58.970628Z","steps":["trace[1101561816] 'agreement among raft nodes before linearized reading' (duration: 2.702474696s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:58.971406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:56.268012Z","time spent":"2.703379865s","remote":"127.0.0.1:52668","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-05-18T00:42:03.186175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.445107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:03.186296Z","caller":"traceutil/trace.go:171","msg":"trace[975623939] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"199.97742ms","start":"2026-05-18T00:42:02.986303Z","end":"2026-05-18T00:42:03.18628Z","steps":["trace[975623939] 'read index received' (duration: 51.281226ms)","trace[975623939] 'applied index is now lower than readState.Index' (duration: 148.694914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:03.186603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.30326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:42:03.186632Z","caller":"traceutil/trace.go:171","msg":"trace[775925581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"200.355472ms","start":"2026-05-18T00:42:02.986269Z","end":"2026-05-18T00:42:03.186625Z","steps":["trace[775925581] 'agreement among raft nodes before linearized reading' (duration: 200.245699ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:03.186759Z","caller":"traceutil/trace.go:171","msg":"trace[2081516890] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"201.411835ms","start":"2026-05-18T00:42:02.985338Z","end":"2026-05-18T00:42:03.18675Z","steps":["trace[2081516890] 'process raft request' (duration: 52.298937ms)","trace[2081516890] 'compare' (duration: 148.320853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:03.186713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.888621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:42:03.186839Z","caller":"traceutil/trace.go:171","msg":"trace[1865702866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"196.065266ms","start":"2026-05-18T00:42:02.990751Z","end":"2026-05-18T00:42:03.186816Z","steps":["trace[1865702866] 'agreement among raft nodes before linearized reading' (duration: 195.740056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:03.187055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.601047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:42:03.187082Z","caller":"traceutil/trace.go:171","msg":"trace[1918793812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"192.643287ms","start":"2026-05-18T00:42:02.99443Z","end":"2026-05-18T00:42:03.187073Z","steps":["trace[1918793812] 'agreement among raft nodes before linearized reading' (duration: 192.554645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:03.187183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.429359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:42:03.187273Z","caller":"traceutil/trace.go:171","msg":"trace[2094758793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"191.555013ms","start":"2026-05-18T00:42:02.9957Z","end":"2026-05-18T00:42:03.187255Z","steps":["trace[2094758793] 'agreement among raft nodes before linearized reading' (duration: 191.238943ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:03.349991Z","caller":"traceutil/trace.go:171","msg":"trace[967406048] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"158.215535ms","start":"2026-05-18T00:42:03.19175Z","end":"2026-05-18T00:42:03.349965Z","steps":["trace[967406048] 'process raft request' (duration: 97.254113ms)","trace[967406048] 'compare' (duration: 60.800997ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:03.350321Z","caller":"traceutil/trace.go:171","msg":"trace[528796000] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"156.12446ms","start":"2026-05-18T00:42:03.194131Z","end":"2026-05-18T00:42:03.350256Z","steps":["trace[528796000] 'process raft request' (duration: 155.795459ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:03.350412Z","caller":"traceutil/trace.go:171","msg":"trace[2080961077] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"156.040967ms","start":"2026-05-18T00:42:03.194345Z","end":"2026-05-18T00:42:03.350386Z","steps":["trace[2080961077] 'process raft request' (duration: 155.787829ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:03.350461Z","caller":"traceutil/trace.go:171","msg":"trace[1487532487] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"155.971744ms","start":"2026-05-18T00:42:03.194465Z","end":"2026-05-18T00:42:03.350437Z","steps":["trace[1487532487] 'process raft request' (duration: 155.778688ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:09.21103Z","caller":"traceutil/trace.go:171","msg":"trace[1694562724] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"111.156434ms","start":"2026-05-18T00:42:09.099848Z","end":"2026-05-18T00:42:09.211004Z","steps":["trace[1694562724] 'process raft request' (duration: 99.687331ms)","trace[1694562724] 'compare' (duration: 11.259997ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:09.308254Z","caller":"traceutil/trace.go:171","msg":"trace[700901852] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"104.490153ms","start":"2026-05-18T00:42:09.203746Z","end":"2026-05-18T00:42:09.308236Z","steps":["trace[700901852] 'process raft request' (duration: 104.38672ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:11.448449Z","caller":"traceutil/trace.go:171","msg":"trace[552628285] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"135.526427ms","start":"2026-05-18T00:42:11.3129Z","end":"2026-05-18T00:42:11.448426Z","steps":["trace[552628285] 'process raft request' (duration: 135.353351ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:13.06334Z","caller":"traceutil/trace.go:171","msg":"trace[449854329] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"208.912473ms","start":"2026-05-18T00:42:12.854329Z","end":"2026-05-18T00:42:13.063241Z","steps":["trace[449854329] 'process raft request' (duration: 154.830349ms)","trace[449854329] 'compare' (duration: 53.811675ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:13.26093Z","caller":"traceutil/trace.go:171","msg":"trace[1709306719] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"140.303808ms","start":"2026-05-18T00:42:13.120607Z","end":"2026-05-18T00:42:13.26091Z","steps":["trace[1709306719] 'process raft request' (duration: 140.176544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:13.975858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T00:42:14.013966Z","caller":"traceutil/trace.go:171","msg":"trace[983480172] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"538.751051ms","start":"2026-05-18T00:42:13.475195Z","end":"2026-05-18T00:42:14.013946Z","steps":["trace[983480172] 'read index received' (duration: 538.620647ms)","trace[983480172] 'applied index is now lower than readState.Index' (duration: 129.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:14.014063Z","caller":"traceutil/trace.go:171","msg":"trace[245920165] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"560.175279ms","start":"2026-05-18T00:42:13.45388Z","end":"2026-05-18T00:42:14.014055Z","steps":["trace[245920165] 'process raft request' (duration: 559.946842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.014176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:13.453861Z","time spent":"560.22203ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:42:14.014343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.175975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:42:14.014411Z","caller":"traceutil/trace.go:171","msg":"trace[22606638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1263; }","duration":"522.252417ms","start":"2026-05-18T00:42:13.492143Z","end":"2026-05-18T00:42:14.014395Z","steps":["trace[22606638] 'agreement among raft nodes before linearized reading' (duration: 522.060651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.014455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:13.492088Z","time spent":"522.358981ms","remote":"127.0.0.1:52668","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-05-18T00:42:14.014563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.76539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:42:14.01459Z","caller":"traceutil/trace.go:171","msg":"trace[1845193060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"491.816452ms","start":"2026-05-18T00:42:13.522767Z","end":"2026-05-18T00:42:14.014584Z","steps":["trace[1845193060] 'agreement among raft nodes before linearized reading' (duration: 491.74199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.01459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.400811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-18T00:42:14.014609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:13.522728Z","time spent":"491.877674ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T00:42:14.014616Z","caller":"traceutil/trace.go:171","msg":"trace[1478481013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"539.464563ms","start":"2026-05-18T00:42:13.475144Z","end":"2026-05-18T00:42:14.014608Z","steps":["trace[1478481013] 'agreement among raft nodes before linearized reading' (duration: 539.37072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.014637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:13.475089Z","time spent":"539.542676ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T00:42:14.015242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.542887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:42:14.015709Z","caller":"traceutil/trace.go:171","msg":"trace[816551995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"522.870817ms","start":"2026-05-18T00:42:13.492627Z","end":"2026-05-18T00:42:14.015498Z","steps":["trace[816551995] 'agreement among raft nodes before linearized reading' (duration: 521.925287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.015995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:13.492609Z","time spent":"523.368662ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:42:14.712922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.432886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:14.713045Z","caller":"traceutil/trace.go:171","msg":"trace[478756477] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"694.759235ms","start":"2026-05-18T00:42:14.018265Z","end":"2026-05-18T00:42:14.713024Z","steps":["trace[478756477] 'process raft request' (duration: 558.130463ms)","trace[478756477] 'compare' (duration: 135.956541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:14.713113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:14.018239Z","time spent":"694.844948ms","remote":"127.0.0.1:52668","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-05-18T00:42:14.717128Z","caller":"traceutil/trace.go:171","msg":"trace[1629132682] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"693.144944ms","start":"2026-05-18T00:42:14.02397Z","end":"2026-05-18T00:42:14.717115Z","steps":["trace[1629132682] 'process raft request' (duration: 693.089052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.717245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:14.023953Z","time spent":"693.218917ms","remote":"127.0.0.1:52668","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-05-18T00:42:14.717349Z","caller":"traceutil/trace.go:171","msg":"trace[248394230] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"693.211057ms","start":"2026-05-18T00:42:14.02412Z","end":"2026-05-18T00:42:14.717331Z","steps":["trace[248394230] 'process raft request' (duration: 692.964879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.717463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:14.024111Z","time spent":"693.283569ms","remote":"127.0.0.1:52668","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-05-18T00:42:14.717228Z","caller":"traceutil/trace.go:171","msg":"trace[689541449] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"698.752302ms","start":"2026-05-18T00:42:14.018403Z","end":"2026-05-18T00:42:14.717156Z","steps":["trace[689541449] 'process raft request' (duration: 698.584687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:14.717661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:14.018189Z","time spent":"699.446894ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:42:16.11984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.157416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:16.11997Z","caller":"traceutil/trace.go:171","msg":"trace[1875512279] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"576.517237ms","start":"2026-05-18T00:42:15.543431Z","end":"2026-05-18T00:42:16.119948Z","steps":["trace[1875512279] 'read index received' (duration: 241.158995ms)","trace[1875512279] 'applied index is now lower than readState.Index' (duration: 335.355252ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:16.120201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.762246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-kz6kj\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-18T00:42:16.120246Z","caller":"traceutil/trace.go:171","msg":"trace[172673372] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-kz6kj; range_end:; response_count:1; response_revision:1270; }","duration":"576.843368ms","start":"2026-05-18T00:42:15.543389Z","end":"2026-05-18T00:42:16.120232Z","steps":["trace[172673372] 'agreement among raft nodes before linearized reading' (duration: 576.618021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:16.12029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:15.543374Z","time spent":"576.90363ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11083,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-kz6kj\" "} {"level":"info","ts":"2026-05-18T00:42:16.120479Z","caller":"traceutil/trace.go:171","msg":"trace[1918998431] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"591.394219ms","start":"2026-05-18T00:42:15.529067Z","end":"2026-05-18T00:42:16.120461Z","steps":["trace[1918998431] 'process raft request' (duration: 255.551141ms)","trace[1918998431] 'compare' (duration: 335.008561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:16.120563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:15.52904Z","time spent":"591.485742ms","remote":"127.0.0.1:52484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-18T00:42:16.933525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.500536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:16.9336Z","caller":"traceutil/trace.go:171","msg":"trace[1194117071] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"914.72345ms","start":"2026-05-18T00:42:16.018863Z","end":"2026-05-18T00:42:16.933586Z","steps":["trace[1194117071] 'process raft request' (duration: 485.10141ms)","trace[1194117071] 'compare' (duration: 429.203677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:16.933642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:16.018842Z","time spent":"914.778961ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:42:16.945789Z","caller":"traceutil/trace.go:171","msg":"trace[428466487] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1322; }","duration":"687.165815ms","start":"2026-05-18T00:42:16.258598Z","end":"2026-05-18T00:42:16.945764Z","steps":["trace[428466487] 'read index received' (duration: 245.356129ms)","trace[428466487] 'applied index is now lower than readState.Index' (duration: 441.808416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:16.945951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.337471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:42:16.945958Z","caller":"traceutil/trace.go:171","msg":"trace[676062684] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"812.113906ms","start":"2026-05-18T00:42:16.133771Z","end":"2026-05-18T00:42:16.945885Z","steps":["trace[676062684] 'process raft request' (duration: 811.819807ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:16.945992Z","caller":"traceutil/trace.go:171","msg":"trace[294554182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"687.432703ms","start":"2026-05-18T00:42:16.258549Z","end":"2026-05-18T00:42:16.945981Z","steps":["trace[294554182] 'agreement among raft nodes before linearized reading' (duration: 687.33238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:16.946034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:16.258532Z","time spent":"687.493616ms","remote":"127.0.0.1:52382","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-05-18T00:42:16.946077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:16.133747Z","time spent":"812.266151ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:42:17.017837Z","caller":"traceutil/trace.go:171","msg":"trace[967895337] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"288.062342ms","start":"2026-05-18T00:42:16.729747Z","end":"2026-05-18T00:42:17.01781Z","steps":["trace[967895337] 'process raft request' (duration: 287.893007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:17.018108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.747133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-18T00:42:17.018155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.817415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T00:42:17.018215Z","caller":"traceutil/trace.go:171","msg":"trace[2037020888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"287.886527ms","start":"2026-05-18T00:42:16.730318Z","end":"2026-05-18T00:42:17.018205Z","steps":["trace[2037020888] 'agreement among raft nodes before linearized reading' (duration: 287.753453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:17.018218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.795299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:42:17.018254Z","caller":"traceutil/trace.go:171","msg":"trace[1690378071] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1273; }","duration":"695.878311ms","start":"2026-05-18T00:42:16.322365Z","end":"2026-05-18T00:42:17.018243Z","steps":["trace[1690378071] 'agreement among raft nodes before linearized reading' (duration: 695.82694ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.01817Z","caller":"traceutil/trace.go:171","msg":"trace[1167511796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"287.851016ms","start":"2026-05-18T00:42:16.730305Z","end":"2026-05-18T00:42:17.018156Z","steps":["trace[1167511796] 'agreement among raft nodes before linearized reading' (duration: 287.664661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:17.018352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.148296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:42:17.018428Z","caller":"traceutil/trace.go:171","msg":"trace[1766453118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"288.254989ms","start":"2026-05-18T00:42:16.730157Z","end":"2026-05-18T00:42:17.018412Z","steps":["trace[1766453118] 'agreement among raft nodes before linearized reading' (duration: 288.029491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:17.018294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:16.322306Z","time spent":"695.978194ms","remote":"127.0.0.1:52646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-18T00:42:17.018442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.080044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:42:17.018564Z","caller":"traceutil/trace.go:171","msg":"trace[1411162695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"288.255299ms","start":"2026-05-18T00:42:16.730291Z","end":"2026-05-18T00:42:17.018546Z","steps":["trace[1411162695] 'agreement among raft nodes before linearized reading' (duration: 287.752203ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.150839Z","caller":"traceutil/trace.go:171","msg":"trace[987122928] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"193.683311ms","start":"2026-05-18T00:42:16.957145Z","end":"2026-05-18T00:42:17.150828Z","steps":["trace[987122928] 'process raft request' (duration: 193.436613ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.150941Z","caller":"traceutil/trace.go:171","msg":"trace[1825872399] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"196.915533ms","start":"2026-05-18T00:42:16.954007Z","end":"2026-05-18T00:42:17.150922Z","steps":["trace[1825872399] 'process raft request' (duration: 196.532871ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.151176Z","caller":"traceutil/trace.go:171","msg":"trace[341299999] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1325; }","duration":"132.853612ms","start":"2026-05-18T00:42:17.017942Z","end":"2026-05-18T00:42:17.150796Z","steps":["trace[341299999] 'read index received' (duration: 132.513571ms)","trace[341299999] 'applied index is now lower than readState.Index' (duration: 338.951µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:17.151225Z","caller":"traceutil/trace.go:171","msg":"trace[1726945500] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"198.220644ms","start":"2026-05-18T00:42:16.952389Z","end":"2026-05-18T00:42:17.150609Z","steps":["trace[1726945500] 'process raft request' (duration: 198.003437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:17.151258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.257896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:42:17.151291Z","caller":"traceutil/trace.go:171","msg":"trace[1575534658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"204.325518ms","start":"2026-05-18T00:42:16.946958Z","end":"2026-05-18T00:42:17.151284Z","steps":["trace[1575534658] 'agreement among raft nodes before linearized reading' (duration: 204.248486ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.172857Z","caller":"traceutil/trace.go:171","msg":"trace[232471409] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"147.056732ms","start":"2026-05-18T00:42:17.025753Z","end":"2026-05-18T00:42:17.17281Z","steps":["trace[232471409] 'process raft request' (duration: 146.9723ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.172855Z","caller":"traceutil/trace.go:171","msg":"trace[353498188] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"148.601141ms","start":"2026-05-18T00:42:17.024209Z","end":"2026-05-18T00:42:17.17281Z","steps":["trace[353498188] 'process raft request' (duration: 148.370624ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.172928Z","caller":"traceutil/trace.go:171","msg":"trace[1792120631] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"147.140905ms","start":"2026-05-18T00:42:17.025711Z","end":"2026-05-18T00:42:17.172852Z","steps":["trace[1792120631] 'process raft request' (duration: 146.98874ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:17.172974Z","caller":"traceutil/trace.go:171","msg":"trace[595539725] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"147.113164ms","start":"2026-05-18T00:42:17.025844Z","end":"2026-05-18T00:42:17.172958Z","steps":["trace[595539725] 'process raft request' (duration: 146.908648ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.277062Z","caller":"traceutil/trace.go:171","msg":"trace[1743716983] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"198.843454ms","start":"2026-05-18T00:42:19.078193Z","end":"2026-05-18T00:42:19.277036Z","steps":["trace[1743716983] 'read index received' (duration: 198.570315ms)","trace[1743716983] 'applied index is now lower than readState.Index' (duration: 272.049µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.277231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.02152ms","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-05-18T00:42:19.27729Z","caller":"traceutil/trace.go:171","msg":"trace[1000280318] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1282; }","duration":"199.120163ms","start":"2026-05-18T00:42:19.078144Z","end":"2026-05-18T00:42:19.277264Z","steps":["trace[1000280318] 'agreement among raft nodes before linearized reading' (duration: 199.011429ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.27721Z","caller":"traceutil/trace.go:171","msg":"trace[1869873517] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"334.896067ms","start":"2026-05-18T00:42:18.942269Z","end":"2026-05-18T00:42:19.277165Z","steps":["trace[1869873517] 'process raft request' (duration: 334.576467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:19.27754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:18.942249Z","time spent":"335.200097ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:42:19.861196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.13711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:42:19.861294Z","caller":"traceutil/trace.go:171","msg":"trace[374806191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"602.318736ms","start":"2026-05-18T00:42:19.258953Z","end":"2026-05-18T00:42:19.861272Z","steps":["trace[374806191] 'agreement among raft nodes before linearized reading' (duration: 18.432835ms)","trace[374806191] 'range keys from in-memory index tree' (duration: 583.747017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.86133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.258934Z","time spent":"602.386918ms","remote":"127.0.0.1:52398","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-05-18T00:42:19.861407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.023616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-18T00:42:19.861626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.808041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:42:19.861664Z","caller":"traceutil/trace.go:171","msg":"trace[1914670735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"684.875423ms","start":"2026-05-18T00:42:19.176778Z","end":"2026-05-18T00:42:19.861653Z","steps":["trace[1914670735] 'agreement among raft nodes before linearized reading' (duration: 100.569148ms)","trace[1914670735] 'range keys from in-memory index tree' (duration: 584.213112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.861316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.385648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-18T00:42:19.861696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.176761Z","time spent":"684.926794ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-18T00:42:19.861758Z","caller":"traceutil/trace.go:171","msg":"trace[1848571128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"684.853033ms","start":"2026-05-18T00:42:19.176851Z","end":"2026-05-18T00:42:19.861704Z","steps":["trace[1848571128] 'agreement among raft nodes before linearized reading' (duration: 100.521697ms)","trace[1848571128] 'range keys from in-memory index tree' (duration: 583.765527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.861759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.407468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-18T00:42:19.861823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.176825Z","time spent":"684.983876ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-18T00:42:19.861849Z","caller":"traceutil/trace.go:171","msg":"trace[585064847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"684.537763ms","start":"2026-05-18T00:42:19.177293Z","end":"2026-05-18T00:42:19.861831Z","steps":["trace[585064847] 'agreement among raft nodes before linearized reading' (duration: 100.073983ms)","trace[585064847] 'range keys from in-memory index tree' (duration: 584.221752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.861908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.177286Z","time spent":"684.595374ms","remote":"127.0.0.1:52668","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-05-18T00:42:19.861793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.47501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-18T00:42:19.862Z","caller":"traceutil/trace.go:171","msg":"trace[1918246147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"684.692238ms","start":"2026-05-18T00:42:19.177294Z","end":"2026-05-18T00:42:19.861986Z","steps":["trace[1918246147] 'agreement among raft nodes before linearized reading' (duration: 100.069413ms)","trace[1918246147] 'range keys from in-memory index tree' (duration: 584.264584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.862124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.177288Z","time spent":"684.823542ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:42:19.862175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.265081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:42:19.862262Z","caller":"traceutil/trace.go:171","msg":"trace[1916479700] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"240.004859ms","start":"2026-05-18T00:42:19.622249Z","end":"2026-05-18T00:42:19.862253Z","steps":["trace[1916479700] 'read index received' (duration: 57.192µs)","trace[1916479700] 'applied index is now lower than readState.Index' (duration: 239.928747ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:19.8623Z","caller":"traceutil/trace.go:171","msg":"trace[406395324] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"473.309616ms","start":"2026-05-18T00:42:19.388963Z","end":"2026-05-18T00:42:19.862272Z","steps":["trace[406395324] 'process raft request' (duration: 89.87958ms)","trace[406395324] 'compare' (duration: 382.30806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.86243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.388935Z","time spent":"473.42742ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:42:19.862127Z","caller":"traceutil/trace.go:171","msg":"trace[340493394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"637.7686ms","start":"2026-05-18T00:42:19.22434Z","end":"2026-05-18T00:42:19.862109Z","steps":["trace[340493394] 'agreement among raft nodes before linearized reading' (duration: 53.055382ms)","trace[340493394] 'range keys from in-memory index tree' (duration: 583.951604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:42:19.862771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:19.224321Z","time spent":"638.43768ms","remote":"127.0.0.1:52668","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-05-18T00:42:19.863663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.34446ms","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-05-18T00:42:19.863729Z","caller":"traceutil/trace.go:171","msg":"trace[678139861] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1283; }","duration":"241.535227ms","start":"2026-05-18T00:42:19.622177Z","end":"2026-05-18T00:42:19.863713Z","steps":["trace[678139861] 'agreement among raft nodes before linearized reading' (duration: 240.100342ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.981766Z","caller":"traceutil/trace.go:171","msg":"trace[1145815692] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"114.343445ms","start":"2026-05-18T00:42:19.867402Z","end":"2026-05-18T00:42:19.981745Z","steps":["trace[1145815692] 'process raft request' (duration: 114.128878ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.981814Z","caller":"traceutil/trace.go:171","msg":"trace[1730789691] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"114.371436ms","start":"2026-05-18T00:42:19.867407Z","end":"2026-05-18T00:42:19.981779Z","steps":["trace[1730789691] 'process raft request' (duration: 114.271883ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.981892Z","caller":"traceutil/trace.go:171","msg":"trace[572539469] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"114.413488ms","start":"2026-05-18T00:42:19.867417Z","end":"2026-05-18T00:42:19.98183Z","steps":["trace[572539469] 'process raft request' (duration: 114.289974ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.982057Z","caller":"traceutil/trace.go:171","msg":"trace[705391719] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"114.411918ms","start":"2026-05-18T00:42:19.867635Z","end":"2026-05-18T00:42:19.982047Z","steps":["trace[705391719] 'process raft request' (duration: 114.098458ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:19.98204Z","caller":"traceutil/trace.go:171","msg":"trace[1343816908] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"113.179279ms","start":"2026-05-18T00:42:19.868613Z","end":"2026-05-18T00:42:19.981792Z","steps":["trace[1343816908] 'process raft request' (duration: 113.145028ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:29.076291Z","caller":"traceutil/trace.go:171","msg":"trace[1082294457] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"163.540035ms","start":"2026-05-18T00:42:28.912726Z","end":"2026-05-18T00:42:29.076266Z","steps":["trace[1082294457] 'read index received' (duration: 163.305567ms)","trace[1082294457] 'applied index is now lower than readState.Index' (duration: 233.298µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:29.076424Z","caller":"traceutil/trace.go:171","msg":"trace[1416196522] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"344.902005ms","start":"2026-05-18T00:42:28.731484Z","end":"2026-05-18T00:42:29.076386Z","steps":["trace[1416196522] 'process raft request' (duration: 344.637626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:29.076489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.746082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:42:29.076674Z","caller":"traceutil/trace.go:171","msg":"trace[645229499] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1359; }","duration":"163.917787ms","start":"2026-05-18T00:42:28.91267Z","end":"2026-05-18T00:42:29.076588Z","steps":["trace[645229499] 'agreement among raft nodes before linearized reading' (duration: 163.69302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:29.076679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:28.731458Z","time spent":"345.049179ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:42:30.118917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670044530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T00:42:30.307648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048242301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:42:30.307717Z","caller":"traceutil/trace.go:171","msg":"trace[207134121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1359; }","duration":"1.048354105s","start":"2026-05-18T00:42:29.259345Z","end":"2026-05-18T00:42:30.3077Z","steps":["trace[207134121] 'range keys from in-memory index tree' (duration: 1.048160799s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.307783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:29.259325Z","time spent":"1.048448408s","remote":"127.0.0.1:52382","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-05-18T00:42:30.452343Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.145562327s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T00:42:30.45276Z","caller":"traceutil/trace.go:171","msg":"trace[1914173762] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"834.113464ms","start":"2026-05-18T00:42:29.618621Z","end":"2026-05-18T00:42:30.452735Z","steps":["trace[1914173762] 'read index received' (duration: 833.864346ms)","trace[1914173762] 'applied index is now lower than readState.Index' (duration: 247.898µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:30.452812Z","caller":"traceutil/trace.go:171","msg":"trace[814386502] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.146099593s","start":"2026-05-18T00:42:29.306684Z","end":"2026-05-18T00:42:30.452783Z","steps":["trace[814386502] 'process raft request' (duration: 1.145810374s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.452925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:29.306662Z","time spent":"1.146198527s","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:42:30.452973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.348771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-18T00:42:30.453015Z","caller":"traceutil/trace.go:171","msg":"trace[567272622] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"834.434084ms","start":"2026-05-18T00:42:29.618568Z","end":"2026-05-18T00:42:30.453002Z","steps":["trace[567272622] 'agreement among raft nodes before linearized reading' (duration: 834.283739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.453049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:29.618551Z","time spent":"834.490065ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-18T00:42:30.45593Z","caller":"traceutil/trace.go:171","msg":"trace[1333688167] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"282.777435ms","start":"2026-05-18T00:42:30.173124Z","end":"2026-05-18T00:42:30.455901Z","steps":["trace[1333688167] 'process raft request' (duration: 282.527757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.455969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.320639ms","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-05-18T00:42:30.456016Z","caller":"traceutil/trace.go:171","msg":"trace[831564768] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1361; }","duration":"833.36405ms","start":"2026-05-18T00:42:29.622637Z","end":"2026-05-18T00:42:30.456001Z","steps":["trace[831564768] 'agreement among raft nodes before linearized reading' (duration: 833.201475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.456048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:29.622616Z","time spent":"833.424752ms","remote":"127.0.0.1:52436","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-05-18T00:42:30.456165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.26866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-18T00:42:30.45636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.576565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-18T00:42:30.456409Z","caller":"traceutil/trace.go:171","msg":"trace[1691056649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"421.666238ms","start":"2026-05-18T00:42:30.034731Z","end":"2026-05-18T00:42:30.456397Z","steps":["trace[1691056649] 'agreement among raft nodes before linearized reading' (duration: 421.503643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.45644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:30.034713Z","time spent":"421.71987ms","remote":"127.0.0.1:52668","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-05-18T00:42:30.456442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.127511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-18T00:42:30.456479Z","caller":"traceutil/trace.go:171","msg":"trace[1580503088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"415.199933ms","start":"2026-05-18T00:42:30.04127Z","end":"2026-05-18T00:42:30.45647Z","steps":["trace[1580503088] 'agreement among raft nodes before linearized reading' (duration: 415.121631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.45651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:30.04126Z","time spent":"415.244594ms","remote":"127.0.0.1:52668","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":"info","ts":"2026-05-18T00:42:30.456362Z","caller":"traceutil/trace.go:171","msg":"trace[1614652270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"400.688874ms","start":"2026-05-18T00:42:30.055657Z","end":"2026-05-18T00:42:30.456346Z","steps":["trace[1614652270] 'agreement among raft nodes before linearized reading' (duration: 400.190918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.456812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:30.055637Z","time spent":"400.929431ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-18T00:42:30.456269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.158541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-18T00:42:30.457104Z","caller":"traceutil/trace.go:171","msg":"trace[1769408092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"403.010448ms","start":"2026-05-18T00:42:30.054079Z","end":"2026-05-18T00:42:30.45709Z","steps":["trace[1769408092] 'agreement among raft nodes before linearized reading' (duration: 402.12972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.457176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:30.054069Z","time spent":"403.09502ms","remote":"127.0.0.1:52668","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-05-18T00:42:30.457476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.456518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-18T00:42:30.457566Z","caller":"traceutil/trace.go:171","msg":"trace[587559088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"354.617793ms","start":"2026-05-18T00:42:30.10293Z","end":"2026-05-18T00:42:30.457548Z","steps":["trace[587559088] 'agreement among raft nodes before linearized reading' (duration: 353.458807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:42:30.457612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:42:30.102909Z","time spent":"354.693765ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T00:42:30.457784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.666813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:42:30.457861Z","caller":"traceutil/trace.go:171","msg":"trace[1721877016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"148.764426ms","start":"2026-05-18T00:42:30.309082Z","end":"2026-05-18T00:42:30.457847Z","steps":["trace[1721877016] 'agreement among raft nodes before linearized reading' (duration: 148.661343ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:32.663166Z","caller":"traceutil/trace.go:171","msg":"trace[864448000] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"110.198694ms","start":"2026-05-18T00:42:32.552939Z","end":"2026-05-18T00:42:32.663138Z","steps":["trace[864448000] 'process raft request' (duration: 81.504354ms)","trace[864448000] 'compare' (duration: 28.551525ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:32.672968Z","caller":"traceutil/trace.go:171","msg":"trace[549022826] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"119.717015ms","start":"2026-05-18T00:42:32.553225Z","end":"2026-05-18T00:42:32.672942Z","steps":["trace[549022826] 'process raft request' (duration: 119.541879ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:32.673132Z","caller":"traceutil/trace.go:171","msg":"trace[2068351800] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"119.833199ms","start":"2026-05-18T00:42:32.553284Z","end":"2026-05-18T00:42:32.673118Z","steps":["trace[2068351800] 'process raft request' (duration: 119.616072ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:32.673408Z","caller":"traceutil/trace.go:171","msg":"trace[1370726141] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"119.947273ms","start":"2026-05-18T00:42:32.553443Z","end":"2026-05-18T00:42:32.67339Z","steps":["trace[1370726141] 'process raft request' (duration: 119.623983ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:32.673965Z","caller":"traceutil/trace.go:171","msg":"trace[679319793] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"120.494569ms","start":"2026-05-18T00:42:32.553449Z","end":"2026-05-18T00:42:32.673943Z","steps":["trace[679319793] 'process raft request' (duration: 119.923211ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:53.383597Z","caller":"traceutil/trace.go:171","msg":"trace[1788637719] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"107.105385ms","start":"2026-05-18T00:42:53.276471Z","end":"2026-05-18T00:42:53.383576Z","steps":["trace[1788637719] 'process raft request' (duration: 82.777404ms)","trace[1788637719] 'compare' (duration: 24.229828ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:42:57.147432Z","caller":"traceutil/trace.go:171","msg":"trace[227455561] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"186.917985ms","start":"2026-05-18T00:42:56.96049Z","end":"2026-05-18T00:42:57.147408Z","steps":["trace[227455561] 'process raft request' (duration: 181.803553ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:57.147622Z","caller":"traceutil/trace.go:171","msg":"trace[1071975185] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"187.081111ms","start":"2026-05-18T00:42:56.960521Z","end":"2026-05-18T00:42:57.147602Z","steps":["trace[1071975185] 'process raft request' (duration: 186.851523ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:57.147663Z","caller":"traceutil/trace.go:171","msg":"trace[1873341118] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"185.899813ms","start":"2026-05-18T00:42:56.961753Z","end":"2026-05-18T00:42:57.147652Z","steps":["trace[1873341118] 'process raft request' (duration: 185.837221ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:42:57.14764Z","caller":"traceutil/trace.go:171","msg":"trace[2068638020] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"186.505482ms","start":"2026-05-18T00:42:56.961115Z","end":"2026-05-18T00:42:57.14762Z","steps":["trace[2068638020] 'process raft request' (duration: 186.388939ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:10.318406Z","caller":"traceutil/trace.go:171","msg":"trace[783840714] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"114.181134ms","start":"2026-05-18T00:45:10.204176Z","end":"2026-05-18T00:45:10.318357Z","steps":["trace[783840714] 'process raft request' (duration: 61.983178ms)","trace[783840714] 'compare' (duration: 51.883556ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:10.536535Z","caller":"traceutil/trace.go:171","msg":"trace[948561891] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2183; }","duration":"279.511241ms","start":"2026-05-18T00:45:10.256997Z","end":"2026-05-18T00:45:10.536508Z","steps":["trace[948561891] 'read index received' (duration: 9.302025ms)","trace[948561891] 'applied index is now lower than readState.Index' (duration: 270.208136ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:10.536574Z","caller":"traceutil/trace.go:171","msg":"trace[987367902] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"331.869564ms","start":"2026-05-18T00:45:10.20468Z","end":"2026-05-18T00:45:10.53655Z","steps":["trace[987367902] 'process raft request' (duration: 324.926553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:10.536691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:10.204643Z","time spent":"331.985757ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:45:10.536714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.691648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:45:10.536768Z","caller":"traceutil/trace.go:171","msg":"trace[1140833895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2098; }","duration":"279.7883ms","start":"2026-05-18T00:45:10.256965Z","end":"2026-05-18T00:45:10.536753Z","steps":["trace[1140833895] 'agreement among raft nodes before linearized reading' (duration: 279.683897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:10.536765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.9351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:45:10.536818Z","caller":"traceutil/trace.go:171","msg":"trace[1912940293] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2098; }","duration":"202.023933ms","start":"2026-05-18T00:45:10.334782Z","end":"2026-05-18T00:45:10.536806Z","steps":["trace[1912940293] 'agreement among raft nodes before linearized reading' (duration: 201.892949ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:11.038371Z","caller":"traceutil/trace.go:171","msg":"trace[334381001] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"124.931126ms","start":"2026-05-18T00:45:10.913414Z","end":"2026-05-18T00:45:11.038345Z","steps":["trace[334381001] 'process raft request' (duration: 124.766961ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:16.204157Z","caller":"traceutil/trace.go:171","msg":"trace[1817619916] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"106.083688ms","start":"2026-05-18T00:45:16.098052Z","end":"2026-05-18T00:45:16.204136Z","steps":["trace[1817619916] 'read index received' (duration: 78.492732ms)","trace[1817619916] 'applied index is now lower than readState.Index' (duration: 27.589786ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:16.204288Z","caller":"traceutil/trace.go:171","msg":"trace[853041651] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"159.276266ms","start":"2026-05-18T00:45:16.044983Z","end":"2026-05-18T00:45:16.20426Z","steps":["trace[853041651] 'process raft request' (duration: 131.564726ms)","trace[853041651] 'compare' (duration: 27.491063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:45:16.204262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.194821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:45:16.204405Z","caller":"traceutil/trace.go:171","msg":"trace[1748131012] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2121; }","duration":"106.390447ms","start":"2026-05-18T00:45:16.098003Z","end":"2026-05-18T00:45:16.204393Z","steps":["trace[1748131012] 'agreement among raft nodes before linearized reading' (duration: 106.16693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:16.204471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.641738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-18T00:45:16.204523Z","caller":"traceutil/trace.go:171","msg":"trace[658594182] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2121; }","duration":"102.740882ms","start":"2026-05-18T00:45:16.10177Z","end":"2026-05-18T00:45:16.204511Z","steps":["trace[658594182] 'agreement among raft nodes before linearized reading' (duration: 102.568206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:33.946564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102426670046654,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-18T00:45:33.987255Z","caller":"traceutil/trace.go:171","msg":"trace[532855509] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2315; }","duration":"541.327872ms","start":"2026-05-18T00:45:33.445902Z","end":"2026-05-18T00:45:33.98723Z","steps":["trace[532855509] 'read index received' (duration: 541.163187ms)","trace[532855509] 'applied index is now lower than readState.Index' (duration: 160.195µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:33.987304Z","caller":"traceutil/trace.go:171","msg":"trace[1961603277] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"587.098115ms","start":"2026-05-18T00:45:33.400157Z","end":"2026-05-18T00:45:33.987255Z","steps":["trace[1961603277] 'process raft request' (duration: 586.895748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:33.987515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.875127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-18T00:45:33.987581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.75491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-18T00:45:33.987576Z","caller":"traceutil/trace.go:171","msg":"trace[12908833] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2224; }","duration":"114.99457ms","start":"2026-05-18T00:45:33.872568Z","end":"2026-05-18T00:45:33.987562Z","steps":["trace[12908833] 'agreement among raft nodes before linearized reading' (duration: 114.863926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:33.987555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.629411ms","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-05-18T00:45:33.98761Z","caller":"traceutil/trace.go:171","msg":"trace[1041381129] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2224; }","duration":"302.813042ms","start":"2026-05-18T00:45:33.684786Z","end":"2026-05-18T00:45:33.987599Z","steps":["trace[1041381129] 'agreement among raft nodes before linearized reading' (duration: 302.690978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:33.98761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.550054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-18T00:45:33.987634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:33.684767Z","time spent":"302.859933ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-18T00:45:33.987638Z","caller":"traceutil/trace.go:171","msg":"trace[998909501] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2224; }","duration":"541.769906ms","start":"2026-05-18T00:45:33.445849Z","end":"2026-05-18T00:45:33.987619Z","steps":["trace[998909501] 'agreement among raft nodes before linearized reading' (duration: 541.505938ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:33.987669Z","caller":"traceutil/trace.go:171","msg":"trace[1369308425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2224; }","duration":"222.676478ms","start":"2026-05-18T00:45:33.764976Z","end":"2026-05-18T00:45:33.987653Z","steps":["trace[1369308425] 'agreement among raft nodes before linearized reading' (duration: 222.510773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:33.987681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:33.445822Z","time spent":"541.851549ms","remote":"127.0.0.1:52594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2745,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-05-18T00:45:33.987809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:33.400122Z","time spent":"587.388134ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:45:38.347517Z","caller":"traceutil/trace.go:171","msg":"trace[572514002] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2339; }","duration":"231.506937ms","start":"2026-05-18T00:45:38.115983Z","end":"2026-05-18T00:45:38.34749Z","steps":["trace[572514002] 'read index received' (duration: 231.336041ms)","trace[572514002] 'applied index is now lower than readState.Index' (duration: 169.896µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:38.347652Z","caller":"traceutil/trace.go:171","msg":"trace[1007799330] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"337.886034ms","start":"2026-05-18T00:45:38.009728Z","end":"2026-05-18T00:45:38.347614Z","steps":["trace[1007799330] 'process raft request' (duration: 337.584084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:38.347895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.259211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-18T00:45:38.347972Z","caller":"traceutil/trace.go:171","msg":"trace[383344230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2247; }","duration":"221.368905ms","start":"2026-05-18T00:45:38.126584Z","end":"2026-05-18T00:45:38.347953Z","steps":["trace[383344230] 'agreement among raft nodes before linearized reading' (duration: 221.064746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:38.348129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:38.009705Z","time spent":"338.245165ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:45:38.34854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.534269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-18T00:45:38.348599Z","caller":"traceutil/trace.go:171","msg":"trace[1622085337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2247; }","duration":"232.648453ms","start":"2026-05-18T00:45:38.115939Z","end":"2026-05-18T00:45:38.348587Z","steps":["trace[1622085337] 'agreement among raft nodes before linearized reading' (duration: 231.683643ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:38.468483Z","caller":"traceutil/trace.go:171","msg":"trace[222742225] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"115.133814ms","start":"2026-05-18T00:45:38.353319Z","end":"2026-05-18T00:45:38.468453Z","steps":["trace[222742225] 'process raft request' (duration: 114.816194ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:38.468949Z","caller":"traceutil/trace.go:171","msg":"trace[570762422] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"114.890226ms","start":"2026-05-18T00:45:38.354006Z","end":"2026-05-18T00:45:38.468896Z","steps":["trace[570762422] 'process raft request' (duration: 114.367899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.64019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.196825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:45:40.640286Z","caller":"traceutil/trace.go:171","msg":"trace[1613976208] linearizableReadLoop","detail":"{readStateIndex:2354; appliedIndex:2353; }","duration":"167.795726ms","start":"2026-05-18T00:45:40.472475Z","end":"2026-05-18T00:45:40.640271Z","steps":["trace[1613976208] 'read index received' (duration: 59.456447ms)","trace[1613976208] 'applied index is now lower than readState.Index' (duration: 108.337948ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:40.640359Z","caller":"traceutil/trace.go:171","msg":"trace[212020083] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"228.348358ms","start":"2026-05-18T00:45:40.412Z","end":"2026-05-18T00:45:40.640348Z","steps":["trace[212020083] 'process raft request' (duration: 119.923107ms)","trace[212020083] 'compare' (duration: 108.095321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:45:40.64056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.22267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:45:40.640594Z","caller":"traceutil/trace.go:171","msg":"trace[1153076390] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2260; }","duration":"163.293913ms","start":"2026-05-18T00:45:40.477291Z","end":"2026-05-18T00:45:40.640585Z","steps":["trace[1153076390] 'agreement among raft nodes before linearized reading' (duration: 163.239372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.640645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.385062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-18T00:45:40.640677Z","caller":"traceutil/trace.go:171","msg":"trace[34847800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2260; }","duration":"132.437213ms","start":"2026-05-18T00:45:40.508232Z","end":"2026-05-18T00:45:40.640669Z","steps":["trace[34847800] 'agreement among raft nodes before linearized reading' (duration: 132.362951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.640765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.356521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-18T00:45:40.640566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.097026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-18T00:45:40.640796Z","caller":"traceutil/trace.go:171","msg":"trace[738824972] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2260; }","duration":"149.477375ms","start":"2026-05-18T00:45:40.491312Z","end":"2026-05-18T00:45:40.640789Z","steps":["trace[738824972] 'agreement among raft nodes before linearized reading' (duration: 149.397022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.640928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.401346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-18T00:45:40.641021Z","caller":"traceutil/trace.go:171","msg":"trace[1035533554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2260; }","duration":"128.520729ms","start":"2026-05-18T00:45:40.512485Z","end":"2026-05-18T00:45:40.641006Z","steps":["trace[1035533554] 'agreement among raft nodes before linearized reading' (duration: 128.278301ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:40.64115Z","caller":"traceutil/trace.go:171","msg":"trace[54676259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2260; }","duration":"168.355214ms","start":"2026-05-18T00:45:40.47246Z","end":"2026-05-18T00:45:40.640815Z","steps":["trace[54676259] 'agreement among raft nodes before linearized reading' (duration: 168.003643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.922585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.122317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:45:40.92266Z","caller":"traceutil/trace.go:171","msg":"trace[14099651] linearizableReadLoop","detail":"{readStateIndex:2355; appliedIndex:2354; }","duration":"278.073986ms","start":"2026-05-18T00:45:40.644573Z","end":"2026-05-18T00:45:40.922647Z","steps":["trace[14099651] 'read index received' (duration: 103.807265ms)","trace[14099651] 'applied index is now lower than readState.Index' (duration: 174.265351ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:40.92268Z","caller":"traceutil/trace.go:171","msg":"trace[1329735895] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"278.49609ms","start":"2026-05-18T00:45:40.64416Z","end":"2026-05-18T00:45:40.922656Z","steps":["trace[1329735895] 'process raft request' (duration: 104.237148ms)","trace[1329735895] 'compare' (duration: 174.035494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:45:40.922744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.167549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:45:40.922767Z","caller":"traceutil/trace.go:171","msg":"trace[1949860784] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2261; }","duration":"278.228251ms","start":"2026-05-18T00:45:40.644532Z","end":"2026-05-18T00:45:40.922761Z","steps":["trace[1949860784] 'agreement among raft nodes before linearized reading' (duration: 278.176329ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:40.990239Z","caller":"traceutil/trace.go:171","msg":"trace[1371069174] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"345.161855ms","start":"2026-05-18T00:45:40.645046Z","end":"2026-05-18T00:45:40.990208Z","steps":["trace[1371069174] 'process raft request' (duration: 345.065472ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:40.990423Z","caller":"traceutil/trace.go:171","msg":"trace[1423624219] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"345.262439ms","start":"2026-05-18T00:45:40.645149Z","end":"2026-05-18T00:45:40.990412Z","steps":["trace[1423624219] 'process raft request' (duration: 345.013331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.990501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.150544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-18T00:45:40.990248Z","caller":"traceutil/trace.go:171","msg":"trace[938470696] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"345.219947ms","start":"2026-05-18T00:45:40.644995Z","end":"2026-05-18T00:45:40.990215Z","steps":["trace[938470696] 'process raft request' (duration: 344.979649ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:45:40.99056Z","caller":"traceutil/trace.go:171","msg":"trace[238257368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2264; }","duration":"264.250197ms","start":"2026-05-18T00:45:40.726296Z","end":"2026-05-18T00:45:40.990546Z","steps":["trace[238257368] 'agreement among raft nodes before linearized reading' (duration: 264.054241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:40.990574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:40.64513Z","time spent":"345.345181ms","remote":"127.0.0.1:52668","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-05-18T00:45:40.990636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:40.644988Z","time spent":"345.590189ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:45:40.99083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:40.645024Z","time spent":"345.311321ms","remote":"127.0.0.1:52668","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-05-18T00:45:41.770591Z","caller":"traceutil/trace.go:171","msg":"trace[1047420215] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"440.46843ms","start":"2026-05-18T00:45:41.330101Z","end":"2026-05-18T00:45:41.770569Z","steps":["trace[1047420215] 'process raft request' (duration: 429.142931ms)","trace[1047420215] 'compare' (duration: 11.072581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:45:41.770723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:41.330083Z","time spent":"440.562803ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}