{"level":"warn","ts":"2026-05-07T23:26:21.076274Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:26:21.076362Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.140:2380","--initial-cluster=instance=https://199.19.213.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T23:26:21.076436Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:26:21.076449Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-05-07T23:26:21.076469Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T23:26:21.077126Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"]} {"level":"info","ts":"2026-05-07T23:26:21.0772Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T23:26:21.082383Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.982435ms"} {"level":"info","ts":"2026-05-07T23:26:21.089385Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6366444c8cd268d","cluster-id":"b92fe020fa22927c"} {"level":"info","ts":"2026-05-07T23:26:21.089445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:26:21.089461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 0"} {"level":"info","ts":"2026-05-07T23:26:21.08947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6366444c8cd268d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:26:21.089488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 1"} {"level":"info","ts":"2026-05-07T23:26:21.089517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"warn","ts":"2026-05-07T23:26:21.105757Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:26:21.108539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:26:21.110297Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T23:26:21.112461Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6366444c8cd268d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:26:21.115619Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6366444c8cd268d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:26:21.115994Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:26:21.116105Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:26:21.116134Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:26:21.118823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"info","ts":"2026-05-07T23:26:21.118955Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","added-peer-id":"b6366444c8cd268d","added-peer-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-05-07T23:26:21.120136Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T23:26:21.120209Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-05-07T23:26:21.120251Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-05-07T23:26:21.120448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6366444c8cd268d","initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:26:21.120492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:26:21.290604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:26:21.29069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:26:21.290732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgPreVoteResp from b6366444c8cd268d at term 1"} {"level":"info","ts":"2026-05-07T23:26:21.290759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:26:21.290788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgVoteResp from b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-05-07T23:26:21.290816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became leader at term 2"} {"level":"info","ts":"2026-05-07T23:26:21.290829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6366444c8cd268d elected leader b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-05-07T23:26:21.292073Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:26:21.293009Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6366444c8cd268d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.140:2379]}","request-path":"/0/members/b6366444c8cd268d/attributes","cluster-id":"b92fe020fa22927c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:26:21.293236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:26:21.293263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:26:21.293363Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:26:21.293525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:26:21.293573Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:26:21.293798Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:26:21.293591Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:26:21.2959Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:26:21.297277Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.140:2379"} {"level":"warn","ts":"2026-05-07T23:26:27.926275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.314966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:26:27.926396Z","caller":"traceutil/trace.go:171","msg":"trace[1366104926] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"875.584193ms","start":"2026-05-07T23:26:27.050797Z","end":"2026-05-07T23:26:27.926381Z","steps":["trace[1366104926] 'read index received' (duration: 253.472359ms)","trace[1366104926] 'applied index is now lower than readState.Index' (duration: 622.110504ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:27.926438Z","caller":"traceutil/trace.go:171","msg":"trace[1202442648] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"882.132915ms","start":"2026-05-07T23:26:27.044262Z","end":"2026-05-07T23:26:27.926395Z","steps":["trace[1202442648] 'process raft request' (duration: 260.113343ms)","trace[1202442648] 'compare' (duration: 621.04494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:27.926495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.694045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-07T23:26:27.926521Z","caller":"traceutil/trace.go:171","msg":"trace[1612440370] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:265; }","duration":"875.749187ms","start":"2026-05-07T23:26:27.050763Z","end":"2026-05-07T23:26:27.926512Z","steps":["trace[1612440370] 'agreement among raft nodes before linearized reading' (duration: 875.684006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:27.926546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.050747Z","time spent":"875.791678ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-07T23:26:27.926532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.044235Z","time spent":"882.251218ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:28.457415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.703486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-07T23:26:28.457516Z","caller":"traceutil/trace.go:171","msg":"trace[988492376] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"889.273351ms","start":"2026-05-07T23:26:27.568229Z","end":"2026-05-07T23:26:28.457502Z","steps":["trace[988492376] 'process raft request' (duration: 653.433541ms)","trace[988492376] 'compare' (duration: 235.651625ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:28.457556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.568196Z","time spent":"889.344953ms","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:29.211574Z","caller":"traceutil/trace.go:171","msg":"trace[87658673] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"1.643008423s","start":"2026-05-07T23:26:27.568543Z","end":"2026-05-07T23:26:29.211551Z","steps":["trace[87658673] 'process raft request' (duration: 1.642832769s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.211706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.568525Z","time spent":"1.643124225s","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:29.211733Z","caller":"traceutil/trace.go:171","msg":"trace[702569100] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"1.642645514s","start":"2026-05-07T23:26:27.569047Z","end":"2026-05-07T23:26:29.211693Z","steps":["trace[702569100] 'process raft request' (duration: 1.64243683s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.211897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.569024Z","time spent":"1.642800929s","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:26:29.212306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351126981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:26:29.212351Z","caller":"traceutil/trace.go:171","msg":"trace[408384685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:266; }","duration":"1.351205243s","start":"2026-05-07T23:26:27.861134Z","end":"2026-05-07T23:26:29.212339Z","steps":["trace[408384685] 'agreement among raft nodes before linearized reading' (duration: 1.35105412s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:29.212411Z","caller":"traceutil/trace.go:171","msg":"trace[1009597209] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"1.282639481s","start":"2026-05-07T23:26:27.929759Z","end":"2026-05-07T23:26:29.212399Z","steps":["trace[1009597209] 'process raft request' (duration: 1.28174591s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.212465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767468935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:26:29.212488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707238626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T23:26:29.212495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.929741Z","time spent":"1.282722052s","remote":"127.0.0.1:43198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:29.212527Z","caller":"traceutil/trace.go:171","msg":"trace[1602133217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:266; }","duration":"1.767593067s","start":"2026-05-07T23:26:27.444919Z","end":"2026-05-07T23:26:29.212512Z","steps":["trace[1602133217] 'agreement among raft nodes before linearized reading' (duration: 1.767490245s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:29.212536Z","caller":"traceutil/trace.go:171","msg":"trace[403329676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:266; }","duration":"1.707334398s","start":"2026-05-07T23:26:27.505183Z","end":"2026-05-07T23:26:29.212518Z","steps":["trace[403329676] 'agreement among raft nodes before linearized reading' (duration: 1.707222375s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.212552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.444885Z","time spent":"1.767661179s","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:26:29.212562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.505152Z","time spent":"1.70740389s","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:26:29.212545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644956038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-07T23:26:29.212614Z","caller":"traceutil/trace.go:171","msg":"trace[62286737] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:266; }","duration":"1.64504779s","start":"2026-05-07T23:26:27.567549Z","end":"2026-05-07T23:26:29.212597Z","steps":["trace[62286737] 'agreement among raft nodes before linearized reading' (duration: 1.644884817s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.212673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.861112Z","time spent":"1.351262574s","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:26:29.212672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.567492Z","time spent":"1.645172053s","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-07T23:26:29.212683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88302378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:29.212792Z","caller":"traceutil/trace.go:171","msg":"trace[745317120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:266; }","duration":"1.883185223s","start":"2026-05-07T23:26:27.329576Z","end":"2026-05-07T23:26:29.212761Z","steps":["trace[745317120] 'agreement among raft nodes before linearized reading' (duration: 1.882976368s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.212845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.329546Z","time spent":"1.883290896s","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:26:29.212099Z","caller":"traceutil/trace.go:171","msg":"trace[1540291752] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:273; }","duration":"1.285662141s","start":"2026-05-07T23:26:27.926425Z","end":"2026-05-07T23:26:29.212087Z","steps":["trace[1540291752] 'read index received' (duration: 295.25838ms)","trace[1540291752] 'applied index is now lower than readState.Index' (duration: 990.402781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:29.213729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.054042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-07T23:26:29.213772Z","caller":"traceutil/trace.go:171","msg":"trace[1314167973] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:266; }","duration":"755.133634ms","start":"2026-05-07T23:26:28.458627Z","end":"2026-05-07T23:26:29.21376Z","steps":["trace[1314167973] 'agreement among raft nodes before linearized reading' (duration: 755.058983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.213802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:28.458614Z","time spent":"755.180955ms","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2944,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-05-07T23:26:52.419058Z","caller":"traceutil/trace.go:171","msg":"trace[863621777] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"416.025656ms","start":"2026-05-07T23:26:52.003006Z","end":"2026-05-07T23:26:52.419032Z","steps":["trace[863621777] 'process raft request' (duration: 415.900103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.419203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.002979Z","time spent":"416.162299ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.431277Z","caller":"traceutil/trace.go:171","msg":"trace[1275479365] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"418.77773ms","start":"2026-05-07T23:26:52.012469Z","end":"2026-05-07T23:26:52.431247Z","steps":["trace[1275479365] 'process raft request' (duration: 418.642407ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.431319Z","caller":"traceutil/trace.go:171","msg":"trace[26046127] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"415.482423ms","start":"2026-05-07T23:26:52.015822Z","end":"2026-05-07T23:26:52.431305Z","steps":["trace[26046127] 'process raft request' (duration: 415.379311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.432006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.015803Z","time spent":"416.127069ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.432002Z","caller":"traceutil/trace.go:171","msg":"trace[1684660422] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"412.164077ms","start":"2026-05-07T23:26:52.019799Z","end":"2026-05-07T23:26:52.431963Z","steps":["trace[1684660422] 'process raft request' (duration: 411.445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.432005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.01243Z","time spent":"419.477646ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:26:52.432171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.019783Z","time spent":"412.3105ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.432253Z","caller":"traceutil/trace.go:171","msg":"trace[1939908516] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"410.360145ms","start":"2026-05-07T23:26:52.021741Z","end":"2026-05-07T23:26:52.432101Z","steps":["trace[1939908516] 'process raft request' (duration: 409.537516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.432373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.021733Z","time spent":"410.58357ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.43431Z","caller":"traceutil/trace.go:171","msg":"trace[1257463743] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"412.352561ms","start":"2026-05-07T23:26:52.021776Z","end":"2026-05-07T23:26:52.434129Z","steps":["trace[1257463743] 'process raft request' (duration: 410.445297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.434397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.021772Z","time spent":"412.577996ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.435223Z","caller":"traceutil/trace.go:171","msg":"trace[544208412] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"413.321814ms","start":"2026-05-07T23:26:52.02183Z","end":"2026-05-07T23:26:52.435151Z","steps":["trace[544208412] 'process raft request' (duration: 412.101275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.435355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.021826Z","time spent":"413.481567ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.437174Z","caller":"traceutil/trace.go:171","msg":"trace[626228337] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"413.62411ms","start":"2026-05-07T23:26:52.021974Z","end":"2026-05-07T23:26:52.435598Z","steps":["trace[626228337] 'process raft request' (duration: 412.183017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.439361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.021968Z","time spent":"417.348647ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.438829Z","caller":"traceutil/trace.go:171","msg":"trace[131362009] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"404.849147ms","start":"2026-05-07T23:26:52.03074Z","end":"2026-05-07T23:26:52.435589Z","steps":["trace[131362009] 'process raft request' (duration: 404.758285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.439438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.03073Z","time spent":"408.694066ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:26:52.44225Z","caller":"traceutil/trace.go:171","msg":"trace[379028930] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"405.531082ms","start":"2026-05-07T23:26:52.036688Z","end":"2026-05-07T23:26:52.442219Z","steps":["trace[379028930] 'process raft request' (duration: 400.655519ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.442278Z","caller":"traceutil/trace.go:171","msg":"trace[1789968034] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"376.446057ms","start":"2026-05-07T23:26:52.065816Z","end":"2026-05-07T23:26:52.442262Z","steps":["trace[1789968034] 'process raft request' (duration: 376.292143ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.442311Z","caller":"traceutil/trace.go:171","msg":"trace[1563482090] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"256.95158ms","start":"2026-05-07T23:26:52.185336Z","end":"2026-05-07T23:26:52.442288Z","steps":["trace[1563482090] 'process raft request' (duration: 256.876919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.442356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.036667Z","time spent":"405.638105ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:26:52.442355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.065794Z","time spent":"376.510998ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:26:52.442903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.414913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:26:52.442944Z","caller":"traceutil/trace.go:171","msg":"trace[2120234328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"394.488095ms","start":"2026-05-07T23:26:52.048444Z","end":"2026-05-07T23:26:52.442932Z","steps":["trace[2120234328] 'agreement among raft nodes before linearized reading' (duration: 394.306521ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.445253Z","caller":"traceutil/trace.go:171","msg":"trace[2060352127] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:491; }","duration":"393.882981ms","start":"2026-05-07T23:26:52.048477Z","end":"2026-05-07T23:26:52.44236Z","steps":["trace[2060352127] 'read index received' (duration: 370.572289ms)","trace[2060352127] 'applied index is now lower than readState.Index' (duration: 23.306622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:52.445405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.048429Z","time spent":"396.963652ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:26:52.458921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.039838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:52.458989Z","caller":"traceutil/trace.go:171","msg":"trace[2062846681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"129.155221ms","start":"2026-05-07T23:26:52.329819Z","end":"2026-05-07T23:26:52.458975Z","steps":["trace[2062846681] 'agreement among raft nodes before linearized reading' (duration: 129.031488ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:53.6144Z","caller":"traceutil/trace.go:171","msg":"trace[918752786] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"281.009149ms","start":"2026-05-07T23:26:53.333365Z","end":"2026-05-07T23:26:53.614374Z","steps":["trace[918752786] 'read index received' (duration: 278.134512ms)","trace[918752786] 'applied index is now lower than readState.Index' (duration: 2.873517ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:53.615405Z","caller":"traceutil/trace.go:171","msg":"trace[1993861547] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"289.844474ms","start":"2026-05-07T23:26:53.325534Z","end":"2026-05-07T23:26:53.615378Z","steps":["trace[1993861547] 'process raft request' (duration: 286.295472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:53.615498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.106804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:53.615613Z","caller":"traceutil/trace.go:171","msg":"trace[2048284306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"282.199166ms","start":"2026-05-07T23:26:53.33336Z","end":"2026-05-07T23:26:53.615559Z","steps":["trace[2048284306] 'agreement among raft nodes before linearized reading' (duration: 282.027032ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:53.620611Z","caller":"traceutil/trace.go:171","msg":"trace[673381390] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"273.839922ms","start":"2026-05-07T23:26:53.346749Z","end":"2026-05-07T23:26:53.620588Z","steps":["trace[673381390] 'process raft request' (duration: 273.657188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:53.620737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.685335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T23:26:53.620783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.391646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:26:53.620803Z","caller":"traceutil/trace.go:171","msg":"trace[442214252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"275.783728ms","start":"2026-05-07T23:26:53.345002Z","end":"2026-05-07T23:26:53.620786Z","steps":["trace[442214252] 'agreement among raft nodes before linearized reading' (duration: 275.623024ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:53.620822Z","caller":"traceutil/trace.go:171","msg":"trace[1426655440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"268.445417ms","start":"2026-05-07T23:26:53.352367Z","end":"2026-05-07T23:26:53.620812Z","steps":["trace[1426655440] 'agreement among raft nodes before linearized reading' (duration: 268.370166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:53.620916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.85612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:26:53.620946Z","caller":"traceutil/trace.go:171","msg":"trace[2077294480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"269.920381ms","start":"2026-05-07T23:26:53.351018Z","end":"2026-05-07T23:26:53.620938Z","steps":["trace[2077294480] 'agreement among raft nodes before linearized reading' (duration: 269.87439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.006593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.857832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qxzkr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T23:26:59.006692Z","caller":"traceutil/trace.go:171","msg":"trace[1297312190] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qxzkr; range_end:; response_count:1; response_revision:583; }","duration":"350.989085ms","start":"2026-05-07T23:26:58.655678Z","end":"2026-05-07T23:26:59.006667Z","steps":["trace[1297312190] 'range keys from in-memory index tree' (duration: 350.76951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.007302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:58.655632Z","time spent":"351.102978ms","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-qxzkr\" "} {"level":"info","ts":"2026-05-07T23:26:59.658076Z","caller":"traceutil/trace.go:171","msg":"trace[1714305420] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"718.579486ms","start":"2026-05-07T23:26:58.939475Z","end":"2026-05-07T23:26:59.658054Z","steps":["trace[1714305420] 'process raft request' (duration: 717.731286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.658383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:58.939444Z","time spent":"718.699609ms","remote":"127.0.0.1:43108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:26:59.860656Z","caller":"traceutil/trace.go:171","msg":"trace[1237102602] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"210.872339ms","start":"2026-05-07T23:26:59.649757Z","end":"2026-05-07T23:26:59.860629Z","steps":["trace[1237102602] 'read index received' (duration: 7.176357ms)","trace[1237102602] 'applied index is now lower than readState.Index' (duration: 203.694962ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:59.860779Z","caller":"traceutil/trace.go:171","msg":"trace[862869720] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"839.953006ms","start":"2026-05-07T23:26:59.020816Z","end":"2026-05-07T23:26:59.860769Z","steps":["trace[862869720] 'process raft request' (duration: 839.646999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.860978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.020787Z","time spent":"840.095398ms","remote":"127.0.0.1:43182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:59.861156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.378781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:26:59.861251Z","caller":"traceutil/trace.go:171","msg":"trace[719438690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"211.546375ms","start":"2026-05-07T23:26:59.649681Z","end":"2026-05-07T23:26:59.861228Z","steps":["trace[719438690] 'agreement among raft nodes before linearized reading' (duration: 211.249918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:00.36114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454313446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:27:00.861927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454313446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:27:01.25031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.389833402s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T23:27:01.473401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.794257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:01.473689Z","caller":"traceutil/trace.go:171","msg":"trace[1226256493] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.752413136s","start":"2026-05-07T23:26:59.721222Z","end":"2026-05-07T23:27:01.473635Z","steps":["trace[1226256493] 'process raft request' (duration: 1.529337854s)","trace[1226256493] 'compare' (duration: 222.424207ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:01.473736Z","caller":"traceutil/trace.go:171","msg":"trace[1764069978] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.607910549s","start":"2026-05-07T23:26:59.865778Z","end":"2026-05-07T23:27:01.473689Z","steps":["trace[1764069978] 'process raft request' (duration: 1.607708504s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.473822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.721198Z","time spent":"1.752565609s","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:27:01.473892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.865753Z","time spent":"1.608061512s","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:01.483923Z","caller":"traceutil/trace.go:171","msg":"trace[1275577220] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:606; }","duration":"1.623164823s","start":"2026-05-07T23:26:59.860733Z","end":"2026-05-07T23:27:01.483898Z","steps":["trace[1275577220] 'read index received' (duration: 1.389960065s)","trace[1275577220] 'applied index is now lower than readState.Index' (duration: 233.203518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:01.484108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833232993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:27:01.484155Z","caller":"traceutil/trace.go:171","msg":"trace[1451797336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"1.833281135s","start":"2026-05-07T23:26:59.650865Z","end":"2026-05-07T23:27:01.484146Z","steps":["trace[1451797336] 'agreement among raft nodes before linearized reading' (duration: 1.833172352s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.650783Z","time spent":"1.833389147s","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:27:01.484115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154017372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.484282Z","caller":"traceutil/trace.go:171","msg":"trace[1651730638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"1.154236748s","start":"2026-05-07T23:27:00.330038Z","end":"2026-05-07T23:27:01.484275Z","steps":["trace[1651730638] 'agreement among raft nodes before linearized reading' (duration: 1.154032003s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.48431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.330017Z","time spent":"1.154286129s","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:27:01.484428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.6791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.484438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.39424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.484476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.3545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.484485Z","caller":"traceutil/trace.go:171","msg":"trace[2010072111] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:587; }","duration":"300.457701ms","start":"2026-05-07T23:27:01.184012Z","end":"2026-05-07T23:27:01.48447Z","steps":["trace[2010072111] 'agreement among raft nodes before linearized reading' (duration: 300.381329ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.484504Z","caller":"traceutil/trace.go:171","msg":"trace[403663509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:587; }","duration":"449.380911ms","start":"2026-05-07T23:27:01.035113Z","end":"2026-05-07T23:27:01.484494Z","steps":["trace[403663509] 'agreement among raft nodes before linearized reading' (duration: 449.34032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.415134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.48452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.184006Z","time spent":"300.502822ms","remote":"127.0.0.1:46750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:27:01.484531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.035049Z","time spent":"449.476483ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:27:01.484544Z","caller":"traceutil/trace.go:171","msg":"trace[1493694914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:587; }","duration":"496.477095ms","start":"2026-05-07T23:27:00.988058Z","end":"2026-05-07T23:27:01.484536Z","steps":["trace[1493694914] 'agreement among raft nodes before linearized reading' (duration: 496.434064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.988039Z","time spent":"496.532566ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:27:01.484637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.981178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.484662Z","caller":"traceutil/trace.go:171","msg":"trace[660720340] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:587; }","duration":"386.016048ms","start":"2026-05-07T23:27:01.098639Z","end":"2026-05-07T23:27:01.484655Z","steps":["trace[660720340] 'agreement among raft nodes before linearized reading' (duration: 385.979478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.098636Z","time spent":"386.040669ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:27:01.484472Z","caller":"traceutil/trace.go:171","msg":"trace[1370104417] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:587; }","duration":"301.734921ms","start":"2026-05-07T23:27:01.182726Z","end":"2026-05-07T23:27:01.484461Z","steps":["trace[1370104417] 'agreement among raft nodes before linearized reading' (duration: 301.673739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.552505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.484784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.182722Z","time spent":"302.052248ms","remote":"127.0.0.1:46750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:27:01.484792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.14454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.484807Z","caller":"traceutil/trace.go:171","msg":"trace[667851840] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:587; }","duration":"449.638307ms","start":"2026-05-07T23:27:01.035158Z","end":"2026-05-07T23:27:01.484797Z","steps":["trace[667851840] 'agreement among raft nodes before linearized reading' (duration: 449.558525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.035127Z","time spent":"449.699718ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"info","ts":"2026-05-07T23:27:01.484859Z","caller":"traceutil/trace.go:171","msg":"trace[1997952600] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:587; }","duration":"497.235343ms","start":"2026-05-07T23:27:00.987614Z","end":"2026-05-07T23:27:01.484849Z","steps":["trace[1997952600] 'agreement among raft nodes before linearized reading' (duration: 497.157091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.052078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.484911Z","caller":"traceutil/trace.go:171","msg":"trace[1633670688] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:587; }","duration":"497.229453ms","start":"2026-05-07T23:27:00.987667Z","end":"2026-05-07T23:27:01.484896Z","steps":["trace[1633670688] 'agreement among raft nodes before linearized reading' (duration: 497.058749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.484917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.987601Z","time spent":"497.282324ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:27:01.484941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.987652Z","time spent":"497.281684ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:27:01.484856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.123144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.485025Z","caller":"traceutil/trace.go:171","msg":"trace[779023492] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:587; }","duration":"322.394931ms","start":"2026-05-07T23:27:01.162618Z","end":"2026-05-07T23:27:01.485012Z","steps":["trace[779023492] 'agreement among raft nodes before linearized reading' (duration: 322.121614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.446216ms","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":"warn","ts":"2026-05-07T23:27:01.485133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833984761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T23:27:01.485138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.550127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.485143Z","caller":"traceutil/trace.go:171","msg":"trace[1343369545] 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:587; }","duration":"524.516926ms","start":"2026-05-07T23:27:00.960616Z","end":"2026-05-07T23:27:01.485133Z","steps":["trace[1343369545] 'agreement among raft nodes before linearized reading' (duration: 524.470995ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.485158Z","caller":"traceutil/trace.go:171","msg":"trace[421062851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"1.834041453s","start":"2026-05-07T23:26:59.651108Z","end":"2026-05-07T23:27:01.485149Z","steps":["trace[421062851] 'agreement among raft nodes before linearized reading' (duration: 1.833021709s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.960586Z","time spent":"524.581178ms","remote":"127.0.0.1:46692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:27:01.485187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.651086Z","time spent":"1.834096854s","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:27:01.485112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.162602Z","time spent":"322.496283ms","remote":"127.0.0.1:46738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-07T23:27:01.485165Z","caller":"traceutil/trace.go:171","msg":"trace[752516439] 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:587; }","duration":"524.609399ms","start":"2026-05-07T23:27:00.960546Z","end":"2026-05-07T23:27:01.485155Z","steps":["trace[752516439] 'agreement among raft nodes before linearized reading' (duration: 524.571628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.269918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.485292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.960528Z","time spent":"524.755992ms","remote":"127.0.0.1:46692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:27:01.485066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.492763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.485471Z","caller":"traceutil/trace.go:171","msg":"trace[820097845] 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:587; }","duration":"524.043116ms","start":"2026-05-07T23:27:00.961421Z","end":"2026-05-07T23:27:01.485464Z","steps":["trace[820097845] 'agreement among raft nodes before linearized reading' (duration: 523.616626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.801407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.485501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.961395Z","time spent":"524.094647ms","remote":"127.0.0.1:46692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:27:01.48523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.397952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T23:27:01.48556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.844616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.485581Z","caller":"traceutil/trace.go:171","msg":"trace[1278500742] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:587; }","duration":"302.866637ms","start":"2026-05-07T23:27:01.182709Z","end":"2026-05-07T23:27:01.485576Z","steps":["trace[1278500742] 'agreement among raft nodes before linearized reading' (duration: 302.832576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.1827Z","time spent":"302.894197ms","remote":"127.0.0.1:46750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:27:01.485634Z","caller":"traceutil/trace.go:171","msg":"trace[1402198266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"953.780801ms","start":"2026-05-07T23:27:00.531784Z","end":"2026-05-07T23:27:01.485565Z","steps":["trace[1402198266] 'agreement among raft nodes before linearized reading' (duration: 953.393932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.48569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.516314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.48572Z","caller":"traceutil/trace.go:171","msg":"trace[357219460] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:587; }","duration":"245.615847ms","start":"2026-05-07T23:27:01.240095Z","end":"2026-05-07T23:27:01.485711Z","steps":["trace[357219460] 'agreement among raft nodes before linearized reading' (duration: 245.577996ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.485523Z","caller":"traceutil/trace.go:171","msg":"trace[1291164750] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:587; }","duration":"244.854169ms","start":"2026-05-07T23:27:01.240659Z","end":"2026-05-07T23:27:01.485513Z","steps":["trace[1291164750] 'agreement among raft nodes before linearized reading' (duration: 244.795358ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.485773Z","caller":"traceutil/trace.go:171","msg":"trace[295362945] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:587; }","duration":"385.20022ms","start":"2026-05-07T23:27:01.100508Z","end":"2026-05-07T23:27:01.485709Z","steps":["trace[295362945] 'agreement among raft nodes before linearized reading' (duration: 384.108654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.48577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:00.531764Z","time spent":"953.917383ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:27:01.485873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.771521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.4859Z","caller":"traceutil/trace.go:171","msg":"trace[327038040] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:587; }","duration":"273.798751ms","start":"2026-05-07T23:27:01.212093Z","end":"2026-05-07T23:27:01.485892Z","steps":["trace[327038040] 'agreement among raft nodes before linearized reading' (duration: 273.76366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.100502Z","time spent":"385.355573ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:27:01.485991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.17033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.486016Z","caller":"traceutil/trace.go:171","msg":"trace[453397674] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:587; }","duration":"274.218451ms","start":"2026-05-07T23:27:01.211791Z","end":"2026-05-07T23:27:01.486009Z","steps":["trace[453397674] 'agreement among raft nodes before linearized reading' (duration: 274.17272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.703089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.486213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.0444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.486238Z","caller":"traceutil/trace.go:171","msg":"trace[1797479157] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:587; }","duration":"179.082881ms","start":"2026-05-07T23:27:01.307149Z","end":"2026-05-07T23:27:01.486232Z","steps":["trace[1797479157] 'agreement among raft nodes before linearized reading' (duration: 179.04847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.486276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.161634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.486315Z","caller":"traceutil/trace.go:171","msg":"trace[931258547] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:587; }","duration":"201.237796ms","start":"2026-05-07T23:27:01.285065Z","end":"2026-05-07T23:27:01.486303Z","steps":["trace[931258547] 'agreement among raft nodes before linearized reading' (duration: 201.186214ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.486214Z","caller":"traceutil/trace.go:171","msg":"trace[1070362711] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:587; }","duration":"274.283432ms","start":"2026-05-07T23:27:01.211914Z","end":"2026-05-07T23:27:01.486198Z","steps":["trace[1070362711] 'agreement among raft nodes before linearized reading' (duration: 273.716019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.486338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.46077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:01.486482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.221365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.486514Z","caller":"traceutil/trace.go:171","msg":"trace[1765463040] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:587; }","duration":"201.274466ms","start":"2026-05-07T23:27:01.285231Z","end":"2026-05-07T23:27:01.486506Z","steps":["trace[1765463040] 'agreement among raft nodes before linearized reading' (duration: 201.222835ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.486463Z","caller":"traceutil/trace.go:171","msg":"trace[1353279610] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:587; }","duration":"201.587693ms","start":"2026-05-07T23:27:01.284865Z","end":"2026-05-07T23:27:01.486452Z","steps":["trace[1353279610] 'agreement among raft nodes before linearized reading' (duration: 201.44241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.485842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.792371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.487701Z","caller":"traceutil/trace.go:171","msg":"trace[2081430634] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:587; }","duration":"247.665984ms","start":"2026-05-07T23:27:01.24002Z","end":"2026-05-07T23:27:01.487686Z","steps":["trace[2081430634] 'agreement among raft nodes before linearized reading' (duration: 245.803111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.486148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.739031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.488159Z","caller":"traceutil/trace.go:171","msg":"trace[1311550305] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:587; }","duration":"328.739777ms","start":"2026-05-07T23:27:01.159401Z","end":"2026-05-07T23:27:01.488141Z","steps":["trace[1311550305] 'agreement among raft nodes before linearized reading' (duration: 326.727711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.488274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.159387Z","time spent":"328.870111ms","remote":"127.0.0.1:46738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:27:01.484975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.342827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.488583Z","caller":"traceutil/trace.go:171","msg":"trace[2029718949] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:587; }","duration":"389.94169ms","start":"2026-05-07T23:27:01.098625Z","end":"2026-05-07T23:27:01.488566Z","steps":["trace[2029718949] 'agreement among raft nodes before linearized reading' (duration: 386.330946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.488667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.09859Z","time spent":"390.062163ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:27:01.486174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.67656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.488986Z","caller":"traceutil/trace.go:171","msg":"trace[357713402] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:587; }","duration":"329.488836ms","start":"2026-05-07T23:27:01.159477Z","end":"2026-05-07T23:27:01.488966Z","steps":["trace[357713402] 'agreement among raft nodes before linearized reading' (duration: 326.68381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.48911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:01.159473Z","time spent":"329.621359ms","remote":"127.0.0.1:46738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:27:01.4895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.75648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.489729Z","caller":"traceutil/trace.go:171","msg":"trace[793143636] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:587; }","duration":"181.001815ms","start":"2026-05-07T23:27:01.308718Z","end":"2026-05-07T23:27:01.48972Z","steps":["trace[793143636] 'agreement among raft nodes before linearized reading' (duration: 180.750989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.489857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.439739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:01.489914Z","caller":"traceutil/trace.go:171","msg":"trace[990004936] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:587; }","duration":"182.49578ms","start":"2026-05-07T23:27:01.307402Z","end":"2026-05-07T23:27:01.489898Z","steps":["trace[990004936] 'agreement among raft nodes before linearized reading' (duration: 182.412778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.251896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.657119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:03.251988Z","caller":"traceutil/trace.go:171","msg":"trace[597473107] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:624; }","duration":"332.809782ms","start":"2026-05-07T23:27:02.919158Z","end":"2026-05-07T23:27:03.251968Z","steps":["trace[597473107] 'range keys from in-memory index tree' (duration: 332.572587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.252014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.823023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:03.252225Z","caller":"traceutil/trace.go:171","msg":"trace[398311194] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:624; }","duration":"132.054758ms","start":"2026-05-07T23:27:03.120126Z","end":"2026-05-07T23:27:03.252181Z","steps":["trace[398311194] 'range keys from in-memory index tree' (duration: 131.70219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.252035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:02.919134Z","time spent":"332.891555ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-05-07T23:27:03.504751Z","caller":"traceutil/trace.go:171","msg":"trace[1423830188] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"107.928387ms","start":"2026-05-07T23:27:03.396808Z","end":"2026-05-07T23:27:03.504737Z","steps":["trace[1423830188] 'process raft request' (duration: 107.782274ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.629585Z","caller":"traceutil/trace.go:171","msg":"trace[382340335] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"120.427328ms","start":"2026-05-07T23:27:03.509121Z","end":"2026-05-07T23:27:03.629548Z","steps":["trace[382340335] 'process raft request' (duration: 99.930672ms)","trace[382340335] 'compare' (duration: 20.195209ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:03.629947Z","caller":"traceutil/trace.go:171","msg":"trace[2036051344] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"120.683344ms","start":"2026-05-07T23:27:03.509231Z","end":"2026-05-07T23:27:03.629915Z","steps":["trace[2036051344] 'process raft request' (duration: 120.154061ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.630222Z","caller":"traceutil/trace.go:171","msg":"trace[71496314] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"120.872958ms","start":"2026-05-07T23:27:03.509332Z","end":"2026-05-07T23:27:03.630205Z","steps":["trace[71496314] 'process raft request' (duration: 120.667493ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.630273Z","caller":"traceutil/trace.go:171","msg":"trace[1662232736] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"111.275436ms","start":"2026-05-07T23:27:03.518984Z","end":"2026-05-07T23:27:03.630259Z","steps":["trace[1662232736] 'read index received' (duration: 89.978461ms)","trace[1662232736] 'applied index is now lower than readState.Index' (duration: 21.296095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:03.630416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.419029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:03.630452Z","caller":"traceutil/trace.go:171","msg":"trace[203845991] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"111.494821ms","start":"2026-05-07T23:27:03.518946Z","end":"2026-05-07T23:27:03.63044Z","steps":["trace[203845991] 'agreement among raft nodes before linearized reading' (duration: 111.360528ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.630488Z","caller":"traceutil/trace.go:171","msg":"trace[396393076] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"121.050252ms","start":"2026-05-07T23:27:03.509422Z","end":"2026-05-07T23:27:03.630472Z","steps":["trace[396393076] 'process raft request' (duration: 120.720095ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:06.099197Z","caller":"traceutil/trace.go:171","msg":"trace[4471564] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"148.815007ms","start":"2026-05-07T23:27:05.950357Z","end":"2026-05-07T23:27:06.099172Z","steps":["trace[4471564] 'process raft request' (duration: 148.556781ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:06.892892Z","caller":"traceutil/trace.go:171","msg":"trace[1610257011] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"138.904848ms","start":"2026-05-07T23:27:06.753939Z","end":"2026-05-07T23:27:06.892844Z","steps":["trace[1610257011] 'read index received' (duration: 134.654169ms)","trace[1610257011] 'applied index is now lower than readState.Index' (duration: 4.248899ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:06.892975Z","caller":"traceutil/trace.go:171","msg":"trace[1053379333] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"165.906945ms","start":"2026-05-07T23:27:06.727032Z","end":"2026-05-07T23:27:06.892939Z","steps":["trace[1053379333] 'process raft request' (duration: 161.640436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:06.893229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.04219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:27:06.893279Z","caller":"traceutil/trace.go:171","msg":"trace[1950751732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"148.119712ms","start":"2026-05-07T23:27:06.745146Z","end":"2026-05-07T23:27:06.893265Z","steps":["trace[1950751732] 'agreement among raft nodes before linearized reading' (duration: 147.855625ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:11.674051Z","caller":"traceutil/trace.go:171","msg":"trace[970305464] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"112.081592ms","start":"2026-05-07T23:27:11.561948Z","end":"2026-05-07T23:27:11.67403Z","steps":["trace[970305464] 'process raft request' (duration: 111.929108ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:12.696293Z","caller":"traceutil/trace.go:171","msg":"trace[1366541809] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"191.731535ms","start":"2026-05-07T23:27:12.504538Z","end":"2026-05-07T23:27:12.696269Z","steps":["trace[1366541809] 'process raft request' (duration: 191.571971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:17.831945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454313794,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:27:18.154586Z","caller":"traceutil/trace.go:171","msg":"trace[843828913] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"910.429838ms","start":"2026-05-07T23:27:17.244123Z","end":"2026-05-07T23:27:18.154553Z","steps":["trace[843828913] 'process raft request' (duration: 910.151381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.154764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.244094Z","time spent":"910.581892ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:18.240238Z","caller":"traceutil/trace.go:171","msg":"trace[1623995083] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:777; }","duration":"908.80635ms","start":"2026-05-07T23:27:17.331389Z","end":"2026-05-07T23:27:18.240195Z","steps":["trace[1623995083] 'read index received' (duration: 822.921238ms)","trace[1623995083] 'applied index is now lower than readState.Index' (duration: 85.883802ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:18.240521Z","caller":"traceutil/trace.go:171","msg":"trace[1346516014] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"441.370651ms","start":"2026-05-07T23:27:17.799139Z","end":"2026-05-07T23:27:18.240509Z","steps":["trace[1346516014] 'process raft request' (duration: 436.748122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.240605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.799117Z","time spent":"441.434513ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:18.240961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.87247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T23:27:18.240981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.559397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:18.241107Z","caller":"traceutil/trace.go:171","msg":"trace[1773492105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"419.106035ms","start":"2026-05-07T23:27:17.82197Z","end":"2026-05-07T23:27:18.241076Z","steps":["trace[1773492105] 'agreement among raft nodes before linearized reading' (duration: 418.642824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.240865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.48465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T23:27:18.241164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.82195Z","time spent":"419.202157ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:27:18.241193Z","caller":"traceutil/trace.go:171","msg":"trace[25536286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"533.952452ms","start":"2026-05-07T23:27:17.707218Z","end":"2026-05-07T23:27:18.24117Z","steps":["trace[25536286] 'agreement among raft nodes before linearized reading' (duration: 533.389879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.241241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.707187Z","time spent":"534.044253ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:27:18.2409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.683034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:27:18.241324Z","caller":"traceutil/trace.go:171","msg":"trace[1214997956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"427.127204ms","start":"2026-05-07T23:27:17.814174Z","end":"2026-05-07T23:27:18.241301Z","steps":["trace[1214997956] 'agreement among raft nodes before linearized reading' (duration: 426.573711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.241367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.81416Z","time spent":"427.195996ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T23:27:18.241363Z","caller":"traceutil/trace.go:171","msg":"trace[162291622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"909.768332ms","start":"2026-05-07T23:27:17.331324Z","end":"2026-05-07T23:27:18.241092Z","steps":["trace[162291622] 'agreement among raft nodes before linearized reading' (duration: 909.410424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.241447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.33128Z","time spent":"910.148082ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:27:18.241825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.177001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:27:18.2419Z","caller":"traceutil/trace.go:171","msg":"trace[395613324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"505.270693ms","start":"2026-05-07T23:27:17.73661Z","end":"2026-05-07T23:27:18.241881Z","steps":["trace[395613324] 'agreement among raft nodes before linearized reading' (duration: 504.337301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:18.241938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:17.736534Z","time spent":"505.394126ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:27:41.25407Z","caller":"traceutil/trace.go:171","msg":"trace[849426502] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"125.420696ms","start":"2026-05-07T23:27:41.128617Z","end":"2026-05-07T23:27:41.254038Z","steps":["trace[849426502] 'process raft request' (duration: 124.961316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:52.745726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.00407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:52.745878Z","caller":"traceutil/trace.go:171","msg":"trace[661294809] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"171.879696ms","start":"2026-05-07T23:27:52.57397Z","end":"2026-05-07T23:27:52.745849Z","steps":["trace[661294809] 'read index received' (duration: 45.821µs)","trace[661294809] 'applied index is now lower than readState.Index' (duration: 171.831915ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:52.745908Z","caller":"traceutil/trace.go:171","msg":"trace[8190408] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"215.376845ms","start":"2026-05-07T23:27:52.530472Z","end":"2026-05-07T23:27:52.745849Z","steps":["trace[8190408] 'process raft request' (duration: 29.016706ms)","trace[8190408] 'compare' (duration: 185.792346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:52.746181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.189754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:27:52.746301Z","caller":"traceutil/trace.go:171","msg":"trace[203173114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"172.362358ms","start":"2026-05-07T23:27:52.57392Z","end":"2026-05-07T23:27:52.746282Z","steps":["trace[203173114] 'agreement among raft nodes before linearized reading' (duration: 172.049941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:52.746288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.235555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:27:52.74643Z","caller":"traceutil/trace.go:171","msg":"trace[865414206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"172.46128ms","start":"2026-05-07T23:27:52.573941Z","end":"2026-05-07T23:27:52.746402Z","steps":["trace[865414206] 'agreement among raft nodes before linearized reading' (duration: 171.966088ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:53.585298Z","caller":"traceutil/trace.go:171","msg":"trace[1055365182] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"203.049213ms","start":"2026-05-07T23:27:53.382223Z","end":"2026-05-07T23:27:53.585273Z","steps":["trace[1055365182] 'process raft request' (duration: 202.602633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:54.304786Z","caller":"traceutil/trace.go:171","msg":"trace[1460114857] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"240.280964ms","start":"2026-05-07T23:27:54.064484Z","end":"2026-05-07T23:27:54.304765Z","steps":["trace[1460114857] 'read index received' (duration: 240.12031ms)","trace[1460114857] 'applied index is now lower than readState.Index' (duration: 159.674µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:54.30484Z","caller":"traceutil/trace.go:171","msg":"trace[993477829] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"830.520698ms","start":"2026-05-07T23:27:53.474295Z","end":"2026-05-07T23:27:54.304816Z","steps":["trace[993477829] 'process raft request' (duration: 830.339263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:54.304913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.415237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:54.30494Z","caller":"traceutil/trace.go:171","msg":"trace[1197500004] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:968; }","duration":"240.507459ms","start":"2026-05-07T23:27:54.064426Z","end":"2026-05-07T23:27:54.304933Z","steps":["trace[1197500004] 'agreement among raft nodes before linearized reading' (duration: 240.421927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:54.305003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:53.474256Z","time spent":"830.650191ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:54.87543Z","caller":"traceutil/trace.go:171","msg":"trace[1698388223] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"165.948476ms","start":"2026-05-07T23:27:54.709459Z","end":"2026-05-07T23:27:54.875408Z","steps":["trace[1698388223] 'read index received' (duration: 165.823583ms)","trace[1698388223] 'applied index is now lower than readState.Index' (duration: 123.863µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:54.875646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.176041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:27:54.875694Z","caller":"traceutil/trace.go:171","msg":"trace[955358030] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:969; }","duration":"166.289534ms","start":"2026-05-07T23:27:54.709383Z","end":"2026-05-07T23:27:54.875672Z","steps":["trace[955358030] 'agreement among raft nodes before linearized reading' (duration: 166.153271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:54.875848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.928979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:27:54.876016Z","caller":"traceutil/trace.go:171","msg":"trace[788632303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"126.129004ms","start":"2026-05-07T23:27:54.74986Z","end":"2026-05-07T23:27:54.875989Z","steps":["trace[788632303] 'agreement among raft nodes before linearized reading' (duration: 125.790766ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:54.876164Z","caller":"traceutil/trace.go:171","msg":"trace[498421595] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"351.410574ms","start":"2026-05-07T23:27:54.524067Z","end":"2026-05-07T23:27:54.875477Z","steps":["trace[498421595] 'process raft request' (duration: 351.183248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:54.876321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:54.524031Z","time spent":"352.198142ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:55.468634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.419137ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-07T23:27:55.468736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:54.877613Z","time spent":"591.118924ms","remote":"127.0.0.1:43054","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T23:27:55.500818Z","caller":"traceutil/trace.go:171","msg":"trace[912173714] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"620.040027ms","start":"2026-05-07T23:27:54.880754Z","end":"2026-05-07T23:27:55.500794Z","steps":["trace[912173714] 'process raft request' (duration: 619.870503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:55.50103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:54.880728Z","time spent":"620.16119ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:55.501107Z","caller":"traceutil/trace.go:171","msg":"trace[277085752] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"620.145599ms","start":"2026-05-07T23:27:54.88092Z","end":"2026-05-07T23:27:55.501066Z","steps":["trace[277085752] 'process raft request' (duration: 619.838942ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:55.501162Z","caller":"traceutil/trace.go:171","msg":"trace[75812098] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"620.064418ms","start":"2026-05-07T23:27:54.881074Z","end":"2026-05-07T23:27:55.501139Z","steps":["trace[75812098] 'process raft request' (duration: 619.905004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:55.501232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.712461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:55.50127Z","caller":"traceutil/trace.go:171","msg":"trace[243912415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"171.789853ms","start":"2026-05-07T23:27:55.329471Z","end":"2026-05-07T23:27:55.501261Z","steps":["trace[243912415] 'agreement among raft nodes before linearized reading' (duration: 171.689961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:55.501265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:54.880912Z","time spent":"620.266073ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:55.501284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:54.88104Z","time spent":"620.15698ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:55.50112Z","caller":"traceutil/trace.go:171","msg":"trace[1289879863] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1007; }","duration":"171.583978ms","start":"2026-05-07T23:27:55.329517Z","end":"2026-05-07T23:27:55.501101Z","steps":["trace[1289879863] 'read index received' (duration: 171.117487ms)","trace[1289879863] 'applied index is now lower than readState.Index' (duration: 465.581µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:55.501389Z","caller":"traceutil/trace.go:171","msg":"trace[731442128] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"379.63702ms","start":"2026-05-07T23:27:55.121743Z","end":"2026-05-07T23:27:55.50138Z","steps":["trace[731442128] 'process raft request' (duration: 379.318242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:55.501464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:55.121714Z","time spent":"379.718792ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:56.003668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454314279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:27:56.201531Z","caller":"traceutil/trace.go:171","msg":"trace[51542572] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"698.556626ms","start":"2026-05-07T23:27:55.50295Z","end":"2026-05-07T23:27:56.201506Z","steps":["trace[51542572] 'read index received' (duration: 698.405902ms)","trace[51542572] 'applied index is now lower than readState.Index' (duration: 149.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:56.201548Z","caller":"traceutil/trace.go:171","msg":"trace[510741575] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"731.834393ms","start":"2026-05-07T23:27:55.469681Z","end":"2026-05-07T23:27:56.201515Z","steps":["trace[510741575] 'process raft request' (duration: 731.6995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:56.201739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:55.469658Z","time spent":"731.986367ms","remote":"127.0.0.1:43054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:56.201724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.74843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:27:56.201782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.997468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:27:56.201806Z","caller":"traceutil/trace.go:171","msg":"trace[12740560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"698.868753ms","start":"2026-05-07T23:27:55.502919Z","end":"2026-05-07T23:27:56.201787Z","steps":["trace[12740560] 'agreement among raft nodes before linearized reading' (duration: 698.687779ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:56.201817Z","caller":"traceutil/trace.go:171","msg":"trace[77938749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"612.08746ms","start":"2026-05-07T23:27:55.589719Z","end":"2026-05-07T23:27:56.201807Z","steps":["trace[77938749] 'agreement among raft nodes before linearized reading' (duration: 611.942976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:56.201881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:55.502905Z","time spent":"698.968245ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:27:56.201886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:55.589697Z","time spent":"612.181193ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T23:28:14.493503Z","caller":"traceutil/trace.go:171","msg":"trace[1084219790] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"118.79375ms","start":"2026-05-07T23:28:14.374675Z","end":"2026-05-07T23:28:14.493469Z","steps":["trace[1084219790] 'process raft request' (duration: 73.458558ms)","trace[1084219790] 'compare' (duration: 45.078286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:15.350993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.189106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:15.351058Z","caller":"traceutil/trace.go:171","msg":"trace[1040009003] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1036; }","duration":"149.316399ms","start":"2026-05-07T23:28:15.201725Z","end":"2026-05-07T23:28:15.351042Z","steps":["trace[1040009003] 'count revisions from in-memory index tree' (duration: 149.083044ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:15.974913Z","caller":"traceutil/trace.go:171","msg":"trace[1558291308] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"112.782111ms","start":"2026-05-07T23:28:15.862099Z","end":"2026-05-07T23:28:15.974881Z","steps":["trace[1558291308] 'read index received' (duration: 112.571346ms)","trace[1558291308] 'applied index is now lower than readState.Index' (duration: 208.835µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:15.97513Z","caller":"traceutil/trace.go:171","msg":"trace[1823692655] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"125.668621ms","start":"2026-05-07T23:28:15.849419Z","end":"2026-05-07T23:28:15.975088Z","steps":["trace[1823692655] 'process raft request' (duration: 125.23135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:15.975234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.105708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:28:15.975298Z","caller":"traceutil/trace.go:171","msg":"trace[152985745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"113.248081ms","start":"2026-05-07T23:28:15.862031Z","end":"2026-05-07T23:28:15.975279Z","steps":["trace[152985745] 'agreement among raft nodes before linearized reading' (duration: 112.978435ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:18.709771Z","caller":"traceutil/trace.go:171","msg":"trace[1714910964] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"311.597958ms","start":"2026-05-07T23:28:18.398146Z","end":"2026-05-07T23:28:18.709744Z","steps":["trace[1714910964] 'process raft request' (duration: 311.431804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:18.709986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:18.398114Z","time spent":"311.761991ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:19.175276Z","caller":"traceutil/trace.go:171","msg":"trace[457887024] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"287.081416ms","start":"2026-05-07T23:28:18.888171Z","end":"2026-05-07T23:28:19.175253Z","steps":["trace[457887024] 'read index received' (duration: 246.10794ms)","trace[457887024] 'applied index is now lower than readState.Index' (duration: 40.971936ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:19.175298Z","caller":"traceutil/trace.go:171","msg":"trace[1257385550] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"626.559893ms","start":"2026-05-07T23:28:18.548702Z","end":"2026-05-07T23:28:19.175262Z","steps":["trace[1257385550] 'process raft request' (duration: 585.645289ms)","trace[1257385550] 'compare' (duration: 40.617367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:19.175392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.209819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:19.175416Z","caller":"traceutil/trace.go:171","msg":"trace[905036270] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1048; }","duration":"287.285931ms","start":"2026-05-07T23:28:18.888122Z","end":"2026-05-07T23:28:19.175408Z","steps":["trace[905036270] 'agreement among raft nodes before linearized reading' (duration: 287.211749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:19.175452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:18.548678Z","time spent":"626.688587ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:20.515635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454314492,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:28:20.670121Z","caller":"traceutil/trace.go:171","msg":"trace[449198010] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"654.536824ms","start":"2026-05-07T23:28:20.015531Z","end":"2026-05-07T23:28:20.670068Z","steps":["trace[449198010] 'read index received' (duration: 654.159116ms)","trace[449198010] 'applied index is now lower than readState.Index' (duration: 376.208µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:20.670162Z","caller":"traceutil/trace.go:171","msg":"trace[2089320328] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"668.977062ms","start":"2026-05-07T23:28:20.001138Z","end":"2026-05-07T23:28:20.670115Z","steps":["trace[2089320328] 'process raft request' (duration: 668.678045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:20.670503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.936174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:28:20.670564Z","caller":"traceutil/trace.go:171","msg":"trace[195375583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"655.049797ms","start":"2026-05-07T23:28:20.015492Z","end":"2026-05-07T23:28:20.670542Z","steps":["trace[195375583] 'agreement among raft nodes before linearized reading' (duration: 654.809621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:20.670608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:20.015416Z","time spent":"655.18201ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:28:20.670569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.703078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:20.670681Z","caller":"traceutil/trace.go:171","msg":"trace[1869535005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"338.912683ms","start":"2026-05-07T23:28:20.331741Z","end":"2026-05-07T23:28:20.670654Z","steps":["trace[1869535005] 'agreement among raft nodes before linearized reading' (duration: 338.729069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:20.670731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:20.331696Z","time spent":"339.026326ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:28:20.670743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:20.001113Z","time spent":"669.252087ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:21.020188Z","caller":"traceutil/trace.go:171","msg":"trace[898676674] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"305.644399ms","start":"2026-05-07T23:28:20.714514Z","end":"2026-05-07T23:28:21.020158Z","steps":["trace[898676674] 'read index received' (duration: 282.752815ms)","trace[898676674] 'applied index is now lower than readState.Index' (duration: 22.889884ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:21.020326Z","caller":"traceutil/trace.go:171","msg":"trace[570049083] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"343.46229ms","start":"2026-05-07T23:28:20.6768Z","end":"2026-05-07T23:28:21.020262Z","steps":["trace[570049083] 'process raft request' (duration: 320.568746ms)","trace[570049083] 'compare' (duration: 21.801459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:21.020485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.940066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:28:21.020534Z","caller":"traceutil/trace.go:171","msg":"trace[603517861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"306.031278ms","start":"2026-05-07T23:28:20.714488Z","end":"2026-05-07T23:28:21.020519Z","steps":["trace[603517861] 'agreement among raft nodes before linearized reading' (duration: 305.820333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:21.020574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:20.71447Z","time spent":"306.095579ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T23:28:21.020588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:20.676774Z","time spent":"343.670726ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:22.452585Z","caller":"traceutil/trace.go:171","msg":"trace[286100380] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"121.938754ms","start":"2026-05-07T23:28:22.330621Z","end":"2026-05-07T23:28:22.45256Z","steps":["trace[286100380] 'read index received' (duration: 121.73604ms)","trace[286100380] 'applied index is now lower than readState.Index' (duration: 201.504µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:22.452616Z","caller":"traceutil/trace.go:171","msg":"trace[616119573] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"645.481204ms","start":"2026-05-07T23:28:21.80711Z","end":"2026-05-07T23:28:22.452591Z","steps":["trace[616119573] 'process raft request' (duration: 645.29397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:22.452722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.075398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:22.452758Z","caller":"traceutil/trace.go:171","msg":"trace[1738865173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"122.129979ms","start":"2026-05-07T23:28:22.330616Z","end":"2026-05-07T23:28:22.452746Z","steps":["trace[1738865173] 'agreement among raft nodes before linearized reading' (duration: 122.053407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:22.452767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:21.807079Z","time spent":"645.625438ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:23.064743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.926533ms","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-05-07T23:28:23.064836Z","caller":"traceutil/trace.go:171","msg":"trace[1464545948] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1058; }","duration":"194.108007ms","start":"2026-05-07T23:28:22.870708Z","end":"2026-05-07T23:28:23.064816Z","steps":["trace[1464545948] 'count revisions from in-memory index tree' (duration: 193.752949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:23.316778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.166739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:28:23.316891Z","caller":"traceutil/trace.go:171","msg":"trace[2065085850] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"183.411618ms","start":"2026-05-07T23:28:23.133459Z","end":"2026-05-07T23:28:23.31687Z","steps":["trace[2065085850] 'process raft request' (duration: 52.050674ms)","trace[2065085850] 'compare' (duration: 131.065107ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:23.601927Z","caller":"traceutil/trace.go:171","msg":"trace[1630676614] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"311.684209ms","start":"2026-05-07T23:28:23.290218Z","end":"2026-05-07T23:28:23.601902Z","steps":["trace[1630676614] 'read index received' (duration: 311.523996ms)","trace[1630676614] 'applied index is now lower than readState.Index' (duration: 159.413µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:23.601997Z","caller":"traceutil/trace.go:171","msg":"trace[814977915] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"317.819102ms","start":"2026-05-07T23:28:23.284162Z","end":"2026-05-07T23:28:23.601981Z","steps":["trace[814977915] 'process raft request' (duration: 317.603717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:23.602101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:23.28412Z","time spent":"317.918545ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:23.602162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.951725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T23:28:23.602222Z","caller":"traceutil/trace.go:171","msg":"trace[1161959934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"312.11833ms","start":"2026-05-07T23:28:23.29009Z","end":"2026-05-07T23:28:23.602209Z","steps":["trace[1161959934] 'agreement among raft nodes before linearized reading' (duration: 311.919345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:23.60229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:23.290058Z","time spent":"312.215652ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:28:32.614428Z","caller":"traceutil/trace.go:171","msg":"trace[724150844] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"100.185596ms","start":"2026-05-07T23:28:32.514217Z","end":"2026-05-07T23:28:32.614403Z","steps":["trace[724150844] 'process raft request' (duration: 100.033593ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:33.23379Z","caller":"traceutil/trace.go:171","msg":"trace[121354029] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"102.360238ms","start":"2026-05-07T23:28:33.131401Z","end":"2026-05-07T23:28:33.233761Z","steps":["trace[121354029] 'process raft request' (duration: 102.133482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.133967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454314726,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:28:37.242504Z","caller":"traceutil/trace.go:171","msg":"trace[180392240] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"609.308021ms","start":"2026-05-07T23:28:36.633172Z","end":"2026-05-07T23:28:37.24248Z","steps":["trace[180392240] 'read index received' (duration: 609.173377ms)","trace[180392240] 'applied index is now lower than readState.Index' (duration: 133.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:37.242612Z","caller":"traceutil/trace.go:171","msg":"trace[1881586566] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"906.296157ms","start":"2026-05-07T23:28:36.336293Z","end":"2026-05-07T23:28:37.242589Z","steps":["trace[1881586566] 'process raft request' (duration: 906.040141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.242723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.546897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T23:28:37.242741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:36.336273Z","time spent":"906.38906ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:37.242756Z","caller":"traceutil/trace.go:171","msg":"trace[1608136993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"609.628799ms","start":"2026-05-07T23:28:36.633117Z","end":"2026-05-07T23:28:37.242746Z","steps":["trace[1608136993] 'agreement among raft nodes before linearized reading' (duration: 609.487255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.242789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:36.633094Z","time spent":"609.68781ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:28:37.243043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.205845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:37.243089Z","caller":"traceutil/trace.go:171","msg":"trace[1147650051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1154; }","duration":"220.273747ms","start":"2026-05-07T23:28:37.022802Z","end":"2026-05-07T23:28:37.243075Z","steps":["trace[1147650051] 'agreement among raft nodes before linearized reading' (duration: 220.190276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.24315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.773152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:28:37.243262Z","caller":"traceutil/trace.go:171","msg":"trace[1702090179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1154; }","duration":"305.900755ms","start":"2026-05-07T23:28:36.937312Z","end":"2026-05-07T23:28:37.243212Z","steps":["trace[1702090179] 'agreement among raft nodes before linearized reading' (duration: 305.6974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.243306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:36.937292Z","time spent":"306.005427ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:28:37.243698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.459116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:37.243741Z","caller":"traceutil/trace.go:171","msg":"trace[1141471528] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1154; }","duration":"517.267345ms","start":"2026-05-07T23:28:36.726459Z","end":"2026-05-07T23:28:37.243727Z","steps":["trace[1141471528] 'agreement among raft nodes before linearized reading' (duration: 516.449016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.243781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:36.726437Z","time spent":"517.332126ms","remote":"127.0.0.1:46838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:28:37.637491Z","caller":"traceutil/trace.go:171","msg":"trace[1880039933] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"389.380211ms","start":"2026-05-07T23:28:37.248085Z","end":"2026-05-07T23:28:37.637465Z","steps":["trace[1880039933] 'process raft request' (duration: 389.255158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.637661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:37.248065Z","time spent":"389.496524ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:37.644183Z","caller":"traceutil/trace.go:171","msg":"trace[1380607300] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"314.985816ms","start":"2026-05-07T23:28:37.329178Z","end":"2026-05-07T23:28:37.644164Z","steps":["trace[1380607300] 'read index received' (duration: 308.27854ms)","trace[1380607300] 'applied index is now lower than readState.Index' (duration: 6.706616ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:37.644288Z","caller":"traceutil/trace.go:171","msg":"trace[1197916229] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"394.443529ms","start":"2026-05-07T23:28:37.249811Z","end":"2026-05-07T23:28:37.644255Z","steps":["trace[1197916229] 'process raft request' (duration: 394.266525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.644458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.722764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:28:37.644489Z","caller":"traceutil/trace.go:171","msg":"trace[1204099220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1156; }","duration":"306.763324ms","start":"2026-05-07T23:28:37.337718Z","end":"2026-05-07T23:28:37.644481Z","steps":["trace[1204099220] 'agreement among raft nodes before linearized reading' (duration: 306.635791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.644501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.579894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:28:37.644573Z","caller":"traceutil/trace.go:171","msg":"trace[1437111221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1156; }","duration":"244.714967ms","start":"2026-05-07T23:28:37.399844Z","end":"2026-05-07T23:28:37.644559Z","steps":["trace[1437111221] 'agreement among raft nodes before linearized reading' (duration: 244.546503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.644449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:37.249802Z","time spent":"394.559813ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:37.644534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:37.337709Z","time spent":"306.819586ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:28:37.644329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.128869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:37.645166Z","caller":"traceutil/trace.go:171","msg":"trace[1724876445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"316.02353ms","start":"2026-05-07T23:28:37.329131Z","end":"2026-05-07T23:28:37.645154Z","steps":["trace[1724876445] 'agreement among raft nodes before linearized reading' (duration: 315.15508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:37.645209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:37.329109Z","time spent":"316.089212ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:28:52.219017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778050288454314845,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:28:52.360312Z","caller":"traceutil/trace.go:171","msg":"trace[474647812] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"642.224499ms","start":"2026-05-07T23:28:51.718043Z","end":"2026-05-07T23:28:52.360267Z","steps":["trace[474647812] 'read index received' (duration: 641.898791ms)","trace[474647812] 'applied index is now lower than readState.Index' (duration: 323.028µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:52.360403Z","caller":"traceutil/trace.go:171","msg":"trace[831734747] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"665.471271ms","start":"2026-05-07T23:28:51.694888Z","end":"2026-05-07T23:28:52.360359Z","steps":["trace[831734747] 'process raft request' (duration: 665.074242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:52.360618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.558546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:28:52.360693Z","caller":"traceutil/trace.go:171","msg":"trace[237547172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"642.652748ms","start":"2026-05-07T23:28:51.718023Z","end":"2026-05-07T23:28:52.360676Z","steps":["trace[237547172] 'agreement among raft nodes before linearized reading' (duration: 642.426383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:52.360678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:51.694853Z","time spent":"665.691946ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:52.360743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:51.717969Z","time spent":"642.762981ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:28:52.360883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.642615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T23:28:52.360823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.060265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:28:52.361029Z","caller":"traceutil/trace.go:171","msg":"trace[1705969631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"537.323692ms","start":"2026-05-07T23:28:51.823686Z","end":"2026-05-07T23:28:52.361009Z","steps":["trace[1705969631] 'agreement among raft nodes before linearized reading' (duration: 536.932413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:52.361098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:51.823667Z","time spent":"537.416494ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:28:52.360972Z","caller":"traceutil/trace.go:171","msg":"trace[516669629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"635.790819ms","start":"2026-05-07T23:28:51.725165Z","end":"2026-05-07T23:28:52.360955Z","steps":["trace[516669629] 'agreement among raft nodes before linearized reading' (duration: 635.513182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:52.361176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:51.725141Z","time spent":"636.016593ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:28:52.361363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.466274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:28:52.36141Z","caller":"traceutil/trace.go:171","msg":"trace[1101196313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"621.550716ms","start":"2026-05-07T23:28:51.739843Z","end":"2026-05-07T23:28:52.361394Z","steps":["trace[1101196313] 'agreement among raft nodes before linearized reading' (duration: 620.811649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:52.361446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:51.739833Z","time spent":"621.605128ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T23:28:52.36171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.376846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:28:52.361777Z","caller":"traceutil/trace.go:171","msg":"trace[45539503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"637.451097ms","start":"2026-05-07T23:28:51.724297Z","end":"2026-05-07T23:28:52.361748Z","steps":["trace[45539503] 'agreement among raft nodes before linearized reading' (duration: 636.29719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:52.361818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:51.724283Z","time spent":"637.52724ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T23:28:54.508935Z","caller":"traceutil/trace.go:171","msg":"trace[456665591] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"126.247524ms","start":"2026-05-07T23:28:54.382665Z","end":"2026-05-07T23:28:54.508913Z","steps":["trace[456665591] 'read index received' (duration: 126.107591ms)","trace[456665591] 'applied index is now lower than readState.Index' (duration: 138.483µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:54.509167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.48077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:28:54.509192Z","caller":"traceutil/trace.go:171","msg":"trace[840454305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"126.575983ms","start":"2026-05-07T23:28:54.382609Z","end":"2026-05-07T23:28:54.509185Z","steps":["trace[840454305] 'agreement among raft nodes before linearized reading' (duration: 126.434969ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:54.509173Z","caller":"traceutil/trace.go:171","msg":"trace[1413246091] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"140.787933ms","start":"2026-05-07T23:28:54.368301Z","end":"2026-05-07T23:28:54.509089Z","steps":["trace[1413246091] 'process raft request' (duration: 140.474626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:54.50935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.217147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:28:54.509377Z","caller":"traceutil/trace.go:171","msg":"trace[1487015856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"124.264268ms","start":"2026-05-07T23:28:54.385106Z","end":"2026-05-07T23:28:54.50937Z","steps":["trace[1487015856] 'agreement among raft nodes before linearized reading' (duration: 124.183916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:54.509404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.070604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:28:54.509633Z","caller":"traceutil/trace.go:171","msg":"trace[1766046297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"124.295379ms","start":"2026-05-07T23:28:54.385322Z","end":"2026-05-07T23:28:54.509618Z","steps":["trace[1766046297] 'agreement among raft nodes before linearized reading' (duration: 124.010882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:54.509645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.582683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:28:54.509762Z","caller":"traceutil/trace.go:171","msg":"trace[1819159004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"126.930881ms","start":"2026-05-07T23:28:54.382806Z","end":"2026-05-07T23:28:54.509737Z","steps":["trace[1819159004] 'agreement among raft nodes before linearized reading' (duration: 126.350477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:54.510275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.769161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:28:54.510773Z","caller":"traceutil/trace.go:171","msg":"trace[690099720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"125.292492ms","start":"2026-05-07T23:28:54.38525Z","end":"2026-05-07T23:28:54.510542Z","steps":["trace[690099720] 'agreement among raft nodes before linearized reading' (duration: 124.036183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:54.633742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.550298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:28:54.63425Z","caller":"traceutil/trace.go:171","msg":"trace[180775673] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"119.917457ms","start":"2026-05-07T23:28:54.514307Z","end":"2026-05-07T23:28:54.634224Z","steps":["trace[180775673] 'process raft request' (duration: 17.615951ms)","trace[180775673] 'compare' (duration: 101.406975ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:54.63437Z","caller":"traceutil/trace.go:171","msg":"trace[1571196118] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"118.950974ms","start":"2026-05-07T23:28:54.5154Z","end":"2026-05-07T23:28:54.634351Z","steps":["trace[1571196118] 'process raft request' (duration: 118.698648ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:54.634535Z","caller":"traceutil/trace.go:171","msg":"trace[2050585057] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"116.628469ms","start":"2026-05-07T23:28:54.517883Z","end":"2026-05-07T23:28:54.634511Z","steps":["trace[2050585057] 'process raft request' (duration: 116.432705ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:54.634556Z","caller":"traceutil/trace.go:171","msg":"trace[1025460211] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"118.653107ms","start":"2026-05-07T23:28:54.515859Z","end":"2026-05-07T23:28:54.634512Z","steps":["trace[1025460211] 'process raft request' (duration: 118.397152ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:54.634199Z","caller":"traceutil/trace.go:171","msg":"trace[41977505] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"119.522578ms","start":"2026-05-07T23:28:54.514653Z","end":"2026-05-07T23:28:54.634175Z","steps":["trace[41977505] 'process raft request' (duration: 119.20757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:56.645786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.964335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:28:56.645877Z","caller":"traceutil/trace.go:171","msg":"trace[37057987] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"149.784343ms","start":"2026-05-07T23:28:56.49608Z","end":"2026-05-07T23:28:56.645864Z","steps":["trace[37057987] 'read index received' (duration: 679.285µs)","trace[37057987] 'applied index is now lower than readState.Index' (duration: 149.104308ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:56.645977Z","caller":"traceutil/trace.go:171","msg":"trace[2130486985] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"179.149478ms","start":"2026-05-07T23:28:56.466782Z","end":"2026-05-07T23:28:56.645932Z","steps":["trace[2130486985] 'process raft request' (duration: 29.976388ms)","trace[2130486985] 'compare' (duration: 148.872483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:56.646183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.884636ms","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":"info","ts":"2026-05-07T23:28:56.646216Z","caller":"traceutil/trace.go:171","msg":"trace[507933512] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1236; }","duration":"150.184153ms","start":"2026-05-07T23:28:56.496022Z","end":"2026-05-07T23:28:56.646207Z","steps":["trace[507933512] 'agreement among raft nodes before linearized reading' (duration: 149.895967ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:56.770996Z","caller":"traceutil/trace.go:171","msg":"trace[916885758] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"125.063617ms","start":"2026-05-07T23:28:56.645896Z","end":"2026-05-07T23:28:56.77096Z","steps":["trace[916885758] 'read index received' (duration: 122.390095ms)","trace[916885758] 'applied index is now lower than readState.Index' (duration: 2.671732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:56.771212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.171826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:28:56.77129Z","caller":"traceutil/trace.go:171","msg":"trace[269945124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"130.288118ms","start":"2026-05-07T23:28:56.640985Z","end":"2026-05-07T23:28:56.771273Z","steps":["trace[269945124] 'agreement among raft nodes before linearized reading' (duration: 130.008972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:56.771476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.653421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:28:56.771517Z","caller":"traceutil/trace.go:171","msg":"trace[820637987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"131.785473ms","start":"2026-05-07T23:28:56.639721Z","end":"2026-05-07T23:28:56.771507Z","steps":["trace[820637987] 'agreement among raft nodes before linearized reading' (duration: 131.370094ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:56.771654Z","caller":"traceutil/trace.go:171","msg":"trace[1874638855] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"256.081843ms","start":"2026-05-07T23:28:56.515562Z","end":"2026-05-07T23:28:56.771644Z","steps":["trace[1874638855] 'process raft request' (duration: 248.346522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:56.771824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.2364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:28:56.771852Z","caller":"traceutil/trace.go:171","msg":"trace[552302742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1237; }","duration":"131.286941ms","start":"2026-05-07T23:28:56.640557Z","end":"2026-05-07T23:28:56.771844Z","steps":["trace[552302742] 'agreement among raft nodes before linearized reading' (duration: 131.206929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:56.77194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.970514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:28:56.771961Z","caller":"traceutil/trace.go:171","msg":"trace[1305147829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"131.008935ms","start":"2026-05-07T23:28:56.640947Z","end":"2026-05-07T23:28:56.771956Z","steps":["trace[1305147829] 'agreement among raft nodes before linearized reading' (duration: 130.951914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:56.772102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.560888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:28:56.772176Z","caller":"traceutil/trace.go:171","msg":"trace[1892670828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"131.673381ms","start":"2026-05-07T23:28:56.640497Z","end":"2026-05-07T23:28:56.77217Z","steps":["trace[1892670828] 'agreement among raft nodes before linearized reading' (duration: 131.539018ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:57.06819Z","caller":"traceutil/trace.go:171","msg":"trace[1460159172] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"417.679902ms","start":"2026-05-07T23:28:56.650486Z","end":"2026-05-07T23:28:57.068166Z","steps":["trace[1460159172] 'process raft request' (duration: 417.627831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.068389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.650477Z","time spent":"417.853726ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:28:57.068448Z","caller":"traceutil/trace.go:171","msg":"trace[2019044875] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"418.762537ms","start":"2026-05-07T23:28:56.649586Z","end":"2026-05-07T23:28:57.068349Z","steps":["trace[2019044875] 'process raft request' (duration: 418.392498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.068786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.64956Z","time spent":"419.095745ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:28:57.710971Z","caller":"traceutil/trace.go:171","msg":"trace[868683872] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"932.770945ms","start":"2026-05-07T23:28:56.778173Z","end":"2026-05-07T23:28:57.710944Z","steps":["trace[868683872] 'process raft request' (duration: 932.676453ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:57.710983Z","caller":"traceutil/trace.go:171","msg":"trace[1349649607] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"932.927168ms","start":"2026-05-07T23:28:56.778019Z","end":"2026-05-07T23:28:57.710946Z","steps":["trace[1349649607] 'process raft request' (duration: 932.620851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.711133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.778168Z","time spent":"932.883547ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:57.711171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.777989Z","time spent":"933.084422ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:57.711188Z","caller":"traceutil/trace.go:171","msg":"trace[1253998275] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"932.963549ms","start":"2026-05-07T23:28:56.778211Z","end":"2026-05-07T23:28:57.711175Z","steps":["trace[1253998275] 'process raft request' (duration: 932.677983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.711248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.778205Z","time spent":"933.01385ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:57.711286Z","caller":"traceutil/trace.go:171","msg":"trace[894523275] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"932.284184ms","start":"2026-05-07T23:28:56.77899Z","end":"2026-05-07T23:28:57.711274Z","steps":["trace[894523275] 'process raft request' (duration: 932.14035ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:57.711283Z","caller":"traceutil/trace.go:171","msg":"trace[1928102983] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1289; }","duration":"687.932035ms","start":"2026-05-07T23:28:57.023317Z","end":"2026-05-07T23:28:57.711249Z","steps":["trace[1928102983] 'read index received' (duration: 44.703913ms)","trace[1928102983] 'applied index is now lower than readState.Index' (duration: 643.226692ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:57.710983Z","caller":"traceutil/trace.go:171","msg":"trace[1057129608] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"932.815736ms","start":"2026-05-07T23:28:56.778139Z","end":"2026-05-07T23:28:57.710955Z","steps":["trace[1057129608] 'process raft request' (duration: 932.671533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.711353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.778962Z","time spent":"932.359075ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:28:57.711484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.15227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:57.711523Z","caller":"traceutil/trace.go:171","msg":"trace[327993491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"688.277642ms","start":"2026-05-07T23:28:57.023235Z","end":"2026-05-07T23:28:57.711513Z","steps":["trace[327993491] 'agreement among raft nodes before linearized reading' (duration: 688.18009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.711551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:57.023216Z","time spent":"688.328534ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:28:57.713329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:56.778133Z","time spent":"933.251046ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:28:57.830252Z","caller":"traceutil/trace.go:171","msg":"trace[767960407] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"752.90795ms","start":"2026-05-07T23:28:57.077303Z","end":"2026-05-07T23:28:57.830211Z","steps":["trace[767960407] 'process raft request' (duration: 724.960448ms)","trace[767960407] 'compare' (duration: 27.764837ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:57.830337Z","caller":"traceutil/trace.go:171","msg":"trace[436004936] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"118.926224ms","start":"2026-05-07T23:28:57.711371Z","end":"2026-05-07T23:28:57.830297Z","steps":["trace[436004936] 'read index received' (duration: 90.953001ms)","trace[436004936] 'applied index is now lower than readState.Index' (duration: 27.969963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:28:57.830421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:57.077276Z","time spent":"753.069734ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:28:57.830602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.447856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-07T23:28:57.830554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.974017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:57.830638Z","caller":"traceutil/trace.go:171","msg":"trace[604606208] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1246; }","duration":"757.534688ms","start":"2026-05-07T23:28:57.073094Z","end":"2026-05-07T23:28:57.830628Z","steps":["trace[604606208] 'agreement among raft nodes before linearized reading' (duration: 757.309463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.830677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:57.073076Z","time spent":"757.593049ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-07T23:28:57.830689Z","caller":"traceutil/trace.go:171","msg":"trace[410182655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"499.159712ms","start":"2026-05-07T23:28:57.331487Z","end":"2026-05-07T23:28:57.830647Z","steps":["trace[410182655] 'agreement among raft nodes before linearized reading' (duration: 498.965377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:57.83074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:28:57.331462Z","time spent":"499.268624ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:29:08.963641Z","caller":"traceutil/trace.go:171","msg":"trace[1683430159] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"118.623327ms","start":"2026-05-07T23:29:08.844968Z","end":"2026-05-07T23:29:08.963591Z","steps":["trace[1683430159] 'process raft request' (duration: 118.401172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:29:09.900632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.895832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-07T23:29:09.900741Z","caller":"traceutil/trace.go:171","msg":"trace[331201795] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1321; }","duration":"838.060196ms","start":"2026-05-07T23:29:09.062652Z","end":"2026-05-07T23:29:09.900712Z","steps":["trace[331201795] 'range keys from in-memory index tree' (duration: 837.685387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:29:09.900797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:29:09.062627Z","time spent":"838.157108ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T23:29:09.900833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.332772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:29:09.900955Z","caller":"traceutil/trace.go:171","msg":"trace[973778764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"570.475025ms","start":"2026-05-07T23:29:09.330452Z","end":"2026-05-07T23:29:09.900927Z","steps":["trace[973778764] 'range keys from in-memory index tree' (duration: 570.24609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:29:09.901002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:29:09.33038Z","time spent":"570.612458ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:29:24.317953Z","caller":"traceutil/trace.go:171","msg":"trace[1586779793] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"157.964403ms","start":"2026-05-07T23:29:24.159951Z","end":"2026-05-07T23:29:24.317916Z","steps":["trace[1586779793] 'process raft request' (duration: 112.970188ms)","trace[1586779793] 'compare' (duration: 44.777679ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:29:24.327027Z","caller":"traceutil/trace.go:171","msg":"trace[1207918800] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"165.883991ms","start":"2026-05-07T23:29:24.161104Z","end":"2026-05-07T23:29:24.326988Z","steps":["trace[1207918800] 'process raft request' (duration: 165.8173ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:29:24.327117Z","caller":"traceutil/trace.go:171","msg":"trace[1643248793] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"166.562198ms","start":"2026-05-07T23:29:24.160537Z","end":"2026-05-07T23:29:24.327099Z","steps":["trace[1643248793] 'process raft request' (duration: 166.21792ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:29:58.923476Z","caller":"traceutil/trace.go:171","msg":"trace[823036546] linearizableReadLoop","detail":"{readStateIndex:1613; appliedIndex:1611; }","duration":"132.78834ms","start":"2026-05-07T23:29:58.790649Z","end":"2026-05-07T23:29:58.923437Z","steps":["trace[823036546] 'read index received' (duration: 8.275117ms)","trace[823036546] 'applied index is now lower than readState.Index' (duration: 124.512023ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:29:58.923673Z","caller":"traceutil/trace.go:171","msg":"trace[120999371] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"153.479164ms","start":"2026-05-07T23:29:58.770134Z","end":"2026-05-07T23:29:58.923613Z","steps":["trace[120999371] 'process raft request' (duration: 98.532973ms)","trace[120999371] 'compare' (duration: 54.604023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:29:58.923763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.083448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:29:58.923806Z","caller":"traceutil/trace.go:171","msg":"trace[1444305548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1549; }","duration":"133.180359ms","start":"2026-05-07T23:29:58.790612Z","end":"2026-05-07T23:29:58.923792Z","steps":["trace[1444305548] 'agreement among raft nodes before linearized reading' (duration: 132.972974ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:22.90819Z","caller":"traceutil/trace.go:171","msg":"trace[811676816] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1724; }","duration":"158.543455ms","start":"2026-05-07T23:30:22.749612Z","end":"2026-05-07T23:30:22.908156Z","steps":["trace[811676816] 'read index received' (duration: 158.276319ms)","trace[811676816] 'applied index is now lower than readState.Index' (duration: 265.506µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:22.908423Z","caller":"traceutil/trace.go:171","msg":"trace[475149225] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"265.54154ms","start":"2026-05-07T23:30:22.642841Z","end":"2026-05-07T23:30:22.908383Z","steps":["trace[475149225] 'process raft request' (duration: 265.092539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:22.908755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.125949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:30:22.909807Z","caller":"traceutil/trace.go:171","msg":"trace[1880207400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1656; }","duration":"160.230255ms","start":"2026-05-07T23:30:22.749549Z","end":"2026-05-07T23:30:22.909779Z","steps":["trace[1880207400] 'agreement among raft nodes before linearized reading' (duration: 158.956445ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:23.078931Z","caller":"traceutil/trace.go:171","msg":"trace[582385786] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"163.75533ms","start":"2026-05-07T23:30:22.915116Z","end":"2026-05-07T23:30:23.078872Z","steps":["trace[582385786] 'process raft request' (duration: 117.378293ms)","trace[582385786] 'compare' (duration: 46.0988ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:23.405061Z","caller":"traceutil/trace.go:171","msg":"trace[1962755683] linearizableReadLoop","detail":"{readStateIndex:1728; appliedIndex:1727; }","duration":"232.46163ms","start":"2026-05-07T23:30:23.172566Z","end":"2026-05-07T23:30:23.405027Z","steps":["trace[1962755683] 'read index received' (duration: 155.114773ms)","trace[1962755683] 'applied index is now lower than readState.Index' (duration: 77.345347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:23.40549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.889871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-07T23:30:23.405521Z","caller":"traceutil/trace.go:171","msg":"trace[463924719] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"318.923255ms","start":"2026-05-07T23:30:23.086552Z","end":"2026-05-07T23:30:23.405475Z","steps":["trace[463924719] 'process raft request' (duration: 241.157408ms)","trace[463924719] 'compare' (duration: 77.135992ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:23.405695Z","caller":"traceutil/trace.go:171","msg":"trace[1093523676] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1659; }","duration":"233.150346ms","start":"2026-05-07T23:30:23.172519Z","end":"2026-05-07T23:30:23.405669Z","steps":["trace[1093523676] 'agreement among raft nodes before linearized reading' (duration: 232.761617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:23.405968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:23.086531Z","time spent":"319.233811ms","remote":"127.0.0.1:43250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:23.513484Z","caller":"traceutil/trace.go:171","msg":"trace[38024787] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"101.876192ms","start":"2026-05-07T23:30:23.411576Z","end":"2026-05-07T23:30:23.513452Z","steps":["trace[38024787] 'process raft request' (duration: 101.611446ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:23.513722Z","caller":"traceutil/trace.go:171","msg":"trace[1706153921] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"101.994915ms","start":"2026-05-07T23:30:23.411681Z","end":"2026-05-07T23:30:23.513676Z","steps":["trace[1706153921] 'process raft request' (duration: 101.729619ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:41.485265Z","caller":"traceutil/trace.go:171","msg":"trace[2103897512] linearizableReadLoop","detail":"{readStateIndex:1813; appliedIndex:1812; }","duration":"100.233098ms","start":"2026-05-07T23:30:41.385003Z","end":"2026-05-07T23:30:41.485236Z","steps":["trace[2103897512] 'read index received' (duration: 27.022311ms)","trace[2103897512] 'applied index is now lower than readState.Index' (duration: 73.209497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:41.485463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.431873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:30:41.485525Z","caller":"traceutil/trace.go:171","msg":"trace[284483897] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1740; }","duration":"100.519805ms","start":"2026-05-07T23:30:41.384994Z","end":"2026-05-07T23:30:41.485514Z","steps":["trace[284483897] 'agreement among raft nodes before linearized reading' (duration: 100.402063ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:41.485456Z","caller":"traceutil/trace.go:171","msg":"trace[911576581] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"129.340799ms","start":"2026-05-07T23:30:41.355974Z","end":"2026-05-07T23:30:41.485314Z","steps":["trace[911576581] 'process raft request' (duration: 56.05823ms)","trace[911576581] 'compare' (duration: 73.075004ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:59.927301Z","caller":"traceutil/trace.go:171","msg":"trace[583776126] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"152.789835ms","start":"2026-05-07T23:30:59.77448Z","end":"2026-05-07T23:30:59.92727Z","steps":["trace[583776126] 'read index received' (duration: 53.365916ms)","trace[583776126] 'applied index is now lower than readState.Index' (duration: 99.423029ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:59.927386Z","caller":"traceutil/trace.go:171","msg":"trace[1045486668] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"159.69742ms","start":"2026-05-07T23:30:59.767644Z","end":"2026-05-07T23:30:59.927341Z","steps":["trace[1045486668] 'process raft request' (duration: 60.149748ms)","trace[1045486668] 'compare' (duration: 99.364297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:59.927554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.054671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T23:30:59.927589Z","caller":"traceutil/trace.go:171","msg":"trace[360638042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1824; }","duration":"153.161894ms","start":"2026-05-07T23:30:59.774417Z","end":"2026-05-07T23:30:59.927579Z","steps":["trace[360638042] 'agreement among raft nodes before linearized reading' (duration: 153.00088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:59.92777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.992072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-07T23:30:59.928145Z","caller":"traceutil/trace.go:171","msg":"trace[771873701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1824; }","duration":"151.397102ms","start":"2026-05-07T23:30:59.7767Z","end":"2026-05-07T23:30:59.928097Z","steps":["trace[771873701] 'agreement among raft nodes before linearized reading' (duration: 150.91098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:59.928349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.34418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:30:59.928532Z","caller":"traceutil/trace.go:171","msg":"trace[339555842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1824; }","duration":"151.561826ms","start":"2026-05-07T23:30:59.776941Z","end":"2026-05-07T23:30:59.928502Z","steps":["trace[339555842] 'agreement among raft nodes before linearized reading' (duration: 151.052814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:31:28.301909Z","caller":"traceutil/trace.go:171","msg":"trace[985354844] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"128.407837ms","start":"2026-05-07T23:31:28.173476Z","end":"2026-05-07T23:31:28.301883Z","steps":["trace[985354844] 'process raft request' (duration: 128.263164ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:31:28.352254Z","caller":"traceutil/trace.go:171","msg":"trace[2069405350] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"178.639519ms","start":"2026-05-07T23:31:28.173558Z","end":"2026-05-07T23:31:28.352197Z","steps":["trace[2069405350] 'process raft request' (duration: 178.097416ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:32:32.434902Z","caller":"traceutil/trace.go:171","msg":"trace[1076375176] linearizableReadLoop","detail":"{readStateIndex:2376; appliedIndex:2375; }","duration":"105.414402ms","start":"2026-05-07T23:32:32.329465Z","end":"2026-05-07T23:32:32.434879Z","steps":["trace[1076375176] 'read index received' (duration: 24.145541ms)","trace[1076375176] 'applied index is now lower than readState.Index' (duration: 81.26807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:32:32.435143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.658977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:32:32.435295Z","caller":"traceutil/trace.go:171","msg":"trace[168055574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2280; }","duration":"105.851942ms","start":"2026-05-07T23:32:32.329424Z","end":"2026-05-07T23:32:32.435276Z","steps":["trace[168055574] 'agreement among raft nodes before linearized reading' (duration: 105.667287ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:32:32.436163Z","caller":"traceutil/trace.go:171","msg":"trace[1708435207] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"111.35429ms","start":"2026-05-07T23:32:32.323654Z","end":"2026-05-07T23:32:32.435008Z","steps":["trace[1708435207] 'process raft request' (duration: 30.006298ms)","trace[1708435207] 'compare' (duration: 81.104676ms)"],"step_count":2}