{"level":"warn","ts":"2026-04-09T05:56:40.547046Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:56:40.547133Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-04-09T05:56:40.547201Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:56:40.547214Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-09T05:56:40.547241Z","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-04-09T05:56:40.547932Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-09T05:56:40.548032Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-04-09T05:56:40.55651Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.264542ms"} {"level":"info","ts":"2026-04-09T05:56:40.566363Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-09T05:56:40.566439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:56:40.566469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-09T05:56:40.566483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:56:40.566501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-09T05:56:40.566541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-09T05:56:40.57319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:56:40.575601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:56:40.577134Z","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-04-09T05:56:40.579596Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:56:40.580004Z","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-04-09T05:56:40.580068Z","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-04-09T05:56:40.580078Z","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-04-09T05:56:40.580072Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:56:40.586046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-09T05:56:40.586177Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-09T05:56:40.58775Z","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-04-09T05:56:40.587854Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-09T05:56:40.587884Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-09T05:56:40.588013Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:56:40.588041Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:56:41.167615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:56:41.167691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:56:41.167723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-09T05:56:41.167747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:56:41.167762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-09T05:56:41.167781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-09T05:56:41.167798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-09T05:56:41.168768Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:56:41.168928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:56:41.169022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:56:41.169392Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:56:41.172305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-09T05:56:41.172433Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:56:41.172481Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:56:41.172928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:56:41.173086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:56:41.173285Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:56:41.173322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-09T05:56:44.348503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.392649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-09T05:56:44.348704Z","caller":"traceutil/trace.go:171","msg":"trace[213491429] linearizableReadLoop","detail":"{readStateIndex:102; appliedIndex:101; }","duration":"127.825391ms","start":"2026-04-09T05:56:44.22086Z","end":"2026-04-09T05:56:44.348685Z","steps":["trace[213491429] 'read index received' (duration: 58.261µs)","trace[213491429] 'applied index is now lower than readState.Index' (duration: 127.76593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:56:44.348831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.968196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T05:56:44.34889Z","caller":"traceutil/trace.go:171","msg":"trace[1298600856] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:98; }","duration":"128.072258ms","start":"2026-04-09T05:56:44.220807Z","end":"2026-04-09T05:56:44.348879Z","steps":["trace[1298600856] 'agreement among raft nodes before linearized reading' (duration: 127.960875ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:44.34884Z","caller":"traceutil/trace.go:171","msg":"trace[481539115] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"661.427792ms","start":"2026-04-09T05:56:43.687383Z","end":"2026-04-09T05:56:44.348811Z","steps":["trace[481539115] 'process raft request' (duration: 99.235103ms)","trace[481539115] 'compare' (duration: 561.226625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:56:44.349122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:43.687369Z","time spent":"661.669207ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:56:44.783996Z","caller":"traceutil/trace.go:171","msg":"trace[1227129129] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"430.057311ms","start":"2026-04-09T05:56:44.353909Z","end":"2026-04-09T05:56:44.783966Z","steps":["trace[1227129129] 'process raft request' (duration: 380.85718ms)","trace[1227129129] 'compare' (duration: 49.085438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:56:44.784223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:44.353896Z","time spent":"430.174184ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:56:45.499677Z","caller":"traceutil/trace.go:171","msg":"trace[214703356] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:102; }","duration":"872.580677ms","start":"2026-04-09T05:56:44.627078Z","end":"2026-04-09T05:56:45.499658Z","steps":["trace[214703356] 'read index received' (duration: 107.666309ms)","trace[214703356] 'applied index is now lower than readState.Index' (duration: 764.913748ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:56:45.499785Z","caller":"traceutil/trace.go:171","msg":"trace[576805473] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"1.14499707s","start":"2026-04-09T05:56:44.354717Z","end":"2026-04-09T05:56:45.499714Z","steps":["trace[576805473] 'process raft request' (duration: 1.144841566s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:45.49987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.324284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T05:56:45.499891Z","caller":"traceutil/trace.go:171","msg":"trace[425989753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:100; }","duration":"832.387016ms","start":"2026-04-09T05:56:44.667496Z","end":"2026-04-09T05:56:45.499883Z","steps":["trace[425989753] 'agreement among raft nodes before linearized reading' (duration: 832.344725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:45.499914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:44.667471Z","time spent":"832.438437ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T05:56:45.499925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:44.354706Z","time spent":"1.145159103s","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:56:45.499928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.614432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T05:56:45.500007Z","caller":"traceutil/trace.go:171","msg":"trace[1513622826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:100; }","duration":"366.701534ms","start":"2026-04-09T05:56:45.133286Z","end":"2026-04-09T05:56:45.499987Z","steps":["trace[1513622826] 'agreement among raft nodes before linearized reading' (duration: 366.572871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:45.499817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.745521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T05:56:45.500046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:45.133243Z","time spent":"366.794686ms","remote":"127.0.0.1:50664","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":"info","ts":"2026-04-09T05:56:45.500082Z","caller":"traceutil/trace.go:171","msg":"trace[488680124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:100; }","duration":"873.069469ms","start":"2026-04-09T05:56:44.627Z","end":"2026-04-09T05:56:45.50007Z","steps":["trace[488680124] 'agreement among raft nodes before linearized reading' (duration: 872.754252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:45.500113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.967059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T05:56:45.500122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:44.626976Z","time spent":"873.133851ms","remote":"127.0.0.1:50414","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-04-09T05:56:45.500137Z","caller":"traceutil/trace.go:171","msg":"trace[831251958] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:100; }","duration":"713.041021ms","start":"2026-04-09T05:56:44.787087Z","end":"2026-04-09T05:56:45.500128Z","steps":["trace[831251958] 'agreement among raft nodes before linearized reading' (duration: 713.00488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:45.500155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:56:44.787068Z","time spent":"713.082912ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" "} {"level":"info","ts":"2026-04-09T05:56:45.734235Z","caller":"traceutil/trace.go:171","msg":"trace[25983494] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"230.240013ms","start":"2026-04-09T05:56:45.503968Z","end":"2026-04-09T05:56:45.734208Z","steps":["trace[25983494] 'process raft request' (duration: 221.013517ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:45.738816Z","caller":"traceutil/trace.go:171","msg":"trace[1675091194] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"234.192718ms","start":"2026-04-09T05:56:45.504576Z","end":"2026-04-09T05:56:45.738768Z","steps":["trace[1675091194] 'process raft request' (duration: 234.018243ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:45.738919Z","caller":"traceutil/trace.go:171","msg":"trace[1310811105] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"233.718297ms","start":"2026-04-09T05:56:45.50515Z","end":"2026-04-09T05:56:45.738868Z","steps":["trace[1310811105] 'process raft request' (duration: 233.532283ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:45.739057Z","caller":"traceutil/trace.go:171","msg":"trace[541022128] linearizableReadLoop","detail":"{readStateIndex:107; appliedIndex:104; }","duration":"111.754778ms","start":"2026-04-09T05:56:45.62729Z","end":"2026-04-09T05:56:45.739044Z","steps":["trace[541022128] 'read index received' (duration: 97.673255ms)","trace[541022128] 'applied index is now lower than readState.Index' (duration: 14.080643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:56:45.739163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.871122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T05:56:45.739194Z","caller":"traceutil/trace.go:171","msg":"trace[1070933817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:104; }","duration":"111.964754ms","start":"2026-04-09T05:56:45.627215Z","end":"2026-04-09T05:56:45.73918Z","steps":["trace[1070933817] 'agreement among raft nodes before linearized reading' (duration: 111.900462ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:47.167398Z","caller":"traceutil/trace.go:171","msg":"trace[40978693] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"137.929798ms","start":"2026-04-09T05:56:47.029445Z","end":"2026-04-09T05:56:47.167375Z","steps":["trace[40978693] 'process raft request' (duration: 137.769084ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:47.170684Z","caller":"traceutil/trace.go:171","msg":"trace[1585794559] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"136.668276ms","start":"2026-04-09T05:56:47.033992Z","end":"2026-04-09T05:56:47.17066Z","steps":["trace[1585794559] 'process raft request' (duration: 136.457991ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.495601Z","caller":"traceutil/trace.go:171","msg":"trace[67001741] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"374.740361ms","start":"2026-04-09T05:57:12.120831Z","end":"2026-04-09T05:57:12.495571Z","steps":["trace[67001741] 'process raft request' (duration: 374.522435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.49577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.12081Z","time spent":"374.882774ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:57:12.503189Z","caller":"traceutil/trace.go:171","msg":"trace[2091130749] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:530; }","duration":"119.825435ms","start":"2026-04-09T05:57:12.383351Z","end":"2026-04-09T05:57:12.503176Z","steps":["trace[2091130749] 'read index received' (duration: 111.918912ms)","trace[2091130749] 'applied index is now lower than readState.Index' (duration: 7.906053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:12.50327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.919647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:57:12.503404Z","caller":"traceutil/trace.go:171","msg":"trace[1005375162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"120.152274ms","start":"2026-04-09T05:57:12.38324Z","end":"2026-04-09T05:57:12.503393Z","steps":["trace[1005375162] 'agreement among raft nodes before linearized reading' (duration: 119.98283ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.503449Z","caller":"traceutil/trace.go:171","msg":"trace[2022590435] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"382.320985ms","start":"2026-04-09T05:57:12.121116Z","end":"2026-04-09T05:57:12.503437Z","steps":["trace[2022590435] 'process raft request' (duration: 381.568047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.50356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.121108Z","time spent":"382.389957ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:57:12.503612Z","caller":"traceutil/trace.go:171","msg":"trace[815766029] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"361.872556ms","start":"2026-04-09T05:57:12.141729Z","end":"2026-04-09T05:57:12.503602Z","steps":["trace[815766029] 'process raft request' (duration: 361.231931ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.503705Z","caller":"traceutil/trace.go:171","msg":"trace[238585777] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"376.709527ms","start":"2026-04-09T05:57:12.126986Z","end":"2026-04-09T05:57:12.503695Z","steps":["trace[238585777] 'process raft request' (duration: 375.762945ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.503743Z","caller":"traceutil/trace.go:171","msg":"trace[2051842218] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"211.833361ms","start":"2026-04-09T05:57:12.291899Z","end":"2026-04-09T05:57:12.503732Z","steps":["trace[2051842218] 'process raft request' (duration: 211.235297ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.503785Z","caller":"traceutil/trace.go:171","msg":"trace[1687430898] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"349.985645ms","start":"2026-04-09T05:57:12.15379Z","end":"2026-04-09T05:57:12.503776Z","steps":["trace[1687430898] 'process raft request' (duration: 349.212626ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.503799Z","caller":"traceutil/trace.go:171","msg":"trace[260530220] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"372.214289ms","start":"2026-04-09T05:57:12.131577Z","end":"2026-04-09T05:57:12.503791Z","steps":["trace[260530220] 'process raft request' (duration: 371.218934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.50384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.131554Z","time spent":"372.26863ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:57:12.50338Z","caller":"traceutil/trace.go:171","msg":"trace[860862691] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"362.05152ms","start":"2026-04-09T05:57:12.141303Z","end":"2026-04-09T05:57:12.503355Z","steps":["trace[860862691] 'process raft request' (duration: 361.581729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.503881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.153768Z","time spent":"350.048127ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:57:12.503924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.141292Z","time spent":"362.607574ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:57:12.503946Z","caller":"traceutil/trace.go:171","msg":"trace[1538060014] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"369.526793ms","start":"2026-04-09T05:57:12.134412Z","end":"2026-04-09T05:57:12.503939Z","steps":["trace[1538060014] 'process raft request' (duration: 368.440816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.503992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.134398Z","time spent":"369.575964ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:57:12.503706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.141721Z","time spent":"361.925237ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:57:12.503918Z","caller":"traceutil/trace.go:171","msg":"trace[98325350] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"369.708788ms","start":"2026-04-09T05:57:12.134201Z","end":"2026-04-09T05:57:12.50391Z","steps":["trace[98325350] 'process raft request' (duration: 368.6223ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.503745Z","caller":"traceutil/trace.go:171","msg":"trace[174553711] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"325.496828ms","start":"2026-04-09T05:57:12.178218Z","end":"2026-04-09T05:57:12.503715Z","steps":["trace[174553711] 'process raft request' (duration: 324.864203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.50376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.126654Z","time spent":"377.082866ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:57:12.504096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.134177Z","time spent":"369.896612ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:57:12.504161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.178194Z","time spent":"325.942819ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:57:12.504076Z","caller":"traceutil/trace.go:171","msg":"trace[909937560] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"335.684927ms","start":"2026-04-09T05:57:12.168348Z","end":"2026-04-09T05:57:12.504033Z","steps":["trace[909937560] 'process raft request' (duration: 334.697422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:12.504311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:12.168325Z","time spent":"335.959814ms","remote":"127.0.0.1:50448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:57:12.705401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.297562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:57:12.705667Z","caller":"traceutil/trace.go:171","msg":"trace[1761316206] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"119.97808ms","start":"2026-04-09T05:57:12.585665Z","end":"2026-04-09T05:57:12.705643Z","steps":["trace[1761316206] 'process raft request' (duration: 16.344669ms)","trace[1761316206] 'compare' (duration: 103.136778ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:12.705733Z","caller":"traceutil/trace.go:171","msg":"trace[567754430] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"111.36943ms","start":"2026-04-09T05:57:12.594343Z","end":"2026-04-09T05:57:12.705712Z","steps":["trace[567754430] 'process raft request' (duration: 111.177225ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.706095Z","caller":"traceutil/trace.go:171","msg":"trace[1681052373] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"108.133151ms","start":"2026-04-09T05:57:12.597937Z","end":"2026-04-09T05:57:12.70607Z","steps":["trace[1681052373] 'process raft request' (duration: 107.68589ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.706279Z","caller":"traceutil/trace.go:171","msg":"trace[1863525291] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"104.822451ms","start":"2026-04-09T05:57:12.601428Z","end":"2026-04-09T05:57:12.706251Z","steps":["trace[1863525291] 'process raft request' (duration: 104.544254ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:12.895154Z","caller":"traceutil/trace.go:171","msg":"trace[399706807] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"115.296015ms","start":"2026-04-09T05:57:12.779825Z","end":"2026-04-09T05:57:12.895121Z","steps":["trace[399706807] 'process raft request' (duration: 93.197255ms)","trace[399706807] 'compare' (duration: 21.824023ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:12.895422Z","caller":"traceutil/trace.go:171","msg":"trace[1365333319] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:563; }","duration":"110.319824ms","start":"2026-04-09T05:57:12.785093Z","end":"2026-04-09T05:57:12.895413Z","steps":["trace[1365333319] 'read index received' (duration: 5.797232ms)","trace[1365333319] 'applied index is now lower than readState.Index' (duration: 104.521692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:12.895491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.384365ms","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-04-09T05:57:12.895517Z","caller":"traceutil/trace.go:171","msg":"trace[824408672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"110.460117ms","start":"2026-04-09T05:57:12.78505Z","end":"2026-04-09T05:57:12.895511Z","steps":["trace[824408672] 'agreement among raft nodes before linearized reading' (duration: 110.393895ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:14.029217Z","caller":"traceutil/trace.go:171","msg":"trace[675111976] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"151.848517ms","start":"2026-04-09T05:57:13.87734Z","end":"2026-04-09T05:57:14.029189Z","steps":["trace[675111976] 'read index received' (duration: 53.65981ms)","trace[675111976] 'applied index is now lower than readState.Index' (duration: 98.187697ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:14.029303Z","caller":"traceutil/trace.go:171","msg":"trace[392164091] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"154.751528ms","start":"2026-04-09T05:57:13.874521Z","end":"2026-04-09T05:57:14.029272Z","steps":["trace[392164091] 'process raft request' (duration: 56.53227ms)","trace[392164091] 'compare' (duration: 98.019693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:14.029572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.337896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-09T05:57:14.029588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.890756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T05:57:14.029635Z","caller":"traceutil/trace.go:171","msg":"trace[1765380915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"149.437688ms","start":"2026-04-09T05:57:13.88018Z","end":"2026-04-09T05:57:14.029617Z","steps":["trace[1765380915] 'agreement among raft nodes before linearized reading' (duration: 149.290975ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:14.029645Z","caller":"traceutil/trace.go:171","msg":"trace[1595136825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"148.968468ms","start":"2026-04-09T05:57:13.88066Z","end":"2026-04-09T05:57:14.029628Z","steps":["trace[1595136825] 'agreement among raft nodes before linearized reading' (duration: 148.855955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:14.029641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.286988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-04-09T05:57:14.029688Z","caller":"traceutil/trace.go:171","msg":"trace[932871888] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:588; }","duration":"152.3392ms","start":"2026-04-09T05:57:13.877336Z","end":"2026-04-09T05:57:14.029675Z","steps":["trace[932871888] 'agreement among raft nodes before linearized reading' (duration: 152.013242ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:16.362496Z","caller":"traceutil/trace.go:171","msg":"trace[1905210871] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"118.391371ms","start":"2026-04-09T05:57:16.244068Z","end":"2026-04-09T05:57:16.362459Z","steps":["trace[1905210871] 'process raft request' (duration: 66.870043ms)","trace[1905210871] 'compare' (duration: 51.257181ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:18.405249Z","caller":"traceutil/trace.go:171","msg":"trace[523464522] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"351.631135ms","start":"2026-04-09T05:57:18.053587Z","end":"2026-04-09T05:57:18.405218Z","steps":["trace[523464522] 'process raft request' (duration: 351.425461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:18.405665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:18.053563Z","time spent":"351.772429ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:57:18.565724Z","caller":"traceutil/trace.go:171","msg":"trace[2137009914] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"510.109317ms","start":"2026-04-09T05:57:18.055577Z","end":"2026-04-09T05:57:18.565686Z","steps":["trace[2137009914] 'process raft request' (duration: 509.702777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:18.56591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:18.055559Z","time spent":"510.2585ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:57:18.566575Z","caller":"traceutil/trace.go:171","msg":"trace[1756747855] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:633; }","duration":"180.083749ms","start":"2026-04-09T05:57:18.38647Z","end":"2026-04-09T05:57:18.566554Z","steps":["trace[1756747855] 'read index received' (duration: 18.654996ms)","trace[1756747855] 'applied index is now lower than readState.Index' (duration: 161.428013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:18.566672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.194241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:57:18.566694Z","caller":"traceutil/trace.go:171","msg":"trace[1105617166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"180.264893ms","start":"2026-04-09T05:57:18.386421Z","end":"2026-04-09T05:57:18.566686Z","steps":["trace[1105617166] 'agreement among raft nodes before linearized reading' (duration: 180.212682ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:18.566748Z","caller":"traceutil/trace.go:171","msg":"trace[570533092] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"388.178899ms","start":"2026-04-09T05:57:18.178563Z","end":"2026-04-09T05:57:18.566742Z","steps":["trace[570533092] 'process raft request' (duration: 387.590355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:18.566831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:18.178538Z","time spent":"388.223121ms","remote":"127.0.0.1:50492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:57:19.471549Z","caller":"traceutil/trace.go:171","msg":"trace[961083395] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"268.975068ms","start":"2026-04-09T05:57:19.202548Z","end":"2026-04-09T05:57:19.471523Z","steps":["trace[961083395] 'read index received' (duration: 268.181038ms)","trace[961083395] 'applied index is now lower than readState.Index' (duration: 792.88µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:19.471624Z","caller":"traceutil/trace.go:171","msg":"trace[34665436] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"435.500875ms","start":"2026-04-09T05:57:19.036084Z","end":"2026-04-09T05:57:19.471585Z","steps":["trace[34665436] 'process raft request' (duration: 434.935271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:19.471765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.193283ms","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-04-09T05:57:19.471801Z","caller":"traceutil/trace.go:171","msg":"trace[896543613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"269.283466ms","start":"2026-04-09T05:57:19.202506Z","end":"2026-04-09T05:57:19.471789Z","steps":["trace[896543613] 'agreement among raft nodes before linearized reading' (duration: 269.144312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:19.471809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:19.036026Z","time spent":"435.682229ms","remote":"127.0.0.1:50492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:57:19.471839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.083618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-whnnx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T05:57:19.471899Z","caller":"traceutil/trace.go:171","msg":"trace[188834536] range","detail":"{range_begin:/registry/pods/kube-system/cilium-whnnx; range_end:; response_count:1; response_revision:620; }","duration":"178.170361ms","start":"2026-04-09T05:57:19.29371Z","end":"2026-04-09T05:57:19.47188Z","steps":["trace[188834536] 'agreement among raft nodes before linearized reading' (duration: 178.001987ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:20.885855Z","caller":"traceutil/trace.go:171","msg":"trace[2036436476] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"500.42462ms","start":"2026-04-09T05:57:20.385409Z","end":"2026-04-09T05:57:20.885834Z","steps":["trace[2036436476] 'read index received' (duration: 496.875202ms)","trace[2036436476] 'applied index is now lower than readState.Index' (duration: 3.548308ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:20.886253Z","caller":"traceutil/trace.go:171","msg":"trace[1350722472] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"936.498208ms","start":"2026-04-09T05:57:19.949397Z","end":"2026-04-09T05:57:20.885895Z","steps":["trace[1350722472] 'process raft request' (duration: 933.640738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:20.886334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.552773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:57:20.886614Z","caller":"traceutil/trace.go:171","msg":"trace[94702455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"501.21958ms","start":"2026-04-09T05:57:20.38536Z","end":"2026-04-09T05:57:20.88658Z","steps":["trace[94702455] 'agreement among raft nodes before linearized reading' (duration: 500.557044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:20.886626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:19.949362Z","time spent":"937.129093ms","remote":"127.0.0.1:50492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:57:20.886672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.715765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-09T05:57:20.886707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:20.385337Z","time spent":"501.333612ms","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T05:57:20.886761Z","caller":"traceutil/trace.go:171","msg":"trace[1371056760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"477.86184ms","start":"2026-04-09T05:57:20.408881Z","end":"2026-04-09T05:57:20.886743Z","steps":["trace[1371056760] 'agreement among raft nodes before linearized reading' (duration: 477.665965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:20.886812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:20.408872Z","time spent":"477.930061ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T05:57:20.88758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.070262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T05:57:20.887646Z","caller":"traceutil/trace.go:171","msg":"trace[1929596346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"317.169885ms","start":"2026-04-09T05:57:20.570458Z","end":"2026-04-09T05:57:20.887628Z","steps":["trace[1929596346] 'agreement among raft nodes before linearized reading' (duration: 316.96934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:20.887694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:20.570431Z","time spent":"317.240956ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T05:57:22.678062Z","caller":"traceutil/trace.go:171","msg":"trace[214513853] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"156.564513ms","start":"2026-04-09T05:57:22.521387Z","end":"2026-04-09T05:57:22.677951Z","steps":["trace[214513853] 'process raft request' (duration: 156.451211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:36.377957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.959318ms","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-04-09T05:57:36.378075Z","caller":"traceutil/trace.go:171","msg":"trace[703386402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"819.101802ms","start":"2026-04-09T05:57:35.558936Z","end":"2026-04-09T05:57:36.378038Z","steps":["trace[703386402] 'range keys from in-memory index tree' (duration: 818.781894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:36.378138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:35.558913Z","time spent":"819.211575ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T05:57:36.378168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.717637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-09T05:57:36.378258Z","caller":"traceutil/trace.go:171","msg":"trace[1114949936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"199.86614ms","start":"2026-04-09T05:57:36.17837Z","end":"2026-04-09T05:57:36.378236Z","steps":["trace[1114949936] 'range keys from in-memory index tree' (duration: 199.484091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:36.378225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.0297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vws86\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-09T05:57:36.378329Z","caller":"traceutil/trace.go:171","msg":"trace[287291595] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vws86; range_end:; response_count:1; response_revision:784; }","duration":"989.176234ms","start":"2026-04-09T05:57:35.389131Z","end":"2026-04-09T05:57:36.378307Z","steps":["trace[287291595] 'range keys from in-memory index tree' (duration: 988.852016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:36.378372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:35.389113Z","time spent":"989.248616ms","remote":"127.0.0.1:50574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vws86\" "} {"level":"info","ts":"2026-04-09T05:57:36.542928Z","caller":"traceutil/trace.go:171","msg":"trace[429746418] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"159.793832ms","start":"2026-04-09T05:57:36.383114Z","end":"2026-04-09T05:57:36.542908Z","steps":["trace[429746418] 'process raft request' (duration: 159.648628ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:36.54327Z","caller":"traceutil/trace.go:171","msg":"trace[290702488] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"159.619267ms","start":"2026-04-09T05:57:36.3836Z","end":"2026-04-09T05:57:36.543219Z","steps":["trace[290702488] 'process raft request' (duration: 159.273129ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:36.543425Z","caller":"traceutil/trace.go:171","msg":"trace[1866399588] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"156.701806ms","start":"2026-04-09T05:57:36.386717Z","end":"2026-04-09T05:57:36.543418Z","steps":["trace[1866399588] 'process raft request' (duration: 156.390169ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:36.54345Z","caller":"traceutil/trace.go:171","msg":"trace[1535655060] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:811; }","duration":"156.82395ms","start":"2026-04-09T05:57:36.386545Z","end":"2026-04-09T05:57:36.543369Z","steps":["trace[1535655060] 'read index received' (duration: 151.400287ms)","trace[1535655060] 'applied index is now lower than readState.Index' (duration: 5.420103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:36.543641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.084356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:57:36.543724Z","caller":"traceutil/trace.go:171","msg":"trace[1124340405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"157.188698ms","start":"2026-04-09T05:57:36.386515Z","end":"2026-04-09T05:57:36.543704Z","steps":["trace[1124340405] 'agreement among raft nodes before linearized reading' (duration: 157.089656ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:51.130682Z","caller":"traceutil/trace.go:171","msg":"trace[1837059726] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"454.179907ms","start":"2026-04-09T05:57:50.676479Z","end":"2026-04-09T05:57:51.130659Z","steps":["trace[1837059726] 'read index received' (duration: 454.006673ms)","trace[1837059726] 'applied index is now lower than readState.Index' (duration: 172.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:51.130755Z","caller":"traceutil/trace.go:171","msg":"trace[2077840958] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"501.304879ms","start":"2026-04-09T05:57:50.629421Z","end":"2026-04-09T05:57:51.130726Z","steps":["trace[2077840958] 'process raft request' (duration: 501.105414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:51.130902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:50.629389Z","time spent":"501.422422ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:57:51.130924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.412613ms","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-04-09T05:57:51.130983Z","caller":"traceutil/trace.go:171","msg":"trace[1195475685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"454.523956ms","start":"2026-04-09T05:57:50.676441Z","end":"2026-04-09T05:57:51.130965Z","steps":["trace[1195475685] 'agreement among raft nodes before linearized reading' (duration: 454.335451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:51.131048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:50.676418Z","time spent":"454.615268ms","remote":"127.0.0.1:50664","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-04-09T05:57:51.131474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.323998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-s7tqw\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-04-09T05:57:51.131599Z","caller":"traceutil/trace.go:171","msg":"trace[1919965373] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-s7tqw; range_end:; response_count:1; response_revision:917; }","duration":"194.684527ms","start":"2026-04-09T05:57:50.936883Z","end":"2026-04-09T05:57:51.131568Z","steps":["trace[1919965373] 'agreement among raft nodes before linearized reading' (duration: 193.98086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:11.79766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366889101753938,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:58:11.843125Z","caller":"traceutil/trace.go:171","msg":"trace[1628620764] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"564.801655ms","start":"2026-04-09T05:58:11.278298Z","end":"2026-04-09T05:58:11.8431Z","steps":["trace[1628620764] 'process raft request' (duration: 564.672082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:11.843285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.278269Z","time spent":"564.929719ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:12.449326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.662933ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T05:58:12.449799Z","caller":"traceutil/trace.go:171","msg":"trace[203589869] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"690.73515ms","start":"2026-04-09T05:58:11.759043Z","end":"2026-04-09T05:58:12.449778Z","steps":["trace[203589869] 'process raft request' (duration: 690.385511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:12.44993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.759018Z","time spent":"690.843793ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:12.450325Z","caller":"traceutil/trace.go:171","msg":"trace[33712602] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1031; }","duration":"1.152618768s","start":"2026-04-09T05:58:11.29734Z","end":"2026-04-09T05:58:12.449958Z","steps":["trace[33712602] 'read index received' (duration: 545.710431ms)","trace[33712602] 'applied index is now lower than readState.Index' (duration: 606.905847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.469561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084361788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:12.469624Z","caller":"traceutil/trace.go:171","msg":"trace[1303779255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"1.0844479s","start":"2026-04-09T05:58:11.385161Z","end":"2026-04-09T05:58:12.469609Z","steps":["trace[1303779255] 'agreement among raft nodes before linearized reading' (duration: 1.065408317s)","trace[1303779255] 'range keys from in-memory index tree' (duration: 18.962052ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.469612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172252937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-09T05:58:12.469659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.385148Z","time spent":"1.084503442s","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T05:58:12.469682Z","caller":"traceutil/trace.go:171","msg":"trace[1880155751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.17236517s","start":"2026-04-09T05:58:11.297299Z","end":"2026-04-09T05:58:12.469664Z","steps":["trace[1880155751] 'agreement among raft nodes before linearized reading' (duration: 1.152824433s)","trace[1880155751] 'range keys from in-memory index tree' (duration: 19.343632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.469673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.143167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:12.469703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148730878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-09T05:58:12.469723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.29728Z","time spent":"1.172433461s","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T05:58:12.469743Z","caller":"traceutil/trace.go:171","msg":"trace[1346051636] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:998; }","duration":"874.28032ms","start":"2026-04-09T05:58:11.595446Z","end":"2026-04-09T05:58:12.469727Z","steps":["trace[1346051636] 'agreement among raft nodes before linearized reading' (duration: 855.109984ms)","trace[1346051636] 'count revisions from in-memory index tree' (duration: 19.048814ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:12.46978Z","caller":"traceutil/trace.go:171","msg":"trace[1464331872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.14880671s","start":"2026-04-09T05:58:11.320927Z","end":"2026-04-09T05:58:12.469734Z","steps":["trace[1464331872] 'agreement among raft nodes before linearized reading' (duration: 1.129567221s)","trace[1464331872] 'range keys from in-memory index tree' (duration: 19.091645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.469744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144582039s","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-04-09T05:58:12.469805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.595415Z","time spent":"874.381453ms","remote":"127.0.0.1:50672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:12.46982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.320908Z","time spent":"1.148903632s","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T05:58:12.469849Z","caller":"traceutil/trace.go:171","msg":"trace[1270382683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"1.144725413s","start":"2026-04-09T05:58:11.325102Z","end":"2026-04-09T05:58:12.469828Z","steps":["trace[1270382683] 'agreement among raft nodes before linearized reading' (duration: 1.125473955s)","trace[1270382683] 'range keys from in-memory index tree' (duration: 18.996342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.469917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.325092Z","time spent":"1.144814635s","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T05:58:12.469975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148279505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-09T05:58:12.470016Z","caller":"traceutil/trace.go:171","msg":"trace[1321470982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.148338127s","start":"2026-04-09T05:58:11.321666Z","end":"2026-04-09T05:58:12.470004Z","steps":["trace[1321470982] 'agreement among raft nodes before linearized reading' (duration: 1.128916654s)","trace[1321470982] 'range keys from in-memory index tree' (duration: 18.863408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.470044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.321652Z","time spent":"1.148385727s","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T05:58:12.978239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366889101753944,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:58:12.982871Z","caller":"traceutil/trace.go:171","msg":"trace[1378891423] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"507.359656ms","start":"2026-04-09T05:58:12.475482Z","end":"2026-04-09T05:58:12.982842Z","steps":["trace[1378891423] 'process raft request' (duration: 507.16119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:12.983121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:12.475454Z","time spent":"507.586082ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:13.39782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.16533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:58:13.398Z","caller":"traceutil/trace.go:171","msg":"trace[373668230] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"922.398268ms","start":"2026-04-09T05:58:12.475575Z","end":"2026-04-09T05:58:13.397974Z","steps":["trace[373668230] 'process raft request' (duration: 598.973681ms)","trace[373668230] 'compare' (duration: 322.753769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:13.398109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:12.475555Z","time spent":"922.4965ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:13.398365Z","caller":"traceutil/trace.go:171","msg":"trace[1673277262] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1034; }","duration":"920.792836ms","start":"2026-04-09T05:58:12.477561Z","end":"2026-04-09T05:58:13.398354Z","steps":["trace[1673277262] 'read index received' (duration: 505.072326ms)","trace[1673277262] 'applied index is now lower than readState.Index' (duration: 415.71932ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:13.398386Z","caller":"traceutil/trace.go:171","msg":"trace[1603177731] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"922.383897ms","start":"2026-04-09T05:58:12.475993Z","end":"2026-04-09T05:58:13.398377Z","steps":["trace[1603177731] 'process raft request' (duration: 922.276154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:13.398428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.866968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:13.398427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:12.475985Z","time spent":"922.422487ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:13.39845Z","caller":"traceutil/trace.go:171","msg":"trace[1840203296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"920.910819ms","start":"2026-04-09T05:58:12.477533Z","end":"2026-04-09T05:58:13.398444Z","steps":["trace[1840203296] 'agreement among raft nodes before linearized reading' (duration: 920.859758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:13.39847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:12.477514Z","time spent":"920.9517ms","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T05:58:13.398538Z","caller":"traceutil/trace.go:171","msg":"trace[1833117308] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"922.259194ms","start":"2026-04-09T05:58:12.476239Z","end":"2026-04-09T05:58:13.398498Z","steps":["trace[1833117308] 'process raft request' (duration: 922.074169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:13.398735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:12.476228Z","time spent":"922.382887ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:19.56445Z","caller":"traceutil/trace.go:171","msg":"trace[417539138] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"113.275067ms","start":"2026-04-09T05:58:19.451151Z","end":"2026-04-09T05:58:19.564426Z","steps":["trace[417539138] 'process raft request' (duration: 113.00871ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:26.670706Z","caller":"traceutil/trace.go:171","msg":"trace[1016183435] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"176.000404ms","start":"2026-04-09T05:58:26.494668Z","end":"2026-04-09T05:58:26.670669Z","steps":["trace[1016183435] 'read index received' (duration: 175.698306ms)","trace[1016183435] 'applied index is now lower than readState.Index' (duration: 300.468µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:26.670804Z","caller":"traceutil/trace.go:171","msg":"trace[971503572] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"179.831964ms","start":"2026-04-09T05:58:26.490938Z","end":"2026-04-09T05:58:26.67077Z","steps":["trace[971503572] 'process raft request' (duration: 179.472524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:26.670899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.178508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:26.670972Z","caller":"traceutil/trace.go:171","msg":"trace[747066750] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1045; }","duration":"176.320462ms","start":"2026-04-09T05:58:26.494638Z","end":"2026-04-09T05:58:26.670959Z","steps":["trace[747066750] 'agreement among raft nodes before linearized reading' (duration: 176.171259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.138679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366889101754141,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:58:36.284574Z","caller":"traceutil/trace.go:171","msg":"trace[1297812464] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"741.415934ms","start":"2026-04-09T05:58:35.543136Z","end":"2026-04-09T05:58:36.284552Z","steps":["trace[1297812464] 'process raft request' (duration: 741.25088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.284717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.543119Z","time spent":"741.511396ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:36.30168Z","caller":"traceutil/trace.go:171","msg":"trace[704925678] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1112; }","duration":"663.398732ms","start":"2026-04-09T05:58:35.638261Z","end":"2026-04-09T05:58:36.30166Z","steps":["trace[704925678] 'read index received' (duration: 646.202565ms)","trace[704925678] 'applied index is now lower than readState.Index' (duration: 17.195307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:36.301944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.569967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T05:58:36.301828Z","caller":"traceutil/trace.go:171","msg":"trace[1721422193] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"757.678017ms","start":"2026-04-09T05:58:35.544067Z","end":"2026-04-09T05:58:36.301745Z","steps":["trace[1721422193] 'process raft request' (duration: 757.511843ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:36.302004Z","caller":"traceutil/trace.go:171","msg":"trace[124528769] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1074; }","duration":"373.69056ms","start":"2026-04-09T05:58:35.928301Z","end":"2026-04-09T05:58:36.301991Z","steps":["trace[124528769] 'agreement among raft nodes before linearized reading' (duration: 373.575607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.302035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.928281Z","time spent":"373.747452ms","remote":"127.0.0.1:50858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:36.302034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.74689ms","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-04-09T05:58:36.302083Z","caller":"traceutil/trace.go:171","msg":"trace[955228564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"663.854323ms","start":"2026-04-09T05:58:35.638213Z","end":"2026-04-09T05:58:36.302067Z","steps":["trace[955228564] 'agreement among raft nodes before linearized reading' (duration: 663.561936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.302241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.63819Z","time spent":"664.034918ms","remote":"127.0.0.1:50664","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-04-09T05:58:36.302402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.544038Z","time spent":"758.091578ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:36.302761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.958214ms","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-04-09T05:58:36.302948Z","caller":"traceutil/trace.go:171","msg":"trace[1536253761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"323.170039ms","start":"2026-04-09T05:58:35.97976Z","end":"2026-04-09T05:58:36.30293Z","steps":["trace[1536253761] 'agreement among raft nodes before linearized reading' (duration: 322.334998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.303087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.979749Z","time spent":"323.321233ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T05:58:36.674978Z","caller":"traceutil/trace.go:171","msg":"trace[1565826975] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"340.661114ms","start":"2026-04-09T05:58:36.334289Z","end":"2026-04-09T05:58:36.67495Z","steps":["trace[1565826975] 'read index received' (duration: 34.163916ms)","trace[1565826975] 'applied index is now lower than readState.Index' (duration: 306.496158ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:36.675022Z","caller":"traceutil/trace.go:171","msg":"trace[1973549] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"368.236629ms","start":"2026-04-09T05:58:36.306746Z","end":"2026-04-09T05:58:36.674983Z","steps":["trace[1973549] 'process raft request' (duration: 289.935739ms)","trace[1973549] 'compare' (duration: 78.172077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:36.67533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:36.306725Z","time spent":"368.426454ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:36.675361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.031584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T05:58:36.675445Z","caller":"traceutil/trace.go:171","msg":"trace[1552830152] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1076; }","duration":"341.170627ms","start":"2026-04-09T05:58:36.334253Z","end":"2026-04-09T05:58:36.675424Z","steps":["trace[1552830152] 'agreement among raft nodes before linearized reading' (duration: 340.813308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.675496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:36.334237Z","time spent":"341.248399ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-09T05:58:36.675392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.226416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:36.675812Z","caller":"traceutil/trace.go:171","msg":"trace[2098249714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"290.686328ms","start":"2026-04-09T05:58:36.385099Z","end":"2026-04-09T05:58:36.675785Z","steps":["trace[2098249714] 'agreement among raft nodes before linearized reading' (duration: 289.99632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.895105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.666531ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-09T05:58:36.895359Z","caller":"traceutil/trace.go:171","msg":"trace[272942380] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"165.671846ms","start":"2026-04-09T05:58:36.729668Z","end":"2026-04-09T05:58:36.89534Z","steps":["trace[272942380] 'process raft request' (duration: 165.544083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:37.539757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.847457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:58:37.539924Z","caller":"traceutil/trace.go:171","msg":"trace[1275982661] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"547.379855ms","start":"2026-04-09T05:58:36.992519Z","end":"2026-04-09T05:58:37.539899Z","steps":["trace[1275982661] 'read index received' (duration: 73.346592ms)","trace[1275982661] 'applied index is now lower than readState.Index' (duration: 474.031373ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:37.540117Z","caller":"traceutil/trace.go:171","msg":"trace[1185111590] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"643.468976ms","start":"2026-04-09T05:58:36.896621Z","end":"2026-04-09T05:58:37.54009Z","steps":["trace[1185111590] 'process raft request' (duration: 169.206887ms)","trace[1185111590] 'compare' (duration: 473.145409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:37.54013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.59095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:37.540199Z","caller":"traceutil/trace.go:171","msg":"trace[1161091519] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1078; }","duration":"547.719113ms","start":"2026-04-09T05:58:36.992465Z","end":"2026-04-09T05:58:37.540184Z","steps":["trace[1161091519] 'agreement among raft nodes before linearized reading' (duration: 547.545829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:37.540245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:36.992446Z","time spent":"547.787445ms","remote":"127.0.0.1:48458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:37.540263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.844495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T05:58:37.540311Z","caller":"traceutil/trace.go:171","msg":"trace[1924312919] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1078; }","duration":"331.922677ms","start":"2026-04-09T05:58:37.20837Z","end":"2026-04-09T05:58:37.540292Z","steps":["trace[1924312919] 'agreement among raft nodes before linearized reading' (duration: 331.809964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:37.540361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.208348Z","time spent":"332.003429ms","remote":"127.0.0.1:50480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:37.540121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.947232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:37.540427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.412501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-09T05:58:37.540479Z","caller":"traceutil/trace.go:171","msg":"trace[978285296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"154.317431ms","start":"2026-04-09T05:58:37.386147Z","end":"2026-04-09T05:58:37.540465Z","steps":["trace[978285296] 'agreement among raft nodes before linearized reading' (duration: 153.902701ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:37.540511Z","caller":"traceutil/trace.go:171","msg":"trace[1976612234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"371.561515ms","start":"2026-04-09T05:58:37.168937Z","end":"2026-04-09T05:58:37.540499Z","steps":["trace[1976612234] 'agreement among raft nodes before linearized reading' (duration: 371.092613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:37.540678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.168917Z","time spent":"371.656088ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T05:58:37.540356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:36.896599Z","time spent":"643.585639ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:38.07559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.403639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:58:38.07595Z","caller":"traceutil/trace.go:171","msg":"trace[311880018] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"531.754019ms","start":"2026-04-09T05:58:37.544151Z","end":"2026-04-09T05:58:38.075905Z","steps":["trace[311880018] 'process raft request' (duration: 107.95032ms)","trace[311880018] 'compare' (duration: 423.291826ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:38.075697Z","caller":"traceutil/trace.go:171","msg":"trace[1467558013] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"531.327657ms","start":"2026-04-09T05:58:37.544352Z","end":"2026-04-09T05:58:38.07568Z","steps":["trace[1467558013] 'read index received' (duration: 107.708203ms)","trace[1467558013] 'applied index is now lower than readState.Index' (duration: 423.618274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:38.076112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.54413Z","time spent":"531.890202ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:38.076245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.540109ms","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-04-09T05:58:38.076304Z","caller":"traceutil/trace.go:171","msg":"trace[1368042359] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1079; }","duration":"527.643072ms","start":"2026-04-09T05:58:37.548646Z","end":"2026-04-09T05:58:38.076289Z","steps":["trace[1368042359] 'agreement among raft nodes before linearized reading' (duration: 527.530379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.076299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.918213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-09T05:58:38.076343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.548626Z","time spent":"527.706283ms","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-09T05:58:38.07639Z","caller":"traceutil/trace.go:171","msg":"trace[1315428550] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1079; }","duration":"532.071117ms","start":"2026-04-09T05:58:37.5443Z","end":"2026-04-09T05:58:38.076371Z","steps":["trace[1315428550] 'agreement among raft nodes before linearized reading' (duration: 531.81278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.07644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.544279Z","time spent":"532.151248ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-09T05:58:38.886111Z","caller":"traceutil/trace.go:171","msg":"trace[1718070923] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"202.254444ms","start":"2026-04-09T05:58:38.683826Z","end":"2026-04-09T05:58:38.886081Z","steps":["trace[1718070923] 'process raft request' (duration: 202.10138ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:39.422014Z","caller":"traceutil/trace.go:171","msg":"trace[1755272237] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"304.384031ms","start":"2026-04-09T05:58:39.117601Z","end":"2026-04-09T05:58:39.421985Z","steps":["trace[1755272237] 'read index received' (duration: 244.094699ms)","trace[1755272237] 'applied index is now lower than readState.Index' (duration: 60.287982ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:39.422101Z","caller":"traceutil/trace.go:171","msg":"trace[1123221948] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"521.621656ms","start":"2026-04-09T05:58:38.900448Z","end":"2026-04-09T05:58:39.422069Z","steps":["trace[1123221948] 'process raft request' (duration: 461.368634ms)","trace[1123221948] 'compare' (duration: 59.844481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:39.422203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.585987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T05:58:39.42225Z","caller":"traceutil/trace.go:171","msg":"trace[4073744] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1084; }","duration":"304.699101ms","start":"2026-04-09T05:58:39.117536Z","end":"2026-04-09T05:58:39.422235Z","steps":["trace[4073744] 'agreement among raft nodes before linearized reading' (duration: 304.574607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:39.422292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:39.117509Z","time spent":"304.772143ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:39.42225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:38.900424Z","time spent":"521.736048ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:40.279472Z","caller":"traceutil/trace.go:171","msg":"trace[924595815] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"195.631823ms","start":"2026-04-09T05:58:40.083809Z","end":"2026-04-09T05:58:40.279441Z","steps":["trace[924595815] 'process raft request' (duration: 195.331085ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:41.046412Z","caller":"traceutil/trace.go:171","msg":"trace[1446658466] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"152.155095ms","start":"2026-04-09T05:58:40.894231Z","end":"2026-04-09T05:58:41.046386Z","steps":["trace[1446658466] 'process raft request' (duration: 152.011741ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:51.600193Z","caller":"traceutil/trace.go:171","msg":"trace[49187675] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"132.42672ms","start":"2026-04-09T05:58:51.467741Z","end":"2026-04-09T05:58:51.600167Z","steps":["trace[49187675] 'process raft request' (duration: 132.249796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:52.849735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366889101754374,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:58:53.040785Z","caller":"traceutil/trace.go:171","msg":"trace[1906390575] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"692.106127ms","start":"2026-04-09T05:58:52.348657Z","end":"2026-04-09T05:58:53.040763Z","steps":["trace[1906390575] 'read index received' (duration: 688.380496ms)","trace[1906390575] 'applied index is now lower than readState.Index' (duration: 3.724261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:53.041027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.358344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T05:58:53.041067Z","caller":"traceutil/trace.go:171","msg":"trace[1433625937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1175; }","duration":"692.441865ms","start":"2026-04-09T05:58:52.348614Z","end":"2026-04-09T05:58:53.041056Z","steps":["trace[1433625937] 'agreement among raft nodes before linearized reading' (duration: 692.276701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.041103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:52.348596Z","time spent":"692.499447ms","remote":"127.0.0.1:50664","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-04-09T05:58:53.041338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.28583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:53.041376Z","caller":"traceutil/trace.go:171","msg":"trace[2066493626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"655.362222ms","start":"2026-04-09T05:58:52.386004Z","end":"2026-04-09T05:58:53.041366Z","steps":["trace[2066493626] 'agreement among raft nodes before linearized reading' (duration: 655.31279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.041401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:52.385971Z","time spent":"655.424163ms","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T05:58:53.041609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.346271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:53.041655Z","caller":"traceutil/trace.go:171","msg":"trace[21413842] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1175; }","duration":"468.448963ms","start":"2026-04-09T05:58:52.573195Z","end":"2026-04-09T05:58:53.041644Z","steps":["trace[21413842] 'agreement among raft nodes before linearized reading' (duration: 468.371531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.041701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:52.573169Z","time spent":"468.520595ms","remote":"127.0.0.1:48484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:53.041968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.442279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T05:58:53.042011Z","caller":"traceutil/trace.go:171","msg":"trace[1055567939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1175; }","duration":"524.522901ms","start":"2026-04-09T05:58:52.517479Z","end":"2026-04-09T05:58:53.042002Z","steps":["trace[1055567939] 'agreement among raft nodes before linearized reading' (duration: 524.417768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.042041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:52.51746Z","time spent":"524.573512ms","remote":"127.0.0.1:50664","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-04-09T05:58:53.042205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.467496ms","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-04-09T05:58:53.042239Z","caller":"traceutil/trace.go:171","msg":"trace[1879054738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"546.534567ms","start":"2026-04-09T05:58:52.495697Z","end":"2026-04-09T05:58:53.042232Z","steps":["trace[1879054738] 'agreement among raft nodes before linearized reading' (duration: 546.452675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.042266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:52.495668Z","time spent":"546.590579ms","remote":"127.0.0.1:50664","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-04-09T05:58:53.042442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.243863ms","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-04-09T05:58:53.042477Z","caller":"traceutil/trace.go:171","msg":"trace[1845243858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1175; }","duration":"574.314635ms","start":"2026-04-09T05:58:52.468153Z","end":"2026-04-09T05:58:53.042468Z","steps":["trace[1845243858] 'agreement among raft nodes before linearized reading' (duration: 574.220773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.042503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:52.468133Z","time spent":"574.364267ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T05:58:53.344147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.171324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:58:53.344242Z","caller":"traceutil/trace.go:171","msg":"trace[570802298] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"103.848983ms","start":"2026-04-09T05:58:53.240376Z","end":"2026-04-09T05:58:53.344225Z","steps":["trace[570802298] 'read index received' (duration: 38.961µs)","trace[570802298] 'applied index is now lower than readState.Index' (duration: 103.808932ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:53.344328Z","caller":"traceutil/trace.go:171","msg":"trace[1945232392] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"247.552936ms","start":"2026-04-09T05:58:53.096763Z","end":"2026-04-09T05:58:53.344316Z","steps":["trace[1945232392] 'process raft request' (duration: 76.140446ms)","trace[1945232392] 'compare' (duration: 171.054991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:53.344631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.212821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:53.344724Z","caller":"traceutil/trace.go:171","msg":"trace[1217899022] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1180; }","duration":"104.368355ms","start":"2026-04-09T05:58:53.24033Z","end":"2026-04-09T05:58:53.344699Z","steps":["trace[1217899022] 'agreement among raft nodes before linearized reading' (duration: 104.186651ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:53.718932Z","caller":"traceutil/trace.go:171","msg":"trace[650636711] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"113.615071ms","start":"2026-04-09T05:58:53.605296Z","end":"2026-04-09T05:58:53.718912Z","steps":["trace[650636711] 'process raft request' (duration: 113.484278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:08.628948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.601223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:08.629049Z","caller":"traceutil/trace.go:171","msg":"trace[1762581368] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"230.175623ms","start":"2026-04-09T05:59:08.398854Z","end":"2026-04-09T05:59:08.629029Z","steps":["trace[1762581368] 'process raft request' (duration: 106.406965ms)","trace[1762581368] 'compare' (duration: 123.298636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:10.134916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366889101754543,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:59:10.30517Z","caller":"traceutil/trace.go:171","msg":"trace[1085580459] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"752.597083ms","start":"2026-04-09T05:59:09.552552Z","end":"2026-04-09T05:59:10.305149Z","steps":["trace[1085580459] 'process raft request' (duration: 752.47485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:10.305296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:09.552525Z","time spent":"752.710045ms","remote":"127.0.0.1:50480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T05:59:10.433685Z","caller":"traceutil/trace.go:171","msg":"trace[612034610] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"124.925777ms","start":"2026-04-09T05:59:10.308737Z","end":"2026-04-09T05:59:10.433663Z","steps":["trace[612034610] 'process raft request' (duration: 124.867556ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:10.433876Z","caller":"traceutil/trace.go:171","msg":"trace[1498293221] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"650.803581ms","start":"2026-04-09T05:59:09.783051Z","end":"2026-04-09T05:59:10.433854Z","steps":["trace[1498293221] 'process raft request' (duration: 586.320898ms)","trace[1498293221] 'compare' (duration: 64.099183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:10.433993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:09.783024Z","time spent":"650.897633ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:59:10.434031Z","caller":"traceutil/trace.go:171","msg":"trace[184821536] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"799.866065ms","start":"2026-04-09T05:59:09.634148Z","end":"2026-04-09T05:59:10.434014Z","steps":["trace[184821536] 'read index received' (duration: 670.897211ms)","trace[184821536] 'applied index is now lower than readState.Index' (duration: 128.966864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:10.434266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.115782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:59:10.434328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.802513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:59:10.434346Z","caller":"traceutil/trace.go:171","msg":"trace[910091344] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1257; }","duration":"800.236574ms","start":"2026-04-09T05:59:09.634095Z","end":"2026-04-09T05:59:10.434332Z","steps":["trace[910091344] 'agreement among raft nodes before linearized reading' (duration: 800.109721ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:10.434364Z","caller":"traceutil/trace.go:171","msg":"trace[1949718710] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1257; }","duration":"370.874554ms","start":"2026-04-09T05:59:10.063479Z","end":"2026-04-09T05:59:10.434354Z","steps":["trace[1949718710] 'agreement among raft nodes before linearized reading' (duration: 370.814043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:10.434398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:10.063461Z","time spent":"370.928626ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:59:10.434403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:09.634073Z","time spent":"800.317506ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-09T05:59:11.341076Z","caller":"traceutil/trace.go:171","msg":"trace[146825987] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"202.917427ms","start":"2026-04-09T05:59:11.138114Z","end":"2026-04-09T05:59:11.341032Z","steps":["trace[146825987] 'process raft request' (duration: 202.61601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:11.966916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.189264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:11.967121Z","caller":"traceutil/trace.go:171","msg":"trace[1772103394] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1310; }","duration":"825.225874ms","start":"2026-04-09T05:59:11.141875Z","end":"2026-04-09T05:59:11.967101Z","steps":["trace[1772103394] 'read index received' (duration: 198.799378ms)","trace[1772103394] 'applied index is now lower than readState.Index' (duration: 626.425296ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:11.967191Z","caller":"traceutil/trace.go:171","msg":"trace[1421280533] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"825.394378ms","start":"2026-04-09T05:59:11.141786Z","end":"2026-04-09T05:59:11.96718Z","steps":["trace[1421280533] 'process raft request' (duration: 521.864787ms)","trace[1421280533] 'compare' (duration: 302.976138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:11.967246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.141732Z","time spent":"825.48295ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:59:11.96741Z","caller":"traceutil/trace.go:171","msg":"trace[1289018574] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"657.74812ms","start":"2026-04-09T05:59:11.309633Z","end":"2026-04-09T05:59:11.967381Z","steps":["trace[1289018574] 'process raft request' (duration: 657.381971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:11.967539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.309603Z","time spent":"657.869083ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:11.968044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.160226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-09T05:59:11.96811Z","caller":"traceutil/trace.go:171","msg":"trace[887650868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"826.249048ms","start":"2026-04-09T05:59:11.141849Z","end":"2026-04-09T05:59:11.968098Z","steps":["trace[887650868] 'agreement among raft nodes before linearized reading' (duration: 825.590962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:11.968152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.141841Z","time spent":"826.30048ms","remote":"127.0.0.1:50664","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-04-09T05:59:12.184583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.076903ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T05:59:12.184847Z","caller":"traceutil/trace.go:171","msg":"trace[1357327320] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"213.564429ms","start":"2026-04-09T05:59:11.971263Z","end":"2026-04-09T05:59:12.184828Z","steps":["trace[1357327320] 'process raft request' (duration: 213.420935ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:12.184877Z","caller":"traceutil/trace.go:171","msg":"trace[130063772] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"217.688459ms","start":"2026-04-09T05:59:11.967151Z","end":"2026-04-09T05:59:12.18484Z","steps":["trace[130063772] 'read index received' (duration: 105.35561ms)","trace[130063772] 'applied index is now lower than readState.Index' (duration: 112.328419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:12.185127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031999406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T05:59:12.185187Z","caller":"traceutil/trace.go:171","msg":"trace[959354148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"1.032095639s","start":"2026-04-09T05:59:11.153075Z","end":"2026-04-09T05:59:12.18517Z","steps":["trace[959354148] 'agreement among raft nodes before linearized reading' (duration: 1.031887534s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:12.185174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.03656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:59:12.18524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.153063Z","time spent":"1.03216335s","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T05:59:12.185308Z","caller":"traceutil/trace.go:171","msg":"trace[1133220753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"800.240855ms","start":"2026-04-09T05:59:11.385017Z","end":"2026-04-09T05:59:12.185257Z","steps":["trace[1133220753] 'agreement among raft nodes before linearized reading' (duration: 800.117192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:12.185426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.38499Z","time spent":"800.359367ms","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T05:59:12.185535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.970409ms","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-04-09T05:59:12.185653Z","caller":"traceutil/trace.go:171","msg":"trace[306141588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"677.143743ms","start":"2026-04-09T05:59:11.508481Z","end":"2026-04-09T05:59:12.185625Z","steps":["trace[306141588] 'agreement among raft nodes before linearized reading' (duration: 676.388795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:12.185706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.512699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:59:12.185799Z","caller":"traceutil/trace.go:171","msg":"trace[1198371561] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1265; }","duration":"412.638692ms","start":"2026-04-09T05:59:11.773147Z","end":"2026-04-09T05:59:12.185786Z","steps":["trace[1198371561] 'agreement among raft nodes before linearized reading' (duration: 412.490289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:12.185843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.773132Z","time spent":"412.699414ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:59:12.185711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:11.508459Z","time spent":"677.241914ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T05:59:24.091192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.851963ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:59:24.091327Z","caller":"traceutil/trace.go:171","msg":"trace[1147477521] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1343; }","duration":"452.996787ms","start":"2026-04-09T05:59:23.63831Z","end":"2026-04-09T05:59:24.091306Z","steps":["trace[1147477521] 'range keys from in-memory index tree' (duration: 452.831042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:24.091375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.184002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:24.09242Z","caller":"traceutil/trace.go:171","msg":"trace[416091095] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"607.500244ms","start":"2026-04-09T05:59:23.484891Z","end":"2026-04-09T05:59:24.092391Z","steps":["trace[416091095] 'process raft request' (duration: 406.223666ms)","trace[416091095] 'compare' (duration: 199.774282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:59:24.092673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:23.484868Z","time spent":"607.613546ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:46.537888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.507862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:46.538218Z","caller":"traceutil/trace.go:171","msg":"trace[676631017] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"139.066702ms","start":"2026-04-09T05:59:46.399127Z","end":"2026-04-09T05:59:46.538193Z","steps":["trace[676631017] 'process raft request' (duration: 138.97829ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:46.538203Z","caller":"traceutil/trace.go:171","msg":"trace[1650281969] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"143.836638ms","start":"2026-04-09T05:59:46.394305Z","end":"2026-04-09T05:59:46.538141Z","steps":["trace[1650281969] 'process raft request' (duration: 35.907396ms)","trace[1650281969] 'compare' (duration: 107.247955ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:46.53839Z","caller":"traceutil/trace.go:171","msg":"trace[301862604] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"139.312457ms","start":"2026-04-09T05:59:46.399042Z","end":"2026-04-09T05:59:46.538354Z","steps":["trace[301862604] 'process raft request' (duration: 138.99067ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:46.53845Z","caller":"traceutil/trace.go:171","msg":"trace[832297027] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"139.208105ms","start":"2026-04-09T05:59:46.399216Z","end":"2026-04-09T05:59:46.538424Z","steps":["trace[832297027] 'process raft request' (duration: 138.930309ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:06.117227Z","caller":"traceutil/trace.go:171","msg":"trace[1620618117] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"139.917259ms","start":"2026-04-09T06:00:05.977291Z","end":"2026-04-09T06:00:06.117208Z","steps":["trace[1620618117] 'process raft request' (duration: 139.824537ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:24.842597Z","caller":"traceutil/trace.go:171","msg":"trace[17057192] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"108.902833ms","start":"2026-04-09T06:00:24.733653Z","end":"2026-04-09T06:00:24.842556Z","steps":["trace[17057192] 'process raft request' (duration: 108.665127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:10.089648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366889101756607,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T06:02:10.145448Z","caller":"traceutil/trace.go:171","msg":"trace[720386173] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"556.107067ms","start":"2026-04-09T06:02:09.589309Z","end":"2026-04-09T06:02:10.145416Z","steps":["trace[720386173] 'read index received' (duration: 555.80564ms)","trace[720386173] 'applied index is now lower than readState.Index' (duration: 300.037µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:10.145547Z","caller":"traceutil/trace.go:171","msg":"trace[1154718003] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"615.249994ms","start":"2026-04-09T06:02:09.530262Z","end":"2026-04-09T06:02:10.145512Z","steps":["trace[1154718003] 'process raft request' (duration: 614.926067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:10.145699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.805962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T06:02:10.145854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.447645ms","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-04-09T06:02:10.145936Z","caller":"traceutil/trace.go:171","msg":"trace[1899035421] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2105; }","duration":"494.14957ms","start":"2026-04-09T06:02:09.651767Z","end":"2026-04-09T06:02:10.145917Z","steps":["trace[1899035421] 'agreement among raft nodes before linearized reading' (duration: 493.783791ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:10.145954Z","caller":"traceutil/trace.go:171","msg":"trace[1343540379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2105; }","duration":"556.709932ms","start":"2026-04-09T06:02:09.589226Z","end":"2026-04-09T06:02:10.145936Z","steps":["trace[1343540379] 'agreement among raft nodes before linearized reading' (duration: 556.325732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:10.145992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.651745Z","time spent":"494.227573ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-09T06:02:10.146011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.589197Z","time spent":"556.803973ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T06:02:10.145921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.530238Z","time spent":"615.380608ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:10.286426Z","caller":"traceutil/trace.go:171","msg":"trace[605533871] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"135.512113ms","start":"2026-04-09T06:02:10.150892Z","end":"2026-04-09T06:02:10.286405Z","steps":["trace[605533871] 'process raft request' (duration: 133.765871ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:32.499387Z","caller":"traceutil/trace.go:171","msg":"trace[1732755153] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2327; }","duration":"113.297043ms","start":"2026-04-09T06:02:32.386067Z","end":"2026-04-09T06:02:32.499364Z","steps":["trace[1732755153] 'read index received' (duration: 113.056547ms)","trace[1732755153] 'applied index is now lower than readState.Index' (duration: 239.586µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:32.499508Z","caller":"traceutil/trace.go:171","msg":"trace[1814390220] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"254.391382ms","start":"2026-04-09T06:02:32.245107Z","end":"2026-04-09T06:02:32.499498Z","steps":["trace[1814390220] 'process raft request' (duration: 254.091965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:32.499666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.591321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:32.499694Z","caller":"traceutil/trace.go:171","msg":"trace[466829799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2237; }","duration":"113.700444ms","start":"2026-04-09T06:02:32.385985Z","end":"2026-04-09T06:02:32.499685Z","steps":["trace[466829799] 'agreement among raft nodes before linearized reading' (duration: 113.638212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:32.499999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.766864ms","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-04-09T06:02:32.500108Z","caller":"traceutil/trace.go:171","msg":"trace[1850933135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2237; }","duration":"108.899767ms","start":"2026-04-09T06:02:32.391179Z","end":"2026-04-09T06:02:32.500079Z","steps":["trace[1850933135] 'agreement among raft nodes before linearized reading' (duration: 108.681282ms)"],"step_count":1}