{"level":"warn","ts":"2026-07-09T23:49:35.324958Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T23:49:35.325038Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-07-09T23:49:35.325109Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T23:49:35.325121Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-09T23:49:35.32514Z","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-07-09T23:49:35.325736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-07-09T23:49:35.325816Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-07-09T23:49:35.333949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.538941ms"} {"level":"info","ts":"2026-07-09T23:49:35.344725Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-07-09T23:49:35.344905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T23:49:35.344949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-07-09T23:49:35.344985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T23:49:35.345014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-07-09T23:49:35.345101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-07-09T23:49:35.354005Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T23:49:35.356189Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T23:49:35.35843Z","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-07-09T23:49:35.361324Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T23:49:35.361509Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T23:49:35.361616Z","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-07-09T23:49:35.361705Z","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-07-09T23:49:35.361718Z","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-07-09T23:49:35.364376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-07-09T23:49:35.364487Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-09T23:49:35.365497Z","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-07-09T23:49:35.36561Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-09T23:49:35.365633Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-09T23:49:35.366885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T23:49:35.366912Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T23:49:35.445391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T23:49:35.445433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T23:49:35.445447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-07-09T23:49:35.445458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-07-09T23:49:35.445464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-09T23:49:35.445472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-07-09T23:49:35.445479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-09T23:49:35.447629Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T23:49:35.44782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:49:35.447971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:49:35.447651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:49:35.448291Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T23:49:35.448366Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T23:49:35.451551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T23:49:35.452232Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:49:35.452456Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:49:35.452546Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:49:35.453135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-07-09T23:49:39.816057Z","caller":"traceutil/trace.go:171","msg":"trace[239204414] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:208; }","duration":"195.194476ms","start":"2026-07-09T23:49:39.620836Z","end":"2026-07-09T23:49:39.81603Z","steps":["trace[239204414] 'read index received' (duration: 195.00176ms)","trace[239204414] 'applied index is now lower than readState.Index' (duration: 191.816µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:49:39.816347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.464774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-09T23:49:39.816438Z","caller":"traceutil/trace.go:171","msg":"trace[1851360066] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:204; }","duration":"195.630339ms","start":"2026-07-09T23:49:39.620794Z","end":"2026-07-09T23:49:39.816424Z","steps":["trace[1851360066] 'agreement among raft nodes before linearized reading' (duration: 195.386132ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:49:39.816421Z","caller":"traceutil/trace.go:171","msg":"trace[1691924965] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"341.126216ms","start":"2026-07-09T23:49:39.47525Z","end":"2026-07-09T23:49:39.816377Z","steps":["trace[1691924965] 'process raft request' (duration: 340.64548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:39.817085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:39.475226Z","time spent":"341.298852ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:49:45.084231Z","caller":"traceutil/trace.go:171","msg":"trace[354599132] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"167.035065ms","start":"2026-07-09T23:49:44.917176Z","end":"2026-07-09T23:49:45.084211Z","steps":["trace[354599132] 'read index received' (duration: 166.809588ms)","trace[354599132] 'applied index is now lower than readState.Index' (duration: 224.757µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:49:45.084294Z","caller":"traceutil/trace.go:171","msg":"trace[587653383] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"200.575938ms","start":"2026-07-09T23:49:44.883685Z","end":"2026-07-09T23:49:45.084261Z","steps":["trace[587653383] 'process raft request' (duration: 200.395823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:45.084335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.143029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:49:45.084494Z","caller":"traceutil/trace.go:171","msg":"trace[2054688692] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:297; }","duration":"167.310634ms","start":"2026-07-09T23:49:44.91717Z","end":"2026-07-09T23:49:45.084481Z","steps":["trace[2054688692] 'agreement among raft nodes before linearized reading' (duration: 167.123638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:51.610848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270104152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:49:52.111409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270104152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:49:52.611856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270104152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:49:52.645708Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.775720336s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T23:49:52.646063Z","caller":"traceutil/trace.go:171","msg":"trace[1862934790] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.776189021s","start":"2026-07-09T23:49:50.86985Z","end":"2026-07-09T23:49:52.646039Z","steps":["trace[1862934790] 'process raft request' (duration: 1.776026986s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:52.646421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:50.869821Z","time spent":"1.776324585s","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:49:53.110785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000107037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T23:49:53.110954Z","caller":"traceutil/trace.go:171","msg":"trace[1406310529] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000349444s","start":"2026-07-09T23:49:51.110582Z","end":"2026-07-09T23:49:53.110931Z","steps":["trace[1406310529] 'agreement among raft nodes before linearized reading' (duration: 2.000148188s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.111012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:51.110556Z","time spent":"2.000442487s","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 23:49:53 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T23:49:53.814128Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.168258699s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T23:49:53.821516Z","caller":"traceutil/trace.go:171","msg":"trace[1612665492] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:381; }","duration":"2.710857783s","start":"2026-07-09T23:49:51.110635Z","end":"2026-07-09T23:49:53.821492Z","steps":["trace[1612665492] 'read index received' (duration: 1.535227619s)","trace[1612665492] 'applied index is now lower than readState.Index' (duration: 1.175628824s)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:49:53.821619Z","caller":"traceutil/trace.go:171","msg":"trace[1251500191] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"2.70544672s","start":"2026-07-09T23:49:51.116162Z","end":"2026-07-09T23:49:53.821609Z","steps":["trace[1251500191] 'process raft request' (duration: 2.698142468s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.821728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:51.116147Z","time spent":"2.705507602s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:49:53.821745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941877925s","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-07-09T23:49:53.821829Z","caller":"traceutil/trace.go:171","msg":"trace[1096480317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"1.94202426s","start":"2026-07-09T23:49:51.879782Z","end":"2026-07-09T23:49:53.821806Z","steps":["trace[1096480317] 'agreement among raft nodes before linearized reading' (duration: 1.941886305s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.821972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.287745654s","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-07-09T23:49:53.821983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.8719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:49:53.82201Z","caller":"traceutil/trace.go:171","msg":"trace[852794631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"2.287831026s","start":"2026-07-09T23:49:51.534168Z","end":"2026-07-09T23:49:53.821999Z","steps":["trace[852794631] 'agreement among raft nodes before linearized reading' (duration: 2.287753053s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.822023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170703895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-07-09T23:49:53.822087Z","caller":"traceutil/trace.go:171","msg":"trace[779821373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"711.015265ms","start":"2026-07-09T23:49:53.111026Z","end":"2026-07-09T23:49:53.822042Z","steps":["trace[779821373] 'agreement among raft nodes before linearized reading' (duration: 710.85058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.822089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:51.534136Z","time spent":"2.287912058s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T23:49:53.822103Z","caller":"traceutil/trace.go:171","msg":"trace[19067132] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:368; }","duration":"1.170807029s","start":"2026-07-09T23:49:52.651283Z","end":"2026-07-09T23:49:53.82209Z","steps":["trace[19067132] 'agreement among raft nodes before linearized reading' (duration: 1.170672044s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.822138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:52.651265Z","time spent":"1.170864251s","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4344,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-09T23:49:53.822132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:53.111005Z","time spent":"711.117788ms","remote":"127.0.0.1:42694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:49:53.822372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.281850185s","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-07-09T23:49:53.822421Z","caller":"traceutil/trace.go:171","msg":"trace[66433599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"2.281941498s","start":"2026-07-09T23:49:51.540465Z","end":"2026-07-09T23:49:53.822407Z","steps":["trace[66433599] 'agreement among raft nodes before linearized reading' (duration: 2.281722931s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.822453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:51.540432Z","time spent":"2.28201398s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:49:53.821909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:51.879759Z","time spent":"1.942134003s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T23:49:53.823172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.712369962s","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-07-09T23:49:53.823287Z","caller":"traceutil/trace.go:171","msg":"trace[2097941245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"2.712550287s","start":"2026-07-09T23:49:51.110707Z","end":"2026-07-09T23:49:53.823257Z","steps":["trace[2097941245] 'agreement among raft nodes before linearized reading' (duration: 2.710957616s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:53.823348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:49:51.110683Z","time spent":"2.712654011s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T23:50:17.745056Z","caller":"traceutil/trace.go:171","msg":"trace[898277148] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"439.236969ms","start":"2026-07-09T23:50:17.305791Z","end":"2026-07-09T23:50:17.745028Z","steps":["trace[898277148] 'process raft request' (duration: 439.105325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.745242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.305773Z","time spent":"439.377544ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.768568Z","caller":"traceutil/trace.go:171","msg":"trace[621109419] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"458.506634ms","start":"2026-07-09T23:50:17.310036Z","end":"2026-07-09T23:50:17.768543Z","steps":["trace[621109419] 'process raft request' (duration: 458.338809ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:17.768579Z","caller":"traceutil/trace.go:171","msg":"trace[496683970] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"458.634588ms","start":"2026-07-09T23:50:17.30987Z","end":"2026-07-09T23:50:17.768505Z","steps":["trace[496683970] 'process raft request' (duration: 458.378039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.768679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.310031Z","time spent":"458.602337ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.768665Z","caller":"traceutil/trace.go:171","msg":"trace[1784916603] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"448.874237ms","start":"2026-07-09T23:50:17.319725Z","end":"2026-07-09T23:50:17.768599Z","steps":["trace[1784916603] 'process raft request' (duration: 448.696421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.768794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.309859Z","time spent":"458.836194ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:50:17.768871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.319704Z","time spent":"449.064502ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.768887Z","caller":"traceutil/trace.go:171","msg":"trace[379495070] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"447.608886ms","start":"2026-07-09T23:50:17.321264Z","end":"2026-07-09T23:50:17.768873Z","steps":["trace[379495070] 'process raft request' (duration: 447.243385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.768952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.321248Z","time spent":"447.673808ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.769111Z","caller":"traceutil/trace.go:171","msg":"trace[56096316] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"447.617306ms","start":"2026-07-09T23:50:17.32148Z","end":"2026-07-09T23:50:17.769097Z","steps":["trace[56096316] 'process raft request' (duration: 447.08525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.769201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.321474Z","time spent":"447.670948ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.769257Z","caller":"traceutil/trace.go:171","msg":"trace[1878085751] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"444.57815ms","start":"2026-07-09T23:50:17.324626Z","end":"2026-07-09T23:50:17.769204Z","steps":["trace[1878085751] 'process raft request' (duration: 444.410195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.769425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.324608Z","time spent":"444.744925ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.769706Z","caller":"traceutil/trace.go:171","msg":"trace[2082632757] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"445.006383ms","start":"2026-07-09T23:50:17.324679Z","end":"2026-07-09T23:50:17.769686Z","steps":["trace[2082632757] 'process raft request' (duration: 444.439965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.769799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.324676Z","time spent":"445.081516ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.769952Z","caller":"traceutil/trace.go:171","msg":"trace[548608230] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"277.829655ms","start":"2026-07-09T23:50:17.492102Z","end":"2026-07-09T23:50:17.769932Z","steps":["trace[548608230] 'process raft request' (duration: 277.637989ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:17.770265Z","caller":"traceutil/trace.go:171","msg":"trace[438600346] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"433.979692ms","start":"2026-07-09T23:50:17.336272Z","end":"2026-07-09T23:50:17.770252Z","steps":["trace[438600346] 'process raft request' (duration: 433.342662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.770383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.336261Z","time spent":"434.081815ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.770657Z","caller":"traceutil/trace.go:171","msg":"trace[989781422] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"437.902127ms","start":"2026-07-09T23:50:17.332742Z","end":"2026-07-09T23:50:17.770644Z","steps":["trace[989781422] 'process raft request' (duration: 436.783501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.771433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.332725Z","time spent":"438.666921ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:17.771876Z","caller":"traceutil/trace.go:171","msg":"trace[1934695800] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"404.878495ms","start":"2026-07-09T23:50:17.366988Z","end":"2026-07-09T23:50:17.771866Z","steps":["trace[1934695800] 'process raft request' (duration: 402.686335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:17.771956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:17.366958Z","time spent":"404.975667ms","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:50:23.837012Z","caller":"traceutil/trace.go:171","msg":"trace[1537495675] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"173.045915ms","start":"2026-07-09T23:50:23.663938Z","end":"2026-07-09T23:50:23.836984Z","steps":["trace[1537495675] 'process raft request' (duration: 172.460207ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:24.080666Z","caller":"traceutil/trace.go:171","msg":"trace[1513837922] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"134.050302ms","start":"2026-07-09T23:50:23.94659Z","end":"2026-07-09T23:50:24.08064Z","steps":["trace[1513837922] 'read index received' (duration: 19.001745ms)","trace[1513837922] 'applied index is now lower than readState.Index' (duration: 115.047137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:50:24.080967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.347533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zff2f\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-09T23:50:24.081022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.386438ms","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-07-09T23:50:24.081078Z","caller":"traceutil/trace.go:171","msg":"trace[79895709] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zff2f; range_end:; response_count:1; response_revision:597; }","duration":"134.501857ms","start":"2026-07-09T23:50:23.94655Z","end":"2026-07-09T23:50:24.081052Z","steps":["trace[79895709] 'agreement among raft nodes before linearized reading' (duration: 134.211968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:24.081091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.171132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:50:24.081115Z","caller":"traceutil/trace.go:171","msg":"trace[1422454690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"110.510702ms","start":"2026-07-09T23:50:23.970567Z","end":"2026-07-09T23:50:24.081078Z","steps":["trace[1422454690] 'agreement among raft nodes before linearized reading' (duration: 110.246944ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:24.081162Z","caller":"traceutil/trace.go:171","msg":"trace[381857378] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"105.275646ms","start":"2026-07-09T23:50:23.975871Z","end":"2026-07-09T23:50:24.081147Z","steps":["trace[381857378] 'agreement among raft nodes before linearized reading' (duration: 105.11568ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:24.222553Z","caller":"traceutil/trace.go:171","msg":"trace[1996844059] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:623; }","duration":"111.68665ms","start":"2026-07-09T23:50:24.110828Z","end":"2026-07-09T23:50:24.222515Z","steps":["trace[1996844059] 'read index received' (duration: 4.532755ms)","trace[1996844059] 'applied index is now lower than readState.Index' (duration: 107.153105ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:50:24.222598Z","caller":"traceutil/trace.go:171","msg":"trace[801153128] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"136.127708ms","start":"2026-07-09T23:50:24.086427Z","end":"2026-07-09T23:50:24.222555Z","steps":["trace[801153128] 'process raft request' (duration: 135.963673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:24.22266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.826934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:50:24.222684Z","caller":"traceutil/trace.go:171","msg":"trace[1816905775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"111.897046ms","start":"2026-07-09T23:50:24.110779Z","end":"2026-07-09T23:50:24.222676Z","steps":["trace[1816905775] 'agreement among raft nodes before linearized reading' (duration: 111.824614ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:24.222876Z","caller":"traceutil/trace.go:171","msg":"trace[413071052] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"136.338315ms","start":"2026-07-09T23:50:24.08653Z","end":"2026-07-09T23:50:24.222869Z","steps":["trace[413071052] 'process raft request' (duration: 135.887891ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:24.222996Z","caller":"traceutil/trace.go:171","msg":"trace[1324608016] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"126.029817ms","start":"2026-07-09T23:50:24.09696Z","end":"2026-07-09T23:50:24.222989Z","steps":["trace[1324608016] 'process raft request' (duration: 125.48128ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:24.223172Z","caller":"traceutil/trace.go:171","msg":"trace[444342301] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"137.389828ms","start":"2026-07-09T23:50:24.085767Z","end":"2026-07-09T23:50:24.223157Z","steps":["trace[444342301] 'process raft request' (duration: 119.741016ms)","trace[444342301] 'compare' (duration: 16.798715ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:50:24.95426Z","caller":"traceutil/trace.go:171","msg":"trace[174505107] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"472.620664ms","start":"2026-07-09T23:50:24.481621Z","end":"2026-07-09T23:50:24.954241Z","steps":["trace[174505107] 'process raft request' (duration: 472.18599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:24.954382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:24.481595Z","time spent":"472.723787ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:50:24.961014Z","caller":"traceutil/trace.go:171","msg":"trace[1713095199] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"290.239191ms","start":"2026-07-09T23:50:24.670755Z","end":"2026-07-09T23:50:24.960994Z","steps":["trace[1713095199] 'process raft request' (duration: 290.137548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:25.427734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.209866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:50:25.427832Z","caller":"traceutil/trace.go:171","msg":"trace[580571648] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"317.012613ms","start":"2026-07-09T23:50:25.110805Z","end":"2026-07-09T23:50:25.427818Z","steps":["trace[580571648] 'read index received' (duration: 39.311µs)","trace[580571648] 'applied index is now lower than readState.Index' (duration: 316.972072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:50:25.427914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.111617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:50:25.427938Z","caller":"traceutil/trace.go:171","msg":"trace[962598782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"317.173779ms","start":"2026-07-09T23:50:25.110756Z","end":"2026-07-09T23:50:25.42793Z","steps":["trace[962598782] 'agreement among raft nodes before linearized reading' (duration: 317.102527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:25.427967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:25.110736Z","time spent":"317.224721ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T23:50:25.428235Z","caller":"traceutil/trace.go:171","msg":"trace[130849502] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"406.098723ms","start":"2026-07-09T23:50:25.022115Z","end":"2026-07-09T23:50:25.428214Z","steps":["trace[130849502] 'process raft request' (duration: 85.195755ms)","trace[130849502] 'compare' (duration: 319.813073ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:50:25.428315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:25.02207Z","time spent":"406.207367ms","remote":"127.0.0.1:42930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:50:26.539282Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.097403627s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T23:50:27.753459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213860307s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:50:27.753587Z","caller":"traceutil/trace.go:171","msg":"trace[1439365636] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"1.630715933s","start":"2026-07-09T23:50:26.12285Z","end":"2026-07-09T23:50:27.753566Z","steps":["trace[1439365636] 'read index received' (duration: 416.579008ms)","trace[1439365636] 'applied index is now lower than readState.Index' (duration: 1.214135515s)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:50:27.753695Z","caller":"traceutil/trace.go:171","msg":"trace[1909475159] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"2.311860282s","start":"2026-07-09T23:50:25.44182Z","end":"2026-07-09T23:50:27.75368Z","steps":["trace[1909475159] 'process raft request' (duration: 1.097669165s)","trace[1909475159] 'compare' (duration: 1.21367115s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:50:27.753773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:25.441809Z","time spent":"2.311922354s","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:50:27.754159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631304862s","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-07-09T23:50:27.754207Z","caller":"traceutil/trace.go:171","msg":"trace[1580094619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"1.631391584s","start":"2026-07-09T23:50:26.1228Z","end":"2026-07-09T23:50:27.754192Z","steps":["trace[1580094619] 'agreement among raft nodes before linearized reading' (duration: 1.631194838s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:27.754249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:26.122779Z","time spent":"1.631458807s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T23:50:27.754386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525356305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T23:50:27.754562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524622872s","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-07-09T23:50:27.754581Z","caller":"traceutil/trace.go:171","msg":"trace[101266145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.525592742s","start":"2026-07-09T23:50:26.228964Z","end":"2026-07-09T23:50:27.754557Z","steps":["trace[101266145] 'agreement among raft nodes before linearized reading' (duration: 1.52523398s)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:27.754622Z","caller":"traceutil/trace.go:171","msg":"trace[1749516381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.524728035s","start":"2026-07-09T23:50:26.229881Z","end":"2026-07-09T23:50:27.754609Z","steps":["trace[1749516381] 'agreement among raft nodes before linearized reading' (duration: 1.5245827s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:27.754656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:26.229858Z","time spent":"1.524791227s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:50:27.754648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:26.22895Z","time spent":"1.525681965s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:50:27.754748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525288872s","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-07-09T23:50:27.754759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.928133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:50:27.754834Z","caller":"traceutil/trace.go:171","msg":"trace[1923303186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.525390766s","start":"2026-07-09T23:50:26.229425Z","end":"2026-07-09T23:50:27.754816Z","steps":["trace[1923303186] 'agreement among raft nodes before linearized reading' (duration: 1.525181509s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:27.754877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:26.229422Z","time spent":"1.525446268s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T23:50:27.754914Z","caller":"traceutil/trace.go:171","msg":"trace[634214894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"644.1711ms","start":"2026-07-09T23:50:27.110718Z","end":"2026-07-09T23:50:27.754889Z","steps":["trace[634214894] 'agreement among raft nodes before linearized reading' (duration: 643.83734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:27.754975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:27.110698Z","time spent":"644.263444ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:50:29.422148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.594041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:50:29.422239Z","caller":"traceutil/trace.go:171","msg":"trace[446576207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"311.747576ms","start":"2026-07-09T23:50:29.110474Z","end":"2026-07-09T23:50:29.422221Z","steps":["trace[446576207] 'range keys from in-memory index tree' (duration: 311.512048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:29.422279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:29.110425Z","time spent":"311.846089ms","remote":"127.0.0.1:42694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T23:50:29.427285Z","caller":"traceutil/trace.go:171","msg":"trace[1240538245] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"210.755637ms","start":"2026-07-09T23:50:29.216518Z","end":"2026-07-09T23:50:29.427273Z","steps":["trace[1240538245] 'process raft request' (duration: 210.667635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.014756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.90643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:50:42.014841Z","caller":"traceutil/trace.go:171","msg":"trace[1004529583] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:760; }","duration":"658.027183ms","start":"2026-07-09T23:50:41.356797Z","end":"2026-07-09T23:50:42.014824Z","steps":["trace[1004529583] 'count revisions from in-memory index tree' (duration: 657.788305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.014978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.392779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T23:50:42.014961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:50:41.356778Z","time spent":"658.154767ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:50:42.015076Z","caller":"traceutil/trace.go:171","msg":"trace[1091456718] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:760; }","duration":"174.557454ms","start":"2026-07-09T23:50:41.840498Z","end":"2026-07-09T23:50:42.015055Z","steps":["trace[1091456718] 'count revisions from in-memory index tree' (duration: 174.212173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.014728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.786416ms","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-07-09T23:50:42.015152Z","caller":"traceutil/trace.go:171","msg":"trace[2051022891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"124.218939ms","start":"2026-07-09T23:50:41.890906Z","end":"2026-07-09T23:50:42.015125Z","steps":["trace[2051022891] 'range keys from in-memory index tree' (duration: 123.648021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.01519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.681468ms","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-07-09T23:50:42.015223Z","caller":"traceutil/trace.go:171","msg":"trace[177903282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"142.75025ms","start":"2026-07-09T23:50:41.872464Z","end":"2026-07-09T23:50:42.015214Z","steps":["trace[177903282] 'range keys from in-memory index tree' (duration: 142.072618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.015333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.001291ms","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-07-09T23:50:42.015361Z","caller":"traceutil/trace.go:171","msg":"trace[1287357267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"118.313891ms","start":"2026-07-09T23:50:41.897037Z","end":"2026-07-09T23:50:42.015351Z","steps":["trace[1287357267] 'range keys from in-memory index tree' (duration: 117.712922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.01584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.325411ms","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-07-09T23:50:42.01589Z","caller":"traceutil/trace.go:171","msg":"trace[911255016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"118.393124ms","start":"2026-07-09T23:50:41.897485Z","end":"2026-07-09T23:50:42.015878Z","steps":["trace[911255016] 'range keys from in-memory index tree' (duration: 118.160446ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:42.142113Z","caller":"traceutil/trace.go:171","msg":"trace[504934787] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"122.755522ms","start":"2026-07-09T23:50:42.019336Z","end":"2026-07-09T23:50:42.142092Z","steps":["trace[504934787] 'process raft request' (duration: 122.609388ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:42.265898Z","caller":"traceutil/trace.go:171","msg":"trace[1855459884] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:791; }","duration":"155.447304ms","start":"2026-07-09T23:50:42.110425Z","end":"2026-07-09T23:50:42.265872Z","steps":["trace[1855459884] 'read index received' (duration: 31.550596ms)","trace[1855459884] 'applied index is now lower than readState.Index' (duration: 123.895708ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:50:42.265952Z","caller":"traceutil/trace.go:171","msg":"trace[1582758212] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"204.553419ms","start":"2026-07-09T23:50:42.061374Z","end":"2026-07-09T23:50:42.265928Z","steps":["trace[1582758212] 'process raft request' (duration: 204.439946ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:42.265939Z","caller":"traceutil/trace.go:171","msg":"trace[96807356] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"246.561928ms","start":"2026-07-09T23:50:42.019336Z","end":"2026-07-09T23:50:42.265898Z","steps":["trace[96807356] 'process raft request' (duration: 246.407303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:50:42.266051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.583779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:50:42.266105Z","caller":"traceutil/trace.go:171","msg":"trace[892302931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"155.708742ms","start":"2026-07-09T23:50:42.110381Z","end":"2026-07-09T23:50:42.26609Z","steps":["trace[892302931] 'agreement among raft nodes before linearized reading' (duration: 155.593459ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:42.266257Z","caller":"traceutil/trace.go:171","msg":"trace[839588988] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"246.602169ms","start":"2026-07-09T23:50:42.019644Z","end":"2026-07-09T23:50:42.266247Z","steps":["trace[839588988] 'process raft request' (duration: 246.140175ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:50:42.266171Z","caller":"traceutil/trace.go:171","msg":"trace[1182952647] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"246.655781ms","start":"2026-07-09T23:50:42.019423Z","end":"2026-07-09T23:50:42.266079Z","steps":["trace[1182952647] 'process raft request' (duration: 246.134634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:18.55974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.495021ms","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-07-09T23:51:18.559811Z","caller":"traceutil/trace.go:171","msg":"trace[162611906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"132.611305ms","start":"2026-07-09T23:51:18.427185Z","end":"2026-07-09T23:51:18.559796Z","steps":["trace[162611906] 'range keys from in-memory index tree' (duration: 132.351866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:18.559795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.788663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:51:18.559948Z","caller":"traceutil/trace.go:171","msg":"trace[1662433761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"448.964879ms","start":"2026-07-09T23:51:18.110954Z","end":"2026-07-09T23:51:18.559919Z","steps":["trace[1662433761] 'range keys from in-memory index tree' (duration: 448.709431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:18.559949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.718166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T23:51:18.559941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.536805ms","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-07-09T23:51:18.560022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:18.110939Z","time spent":"449.064903ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T23:51:18.560035Z","caller":"traceutil/trace.go:171","msg":"trace[1494948959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"136.661229ms","start":"2026-07-09T23:51:18.423353Z","end":"2026-07-09T23:51:18.560014Z","steps":["trace[1494948959] 'range keys from in-memory index tree' (duration: 136.37544ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:18.560025Z","caller":"traceutil/trace.go:171","msg":"trace[1518049557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"141.827711ms","start":"2026-07-09T23:51:18.418179Z","end":"2026-07-09T23:51:18.560007Z","steps":["trace[1518049557] 'range keys from in-memory index tree' (duration: 141.5226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:18.560168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.120292ms","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-07-09T23:51:18.560215Z","caller":"traceutil/trace.go:171","msg":"trace[1668574827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"251.203825ms","start":"2026-07-09T23:51:18.308997Z","end":"2026-07-09T23:51:18.560201Z","steps":["trace[1668574827] 'range keys from in-memory index tree' (duration: 250.839813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:18.56021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.424759ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:51:18.56028Z","caller":"traceutil/trace.go:171","msg":"trace[42302343] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:983; }","duration":"823.503312ms","start":"2026-07-09T23:51:17.736765Z","end":"2026-07-09T23:51:18.560268Z","steps":["trace[42302343] 'range keys from in-memory index tree' (duration: 823.416009ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:19.16307Z","caller":"traceutil/trace.go:171","msg":"trace[1203817222] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"598.573357ms","start":"2026-07-09T23:51:18.564407Z","end":"2026-07-09T23:51:19.16298Z","steps":["trace[1203817222] 'process raft request' (duration: 598.445543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:19.16323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:18.564387Z","time spent":"598.749453ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:51:19.487953Z","caller":"traceutil/trace.go:171","msg":"trace[808186572] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"923.133003ms","start":"2026-07-09T23:51:18.564799Z","end":"2026-07-09T23:51:19.487932Z","steps":["trace[808186572] 'process raft request' (duration: 922.866394ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:19.487995Z","caller":"traceutil/trace.go:171","msg":"trace[346627135] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"923.110122ms","start":"2026-07-09T23:51:18.564854Z","end":"2026-07-09T23:51:19.487964Z","steps":["trace[346627135] 'process raft request' (duration: 922.988798ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:19.488172Z","caller":"traceutil/trace.go:171","msg":"trace[2087930626] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"923.062521ms","start":"2026-07-09T23:51:18.565103Z","end":"2026-07-09T23:51:19.488165Z","steps":["trace[2087930626] 'process raft request' (duration: 922.816193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:19.488219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:18.565094Z","time spent":"923.101202ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:51:19.488096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:18.564787Z","time spent":"923.243507ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:51:19.488158Z","caller":"traceutil/trace.go:171","msg":"trace[437189640] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1022; }","duration":"614.745733ms","start":"2026-07-09T23:51:18.873362Z","end":"2026-07-09T23:51:19.488107Z","steps":["trace[437189640] 'read index received' (duration: 289.522995ms)","trace[437189640] 'applied index is now lower than readState.Index' (duration: 325.221508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:51:19.488265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:18.56484Z","time spent":"923.33707ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:51:19.488408Z","caller":"traceutil/trace.go:171","msg":"trace[534667823] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"202.948146ms","start":"2026-07-09T23:51:19.285448Z","end":"2026-07-09T23:51:19.488396Z","steps":["trace[534667823] 'process raft request' (duration: 202.825982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:19.488533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.285961ms","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-07-09T23:51:19.488569Z","caller":"traceutil/trace.go:171","msg":"trace[640307530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"134.341782ms","start":"2026-07-09T23:51:19.354218Z","end":"2026-07-09T23:51:19.48856Z","steps":["trace[640307530] 'agreement among raft nodes before linearized reading' (duration: 134.232628ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:19.488578Z","caller":"traceutil/trace.go:171","msg":"trace[1362255396] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"169.56653ms","start":"2026-07-09T23:51:19.318987Z","end":"2026-07-09T23:51:19.488554Z","steps":["trace[1362255396] 'process raft request' (duration: 169.373043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:19.488908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.54656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-09T23:51:19.488974Z","caller":"traceutil/trace.go:171","msg":"trace[219914059] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:989; }","duration":"615.648193ms","start":"2026-07-09T23:51:18.873313Z","end":"2026-07-09T23:51:19.488961Z","steps":["trace[219914059] 'agreement among raft nodes before linearized reading' (duration: 614.878098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:19.489012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:18.873292Z","time spent":"615.712126ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-07-09T23:51:41.974214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270105627,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:51:42.023766Z","caller":"traceutil/trace.go:171","msg":"trace[1397382793] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"750.970007ms","start":"2026-07-09T23:51:41.272771Z","end":"2026-07-09T23:51:42.023741Z","steps":["trace[1397382793] 'process raft request' (duration: 750.800661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.023914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.272752Z","time spent":"751.08855ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:51:42.411666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.709013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:51:42.411785Z","caller":"traceutil/trace.go:171","msg":"trace[1244198955] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1101; }","duration":"938.239062ms","start":"2026-07-09T23:51:41.473524Z","end":"2026-07-09T23:51:42.411763Z","steps":["trace[1244198955] 'read index received' (duration: 550.12932ms)","trace[1244198955] 'applied index is now lower than readState.Index' (duration: 388.108412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:51:42.412086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.475131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:51:42.412243Z","caller":"traceutil/trace.go:171","msg":"trace[1346916088] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1059; }","duration":"938.735829ms","start":"2026-07-09T23:51:41.473481Z","end":"2026-07-09T23:51:42.412217Z","steps":["trace[1346916088] 'agreement among raft nodes before linearized reading' (duration: 938.422839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.4123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.473461Z","time spent":"938.829243ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:51:42.412261Z","caller":"traceutil/trace.go:171","msg":"trace[1046894109] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"790.734974ms","start":"2026-07-09T23:51:41.621445Z","end":"2026-07-09T23:51:42.41218Z","steps":["trace[1046894109] 'process raft request' (duration: 630.424171ms)","trace[1046894109] 'compare' (duration: 159.327751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:51:42.412478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.41616ms","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-07-09T23:51:42.41249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.621406Z","time spent":"790.980413ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:51:42.412527Z","caller":"traceutil/trace.go:171","msg":"trace[64822670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"722.496543ms","start":"2026-07-09T23:51:41.690017Z","end":"2026-07-09T23:51:42.412513Z","steps":["trace[64822670] 'agreement among raft nodes before linearized reading' (duration: 722.340998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.412552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.690005Z","time spent":"722.541924ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:51:42.412556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.920645ms","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-07-09T23:51:42.412668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.05428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:51:42.412662Z","caller":"traceutil/trace.go:171","msg":"trace[259536188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"751.07894ms","start":"2026-07-09T23:51:41.66156Z","end":"2026-07-09T23:51:42.412639Z","steps":["trace[259536188] 'agreement among raft nodes before linearized reading' (duration: 750.7716ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:42.412701Z","caller":"traceutil/trace.go:171","msg":"trace[1998819115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"302.118632ms","start":"2026-07-09T23:51:42.110573Z","end":"2026-07-09T23:51:42.412692Z","steps":["trace[1998819115] 'agreement among raft nodes before linearized reading' (duration: 302.05643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.412727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:42.110549Z","time spent":"302.173534ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:51:42.412727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.661541Z","time spent":"751.175354ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:51:42.412692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.403973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T23:51:42.412771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.562582ms","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-07-09T23:51:42.412781Z","caller":"traceutil/trace.go:171","msg":"trace[948655398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"723.516638ms","start":"2026-07-09T23:51:41.689249Z","end":"2026-07-09T23:51:42.412765Z","steps":["trace[948655398] 'agreement among raft nodes before linearized reading' (duration: 723.139775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.412894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.689237Z","time spent":"723.637391ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T23:51:42.413075Z","caller":"traceutil/trace.go:171","msg":"trace[1878008920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"724.652045ms","start":"2026-07-09T23:51:41.688149Z","end":"2026-07-09T23:51:42.412801Z","steps":["trace[1878008920] 'agreement among raft nodes before linearized reading' (duration: 724.472869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.413315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:41.688094Z","time spent":"725.204913ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T23:51:42.809027Z","caller":"traceutil/trace.go:171","msg":"trace[1600834996] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"391.016149ms","start":"2026-07-09T23:51:42.417974Z","end":"2026-07-09T23:51:42.80899Z","steps":["trace[1600834996] 'process raft request' (duration: 390.974637ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:42.809119Z","caller":"traceutil/trace.go:171","msg":"trace[1887840351] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"391.613208ms","start":"2026-07-09T23:51:42.417461Z","end":"2026-07-09T23:51:42.809074Z","steps":["trace[1887840351] 'process raft request' (duration: 391.452383ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:42.809109Z","caller":"traceutil/trace.go:171","msg":"trace[1361919987] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"391.872086ms","start":"2026-07-09T23:51:42.417173Z","end":"2026-07-09T23:51:42.809045Z","steps":["trace[1361919987] 'process raft request' (duration: 391.598227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:42.809258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:42.417965Z","time spent":"391.135782ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:51:42.809287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:42.417449Z","time spent":"391.760913ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:51:42.809365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:42.417153Z","time spent":"392.125925ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:51:43.07907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.774355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:51:43.079186Z","caller":"traceutil/trace.go:171","msg":"trace[1029798699] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1063; }","duration":"162.95175ms","start":"2026-07-09T23:51:42.916213Z","end":"2026-07-09T23:51:43.079165Z","steps":["trace[1029798699] 'count revisions from in-memory index tree' (duration: 162.686412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:44.203397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.423138ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-09T23:51:44.203505Z","caller":"traceutil/trace.go:171","msg":"trace[446721560] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"174.851465ms","start":"2026-07-09T23:51:44.028636Z","end":"2026-07-09T23:51:44.203487Z","steps":["trace[446721560] 'read index received' (duration: 4.277832ms)","trace[446721560] 'applied index is now lower than readState.Index' (duration: 170.572183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:51:44.203805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.150875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T23:51:44.203888Z","caller":"traceutil/trace.go:171","msg":"trace[940959587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"175.289059ms","start":"2026-07-09T23:51:44.028579Z","end":"2026-07-09T23:51:44.203868Z","steps":["trace[940959587] 'agreement among raft nodes before linearized reading' (duration: 174.987969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:47.007533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270105658,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:51:47.152352Z","caller":"traceutil/trace.go:171","msg":"trace[1749124282] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"645.810032ms","start":"2026-07-09T23:51:46.506521Z","end":"2026-07-09T23:51:47.152331Z","steps":["trace[1749124282] 'read index received' (duration: 574.513849ms)","trace[1749124282] 'applied index is now lower than readState.Index' (duration: 71.295163ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:51:47.153163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.941317ms","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-07-09T23:51:47.153223Z","caller":"traceutil/trace.go:171","msg":"trace[1244892362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"283.0288ms","start":"2026-07-09T23:51:46.870172Z","end":"2026-07-09T23:51:47.1532Z","steps":["trace[1244892362] 'agreement among raft nodes before linearized reading' (duration: 282.874455ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:47.15344Z","caller":"traceutil/trace.go:171","msg":"trace[14156788] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"464.322858ms","start":"2026-07-09T23:51:46.689058Z","end":"2026-07-09T23:51:47.153381Z","steps":["trace[14156788] 'process raft request' (duration: 463.215641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:47.153563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.047164ms","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-07-09T23:51:47.153673Z","caller":"traceutil/trace.go:171","msg":"trace[749195250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1072; }","duration":"647.192588ms","start":"2026-07-09T23:51:46.506469Z","end":"2026-07-09T23:51:47.153661Z","steps":["trace[749195250] 'agreement among raft nodes before linearized reading' (duration: 647.025743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:51:47.15364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:46.689033Z","time spent":"464.494614ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:51:47.153712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:46.506449Z","time spent":"647.253671ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:51:47.15371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.695848ms","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-07-09T23:51:47.153905Z","caller":"traceutil/trace.go:171","msg":"trace[1279958744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"218.906985ms","start":"2026-07-09T23:51:46.934961Z","end":"2026-07-09T23:51:47.153868Z","steps":["trace[1279958744] 'agreement among raft nodes before linearized reading' (duration: 218.531813ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:47.154009Z","caller":"traceutil/trace.go:171","msg":"trace[2046341137] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"676.713067ms","start":"2026-07-09T23:51:46.475938Z","end":"2026-07-09T23:51:47.152651Z","steps":["trace[2046341137] 'process raft request' (duration: 605.131955ms)","trace[2046341137] 'compare' (duration: 70.826947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:51:47.15411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.755039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T23:51:47.154162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:51:46.475912Z","time spent":"678.165095ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:51:47.154204Z","caller":"traceutil/trace.go:171","msg":"trace[481459317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"259.884203ms","start":"2026-07-09T23:51:46.894305Z","end":"2026-07-09T23:51:47.154189Z","steps":["trace[481459317] 'agreement among raft nodes before linearized reading' (duration: 259.676606ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:47.300212Z","caller":"traceutil/trace.go:171","msg":"trace[266401921] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"141.705546ms","start":"2026-07-09T23:51:47.158481Z","end":"2026-07-09T23:51:47.300187Z","steps":["trace[266401921] 'process raft request' (duration: 138.252851ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:47.330012Z","caller":"traceutil/trace.go:171","msg":"trace[733462919] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"171.269345ms","start":"2026-07-09T23:51:47.158718Z","end":"2026-07-09T23:51:47.329988Z","steps":["trace[733462919] 'process raft request' (duration: 171.086989ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:47.330373Z","caller":"traceutil/trace.go:171","msg":"trace[418002166] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"170.096537ms","start":"2026-07-09T23:51:47.16025Z","end":"2026-07-09T23:51:47.330346Z","steps":["trace[418002166] 'process raft request' (duration: 169.992744ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:51:47.330437Z","caller":"traceutil/trace.go:171","msg":"trace[1992696990] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"170.963386ms","start":"2026-07-09T23:51:47.159452Z","end":"2026-07-09T23:51:47.330415Z","steps":["trace[1992696990] 'process raft request' (duration: 170.48821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:05.098924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270105899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:52:05.292845Z","caller":"traceutil/trace.go:171","msg":"trace[790234223] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"694.968437ms","start":"2026-07-09T23:52:04.597852Z","end":"2026-07-09T23:52:05.292821Z","steps":["trace[790234223] 'read index received' (duration: 694.870554ms)","trace[790234223] 'applied index is now lower than readState.Index' (duration: 94.713µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:05.293092Z","caller":"traceutil/trace.go:171","msg":"trace[1892558578] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"869.510329ms","start":"2026-07-09T23:52:04.423563Z","end":"2026-07-09T23:52:05.293074Z","steps":["trace[1892558578] 'process raft request' (duration: 869.099616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:05.293203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:04.423542Z","time spent":"869.597702ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:05.293223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.334969ms","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-07-09T23:52:05.293305Z","caller":"traceutil/trace.go:171","msg":"trace[215474240] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1182; }","duration":"695.467683ms","start":"2026-07-09T23:52:04.597817Z","end":"2026-07-09T23:52:05.293285Z","steps":["trace[215474240] 'agreement among raft nodes before linearized reading' (duration: 695.236895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:05.29335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:04.597795Z","time spent":"695.545326ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":102,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:52:05.793447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270105902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:52:06.294119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270105902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:52:06.372662Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079992168s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T23:52:06.374688Z","caller":"traceutil/trace.go:171","msg":"trace[1656498864] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"1.081755235s","start":"2026-07-09T23:52:05.292913Z","end":"2026-07-09T23:52:06.374668Z","steps":["trace[1656498864] 'read index received' (duration: 1.079934246s)","trace[1656498864] 'applied index is now lower than readState.Index' (duration: 1.820208ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:06.374711Z","caller":"traceutil/trace.go:171","msg":"trace[1533868197] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"1.102391674s","start":"2026-07-09T23:52:05.27229Z","end":"2026-07-09T23:52:06.374682Z","steps":["trace[1533868197] 'process raft request' (duration: 1.100512094s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.374799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264193959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:06.374825Z","caller":"traceutil/trace.go:171","msg":"trace[1411151390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"1.264268791s","start":"2026-07-09T23:52:05.110548Z","end":"2026-07-09T23:52:06.374817Z","steps":["trace[1411151390] 'agreement among raft nodes before linearized reading' (duration: 1.26421784s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.374847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.110531Z","time spent":"1.264311563s","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:52:06.374889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.272272Z","time spent":"1.102499567s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:06.374956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.138547ms","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-07-09T23:52:06.375016Z","caller":"traceutil/trace.go:171","msg":"trace[647185336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1183; }","duration":"973.2403ms","start":"2026-07-09T23:52:05.401756Z","end":"2026-07-09T23:52:06.374997Z","steps":["trace[647185336] 'agreement among raft nodes before linearized reading' (duration: 973.064104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.375058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.401735Z","time spent":"973.313633ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T23:52:06.375123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.120495ms","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-07-09T23:52:06.375145Z","caller":"traceutil/trace.go:171","msg":"trace[1465082556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"941.183437ms","start":"2026-07-09T23:52:05.433955Z","end":"2026-07-09T23:52:06.375139Z","steps":["trace[1465082556] 'agreement among raft nodes before linearized reading' (duration: 941.096794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.375169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.433933Z","time spent":"941.230808ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:52:06.375136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571368946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:06.375278Z","caller":"traceutil/trace.go:171","msg":"trace[1328060128] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1183; }","duration":"1.571611063s","start":"2026-07-09T23:52:04.803646Z","end":"2026-07-09T23:52:06.375257Z","steps":["trace[1328060128] 'agreement among raft nodes before linearized reading' (duration: 1.571374396s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.375366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:04.803622Z","time spent":"1.571728047s","remote":"127.0.0.1:42976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:52:06.375128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.965396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:06.376347Z","caller":"traceutil/trace.go:171","msg":"trace[1654605615] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1183; }","duration":"836.247187ms","start":"2026-07-09T23:52:05.54008Z","end":"2026-07-09T23:52:06.376327Z","steps":["trace[1654605615] 'agreement among raft nodes before linearized reading' (duration: 835.005767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.376401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.540058Z","time spent":"836.322129ms","remote":"127.0.0.1:42930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:52:06.376807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.747084ms","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-07-09T23:52:06.376846Z","caller":"traceutil/trace.go:171","msg":"trace[399083874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1183; }","duration":"940.819676ms","start":"2026-07-09T23:52:05.436015Z","end":"2026-07-09T23:52:06.376834Z","steps":["trace[399083874] 'agreement among raft nodes before linearized reading' (duration: 938.965586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.376908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.435996Z","time spent":"940.904038ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:52:06.377003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.469907ms","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-07-09T23:52:06.377109Z","caller":"traceutil/trace.go:171","msg":"trace[1574731836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"906.48504ms","start":"2026-07-09T23:52:05.470599Z","end":"2026-07-09T23:52:06.377084Z","steps":["trace[1574731836] 'agreement among raft nodes before linearized reading' (duration: 904.249188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.377182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.470584Z","time spent":"906.564742ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T23:52:20.020932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.881918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:52:20.020994Z","caller":"traceutil/trace.go:171","msg":"trace[703724713] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1229; }","duration":"441.988161ms","start":"2026-07-09T23:52:19.578989Z","end":"2026-07-09T23:52:20.020977Z","steps":["trace[703724713] 'count revisions from in-memory index tree' (duration: 441.779984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:20.021019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:19.57896Z","time spent":"442.051073ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:52:22.342578Z","caller":"traceutil/trace.go:171","msg":"trace[1421068127] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"231.429148ms","start":"2026-07-09T23:52:22.111122Z","end":"2026-07-09T23:52:22.342551Z","steps":["trace[1421068127] 'read index received' (duration: 231.259552ms)","trace[1421068127] 'applied index is now lower than readState.Index' (duration: 168.276µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:22.342682Z","caller":"traceutil/trace.go:171","msg":"trace[1589173677] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"321.572116ms","start":"2026-07-09T23:52:22.021079Z","end":"2026-07-09T23:52:22.342651Z","steps":["trace[1589173677] 'process raft request' (duration: 321.304207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:22.342743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.598294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:22.342776Z","caller":"traceutil/trace.go:171","msg":"trace[2062682924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"231.683767ms","start":"2026-07-09T23:52:22.111083Z","end":"2026-07-09T23:52:22.342767Z","steps":["trace[2062682924] 'agreement among raft nodes before linearized reading' (duration: 231.570033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:22.342833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:22.02106Z","time spent":"321.6892ms","remote":"127.0.0.1:42868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:22.5435Z","caller":"traceutil/trace.go:171","msg":"trace[1155240283] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"114.856007ms","start":"2026-07-09T23:52:22.428624Z","end":"2026-07-09T23:52:22.54348Z","steps":["trace[1155240283] 'process raft request' (duration: 114.742253ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:22.611634Z","caller":"traceutil/trace.go:171","msg":"trace[733474352] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"120.800837ms","start":"2026-07-09T23:52:22.490796Z","end":"2026-07-09T23:52:22.611597Z","steps":["trace[733474352] 'process raft request' (duration: 120.337732ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:22.612096Z","caller":"traceutil/trace.go:171","msg":"trace[1400131864] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"121.143768ms","start":"2026-07-09T23:52:22.490918Z","end":"2026-07-09T23:52:22.612061Z","steps":["trace[1400131864] 'process raft request' (duration: 120.325252ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:22.613004Z","caller":"traceutil/trace.go:171","msg":"trace[1672920157] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"117.101368ms","start":"2026-07-09T23:52:22.495882Z","end":"2026-07-09T23:52:22.612983Z","steps":["trace[1672920157] 'process raft request' (duration: 116.035424ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:24.22996Z","caller":"traceutil/trace.go:171","msg":"trace[1700599] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"122.060997ms","start":"2026-07-09T23:52:24.10786Z","end":"2026-07-09T23:52:24.229921Z","steps":["trace[1700599] 'read index received' (duration: 78.176196ms)","trace[1700599] 'applied index is now lower than readState.Index' (duration: 43.882971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:24.230158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.277113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:24.230201Z","caller":"traceutil/trace.go:171","msg":"trace[1858079977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"122.364026ms","start":"2026-07-09T23:52:24.107822Z","end":"2026-07-09T23:52:24.230186Z","steps":["trace[1858079977] 'agreement among raft nodes before linearized reading' (duration: 122.210521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:26.312484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270106113,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:52:26.438243Z","caller":"traceutil/trace.go:171","msg":"trace[835930980] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"626.676147ms","start":"2026-07-09T23:52:25.811547Z","end":"2026-07-09T23:52:26.438223Z","steps":["trace[835930980] 'read index received' (duration: 626.340966ms)","trace[835930980] 'applied index is now lower than readState.Index' (duration: 334.021µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:26.438288Z","caller":"traceutil/trace.go:171","msg":"trace[1913185724] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"932.646705ms","start":"2026-07-09T23:52:25.505621Z","end":"2026-07-09T23:52:26.438267Z","steps":["trace[1913185724] 'process raft request' (duration: 932.442339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:26.438397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:25.505608Z","time spent":"932.735568ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:26.438394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.609649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:26.438429Z","caller":"traceutil/trace.go:171","msg":"trace[1414441908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"327.683701ms","start":"2026-07-09T23:52:26.110736Z","end":"2026-07-09T23:52:26.438419Z","steps":["trace[1414441908] 'agreement among raft nodes before linearized reading' (duration: 327.613949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:26.438419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.840912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T23:52:26.438473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:26.110721Z","time spent":"327.744464ms","remote":"127.0.0.1:42694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T23:52:26.438513Z","caller":"traceutil/trace.go:171","msg":"trace[209999006] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1282; }","duration":"626.980496ms","start":"2026-07-09T23:52:25.811508Z","end":"2026-07-09T23:52:26.438488Z","steps":["trace[209999006] 'agreement among raft nodes before linearized reading' (duration: 626.816611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:26.438602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:25.811488Z","time spent":"627.09259ms","remote":"127.0.0.1:34830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:52:26.791759Z","caller":"traceutil/trace.go:171","msg":"trace[2040552761] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"129.034ms","start":"2026-07-09T23:52:26.6627Z","end":"2026-07-09T23:52:26.791734Z","steps":["trace[2040552761] 'read index received' (duration: 89.777636ms)","trace[2040552761] 'applied index is now lower than readState.Index' (duration: 39.255224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:26.79201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.286538ms","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-07-09T23:52:26.792055Z","caller":"traceutil/trace.go:171","msg":"trace[1240691969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1284; }","duration":"129.36303ms","start":"2026-07-09T23:52:26.662683Z","end":"2026-07-09T23:52:26.792046Z","steps":["trace[1240691969] 'agreement among raft nodes before linearized reading' (duration: 129.218995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:26.79207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.584475ms","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-07-09T23:52:26.792145Z","caller":"traceutil/trace.go:171","msg":"trace[257642465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1284; }","duration":"128.640517ms","start":"2026-07-09T23:52:26.663466Z","end":"2026-07-09T23:52:26.792106Z","steps":["trace[257642465] 'agreement among raft nodes before linearized reading' (duration: 128.4227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:26.792369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.388638ms","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-07-09T23:52:26.792468Z","caller":"traceutil/trace.go:171","msg":"trace[636199775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1284; }","duration":"128.532483ms","start":"2026-07-09T23:52:26.663911Z","end":"2026-07-09T23:52:26.792444Z","steps":["trace[636199775] 'agreement among raft nodes before linearized reading' (duration: 128.168702ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:26.792516Z","caller":"traceutil/trace.go:171","msg":"trace[1742793786] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"130.657542ms","start":"2026-07-09T23:52:26.6612Z","end":"2026-07-09T23:52:26.791857Z","steps":["trace[1742793786] 'process raft request' (duration: 91.326936ms)","trace[1742793786] 'compare' (duration: 39.096188ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:27.180896Z","caller":"traceutil/trace.go:171","msg":"trace[846451759] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"382.727329ms","start":"2026-07-09T23:52:26.798142Z","end":"2026-07-09T23:52:27.180869Z","steps":["trace[846451759] 'process raft request' (duration: 382.521792ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:27.180989Z","caller":"traceutil/trace.go:171","msg":"trace[467753640] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"381.309454ms","start":"2026-07-09T23:52:26.799594Z","end":"2026-07-09T23:52:27.180903Z","steps":["trace[467753640] 'process raft request' (duration: 381.19572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:27.181016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:26.798133Z","time spent":"382.818861ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:27.181132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:26.799586Z","time spent":"381.468099ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:28.332357Z","caller":"traceutil/trace.go:171","msg":"trace[1843558140] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"163.689314ms","start":"2026-07-09T23:52:28.168634Z","end":"2026-07-09T23:52:28.332323Z","steps":["trace[1843558140] 'process raft request' (duration: 163.2391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:28.943339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021651270106128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:52:29.549308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.130071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:52:29.549515Z","caller":"traceutil/trace.go:171","msg":"trace[2020842072] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"1.106906008s","start":"2026-07-09T23:52:28.442592Z","end":"2026-07-09T23:52:29.549498Z","steps":["trace[2020842072] 'read index received' (duration: 510.448046ms)","trace[2020842072] 'applied index is now lower than readState.Index' (duration: 596.456902ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:29.549602Z","caller":"traceutil/trace.go:171","msg":"trace[1854537446] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"1.210448714s","start":"2026-07-09T23:52:28.339143Z","end":"2026-07-09T23:52:29.549591Z","steps":["trace[1854537446] 'process raft request' (duration: 613.902439ms)","trace[1854537446] 'compare' (duration: 595.997157ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:29.54966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.339119Z","time spent":"1.210507995s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:29.549947Z","caller":"traceutil/trace.go:171","msg":"trace[225927195] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"904.994352ms","start":"2026-07-09T23:52:28.644941Z","end":"2026-07-09T23:52:29.549935Z","steps":["trace[225927195] 'process raft request' (duration: 904.496546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.644914Z","time spent":"905.070745ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:29.550173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10756494s","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-07-09T23:52:29.550244Z","caller":"traceutil/trace.go:171","msg":"trace[109040719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"1.107682693s","start":"2026-07-09T23:52:28.442544Z","end":"2026-07-09T23:52:29.550226Z","steps":["trace[109040719] 'agreement among raft nodes before linearized reading' (duration: 1.107461256s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.821669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T23:52:29.550291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.442526Z","time spent":"1.107756645s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T23:52:29.550309Z","caller":"traceutil/trace.go:171","msg":"trace[338350307] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1290; }","duration":"936.916271ms","start":"2026-07-09T23:52:28.61338Z","end":"2026-07-09T23:52:29.550296Z","steps":["trace[338350307] 'agreement among raft nodes before linearized reading' (duration: 936.821968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.613357Z","time spent":"936.981164ms","remote":"127.0.0.1:43020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:52:29.550383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.828833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:52:29.550613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.280873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T23:52:29.550613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.666337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:29.550633Z","caller":"traceutil/trace.go:171","msg":"trace[2057979205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"366.361136ms","start":"2026-07-09T23:52:29.184266Z","end":"2026-07-09T23:52:29.550627Z","steps":["trace[2057979205] 'agreement among raft nodes before linearized reading' (duration: 366.117388ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:29.550634Z","caller":"traceutil/trace.go:171","msg":"trace[1468230632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"560.115302ms","start":"2026-07-09T23:52:28.990504Z","end":"2026-07-09T23:52:29.550619Z","steps":["trace[1468230632] 'agreement among raft nodes before linearized reading' (duration: 559.825523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.184246Z","time spent":"366.401898ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T23:52:29.550648Z","caller":"traceutil/trace.go:171","msg":"trace[552867018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"439.910344ms","start":"2026-07-09T23:52:29.110728Z","end":"2026-07-09T23:52:29.550638Z","steps":["trace[552867018] 'agreement among raft nodes before linearized reading' (duration: 439.679127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.990484Z","time spent":"560.179554ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:52:29.550678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.110708Z","time spent":"439.965046ms","remote":"127.0.0.1:42694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:52:29.550693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.864679ms","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-07-09T23:52:29.550748Z","caller":"traceutil/trace.go:171","msg":"trace[1034612197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"364.948411ms","start":"2026-07-09T23:52:29.185787Z","end":"2026-07-09T23:52:29.550735Z","steps":["trace[1034612197] 'agreement among raft nodes before linearized reading' (duration: 364.611021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.333117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-09T23:52:29.550793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.185775Z","time spent":"365.006153ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T23:52:29.550808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.443864ms","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-07-09T23:52:29.55081Z","caller":"traceutil/trace.go:171","msg":"trace[603001006] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1290; }","duration":"673.397039ms","start":"2026-07-09T23:52:28.877404Z","end":"2026-07-09T23:52:29.550801Z","steps":["trace[603001006] 'agreement among raft nodes before linearized reading' (duration: 673.287735ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:29.55083Z","caller":"traceutil/trace.go:171","msg":"trace[1416106795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"732.485664ms","start":"2026-07-09T23:52:28.818338Z","end":"2026-07-09T23:52:29.550823Z","steps":["trace[1416106795] 'agreement among raft nodes before linearized reading' (duration: 732.437263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.55084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.877384Z","time spent":"673.44999ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-07-09T23:52:29.550855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.818324Z","time spent":"732.527596ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:52:29.550761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.366478ms","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-07-09T23:52:29.550887Z","caller":"traceutil/trace.go:171","msg":"trace[1410115785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"751.519493ms","start":"2026-07-09T23:52:28.799357Z","end":"2026-07-09T23:52:29.550877Z","steps":["trace[1410115785] 'agreement among raft nodes before linearized reading' (duration: 751.366798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.550927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:28.799339Z","time spent":"751.579054ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T23:52:29.908173Z","caller":"traceutil/trace.go:171","msg":"trace[790207970] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"353.055741ms","start":"2026-07-09T23:52:29.555045Z","end":"2026-07-09T23:52:29.908101Z","steps":["trace[790207970] 'process raft request' (duration: 352.747831ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:29.908407Z","caller":"traceutil/trace.go:171","msg":"trace[401866865] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"352.741791ms","start":"2026-07-09T23:52:29.555634Z","end":"2026-07-09T23:52:29.908376Z","steps":["trace[401866865] 'process raft request' (duration: 352.40078ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:29.908424Z","caller":"traceutil/trace.go:171","msg":"trace[391619776] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"352.583486ms","start":"2026-07-09T23:52:29.555822Z","end":"2026-07-09T23:52:29.908405Z","steps":["trace[391619776] 'process raft request' (duration: 352.508573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.908466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.555028Z","time spent":"353.246548ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:29.908562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.55563Z","time spent":"352.847245ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:29.908587Z","caller":"traceutil/trace.go:171","msg":"trace[633054751] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"352.905306ms","start":"2026-07-09T23:52:29.555664Z","end":"2026-07-09T23:52:29.90857Z","steps":["trace[633054751] 'process raft request' (duration: 352.603056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.908604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.555816Z","time spent":"352.6884ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:29.908663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.55566Z","time spent":"352.963639ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:29.90877Z","caller":"traceutil/trace.go:171","msg":"trace[1310980205] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"352.593466ms","start":"2026-07-09T23:52:29.555575Z","end":"2026-07-09T23:52:29.908169Z","steps":["trace[1310980205] 'process raft request' (duration: 352.40457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.908892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.555558Z","time spent":"353.251708ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:29.912761Z","caller":"traceutil/trace.go:171","msg":"trace[1451290344] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"331.014118ms","start":"2026-07-09T23:52:29.581685Z","end":"2026-07-09T23:52:29.912699Z","steps":["trace[1451290344] 'process raft request' (duration: 330.882584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:29.912901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:29.581668Z","time spent":"331.158762ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:30.075063Z","caller":"traceutil/trace.go:171","msg":"trace[522211561] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"153.355535ms","start":"2026-07-09T23:52:29.921677Z","end":"2026-07-09T23:52:30.075033Z","steps":["trace[522211561] 'process raft request' (duration: 114.470814ms)","trace[522211561] 'compare' (duration: 38.750877ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:31.319499Z","caller":"traceutil/trace.go:171","msg":"trace[302438141] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"350.969164ms","start":"2026-07-09T23:52:30.968484Z","end":"2026-07-09T23:52:31.319453Z","steps":["trace[302438141] 'read index received' (duration: 350.791299ms)","trace[302438141] 'applied index is now lower than readState.Index' (duration: 176.545µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:31.319927Z","caller":"traceutil/trace.go:171","msg":"trace[1840779557] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"473.438335ms","start":"2026-07-09T23:52:30.846471Z","end":"2026-07-09T23:52:31.319909Z","steps":["trace[1840779557] 'process raft request' (duration: 472.793094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:31.319987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.518282ms","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-07-09T23:52:31.320038Z","caller":"traceutil/trace.go:171","msg":"trace[288828401] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1298; }","duration":"351.601816ms","start":"2026-07-09T23:52:30.968417Z","end":"2026-07-09T23:52:31.320019Z","steps":["trace[288828401] 'agreement among raft nodes before linearized reading' (duration: 351.544454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:31.320082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:30.96839Z","time spent":"351.682287ms","remote":"127.0.0.1:56776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:52:31.320083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:30.846451Z","time spent":"473.523947ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:31.319914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.302719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:52:31.320459Z","caller":"traceutil/trace.go:171","msg":"trace[785809300] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1298; }","duration":"254.905218ms","start":"2026-07-09T23:52:31.065536Z","end":"2026-07-09T23:52:31.320441Z","steps":["trace[785809300] 'agreement among raft nodes before linearized reading' (duration: 254.281538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.116636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257453653s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:52:33.116746Z","caller":"traceutil/trace.go:171","msg":"trace[1150492677] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"1.204448922s","start":"2026-07-09T23:52:31.912281Z","end":"2026-07-09T23:52:33.11673Z","steps":["trace[1150492677] 'read index received' (duration: 54.042µs)","trace[1150492677] 'applied index is now lower than readState.Index' (duration: 1.2043937s)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:33.116831Z","caller":"traceutil/trace.go:171","msg":"trace[1046140925] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.560770586s","start":"2026-07-09T23:52:31.556049Z","end":"2026-07-09T23:52:33.11682Z","steps":["trace[1046140925] 'process raft request' (duration: 303.051764ms)","trace[1046140925] 'compare' (duration: 1.251965889s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:33.116894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:31.556023Z","time spent":"1.560837218s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:33.117267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204976669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T23:52:33.117323Z","caller":"traceutil/trace.go:171","msg":"trace[1596108052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"1.205069402s","start":"2026-07-09T23:52:31.91224Z","end":"2026-07-09T23:52:33.117309Z","steps":["trace[1596108052] 'agreement among raft nodes before linearized reading' (duration: 1.204906807s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.117342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204026898s","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-07-09T23:52:33.117367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:31.912221Z","time spent":"1.205133144s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T23:52:33.117349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203989617s","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-07-09T23:52:33.117398Z","caller":"traceutil/trace.go:171","msg":"trace[595234824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"1.204106181s","start":"2026-07-09T23:52:31.913279Z","end":"2026-07-09T23:52:33.117385Z","steps":["trace[595234824] 'agreement among raft nodes before linearized reading' (duration: 1.203946436s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.117431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:31.913117Z","time spent":"1.204293927s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:52:33.117441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204160663s","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-07-09T23:52:33.117346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204042889s","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-07-09T23:52:33.11749Z","caller":"traceutil/trace.go:171","msg":"trace[2107226735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"1.204220625s","start":"2026-07-09T23:52:31.913256Z","end":"2026-07-09T23:52:33.117477Z","steps":["trace[2107226735] 'agreement among raft nodes before linearized reading' (duration: 1.204127842s)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:33.117436Z","caller":"traceutil/trace.go:171","msg":"trace[523575170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1299; }","duration":"1.20409769s","start":"2026-07-09T23:52:31.913322Z","end":"2026-07-09T23:52:33.11742Z","steps":["trace[523575170] 'agreement among raft nodes before linearized reading' (duration: 1.203885793s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.117526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007373781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:33.117592Z","caller":"traceutil/trace.go:171","msg":"trace[198384092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"1.007475683s","start":"2026-07-09T23:52:32.110107Z","end":"2026-07-09T23:52:33.117583Z","steps":["trace[198384092] 'agreement among raft nodes before linearized reading' (duration: 1.007402821s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.117628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:32.110078Z","time spent":"1.007541676s","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T23:52:33.117507Z","caller":"traceutil/trace.go:171","msg":"trace[1031285973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"1.204270906s","start":"2026-07-09T23:52:31.913229Z","end":"2026-07-09T23:52:33.1175Z","steps":["trace[1031285973] 'agreement among raft nodes before linearized reading' (duration: 1.203965096s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.117701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:31.913216Z","time spent":"1.204477253s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T23:52:33.11752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:31.913247Z","time spent":"1.204266976s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:52:33.11749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.44774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:52:33.117645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:31.913316Z","time spent":"1.204313208s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T23:52:33.117852Z","caller":"traceutil/trace.go:171","msg":"trace[306408658] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1299; }","duration":"273.826572ms","start":"2026-07-09T23:52:32.844009Z","end":"2026-07-09T23:52:33.117835Z","steps":["trace[306408658] 'agreement among raft nodes before linearized reading' (duration: 273.45057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:33.712223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.270641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:52:33.712458Z","caller":"traceutil/trace.go:171","msg":"trace[1032809503] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"591.008028ms","start":"2026-07-09T23:52:33.121426Z","end":"2026-07-09T23:52:33.712434Z","steps":["trace[1032809503] 'process raft request' (duration: 329.353935ms)","trace[1032809503] 'compare' (duration: 260.953511ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:33.712805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:33.121412Z","time spent":"591.267966ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:34.065323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.395922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:52:34.065697Z","caller":"traceutil/trace.go:171","msg":"trace[142852296] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"943.891703ms","start":"2026-07-09T23:52:33.121785Z","end":"2026-07-09T23:52:34.065677Z","steps":["trace[142852296] 'process raft request' (duration: 725.071317ms)","trace[142852296] 'compare' (duration: 218.118404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:34.065756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:33.121764Z","time spent":"943.966926ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:34.066188Z","caller":"traceutil/trace.go:171","msg":"trace[1091710297] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"944.248055ms","start":"2026-07-09T23:52:33.121932Z","end":"2026-07-09T23:52:34.06618Z","steps":["trace[1091710297] 'process raft request' (duration: 943.47129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:34.066231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:33.121921Z","time spent":"944.290086ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:34.066222Z","caller":"traceutil/trace.go:171","msg":"trace[2012349716] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1356; }","duration":"940.057251ms","start":"2026-07-09T23:52:33.12614Z","end":"2026-07-09T23:52:34.066197Z","steps":["trace[2012349716] 'read index received' (duration: 324.652014ms)","trace[2012349716] 'applied index is now lower than readState.Index' (duration: 615.403867ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:34.066564Z","caller":"traceutil/trace.go:171","msg":"trace[1990682134] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"944.358709ms","start":"2026-07-09T23:52:33.122182Z","end":"2026-07-09T23:52:34.066541Z","steps":["trace[1990682134] 'process raft request' (duration: 943.969366ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:34.067011Z","caller":"traceutil/trace.go:171","msg":"trace[517212032] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"944.322747ms","start":"2026-07-09T23:52:33.122114Z","end":"2026-07-09T23:52:34.066436Z","steps":["trace[517212032] 'process raft request' (duration: 943.976046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:34.06716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:33.122105Z","time spent":"944.978098ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:34.067247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:33.122164Z","time spent":"944.984538ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:34.067531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.370532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:34.067627Z","caller":"traceutil/trace.go:171","msg":"trace[124847104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"941.501417ms","start":"2026-07-09T23:52:33.126109Z","end":"2026-07-09T23:52:34.067611Z","steps":["trace[124847104] 'agreement among raft nodes before linearized reading' (duration: 941.203987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:34.067974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:33.126101Z","time spent":"941.861288ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:52:34.979119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.546262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-09T23:52:34.979229Z","caller":"traceutil/trace.go:171","msg":"trace[994848567] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"672.053716ms","start":"2026-07-09T23:52:34.307154Z","end":"2026-07-09T23:52:34.979208Z","steps":["trace[994848567] 'read index received' (duration: 53.352µs)","trace[994848567] 'applied index is now lower than readState.Index' (duration: 671.998584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:34.979426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.255062ms","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-07-09T23:52:34.979486Z","caller":"traceutil/trace.go:171","msg":"trace[679611237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1304; }","duration":"672.327994ms","start":"2026-07-09T23:52:34.307143Z","end":"2026-07-09T23:52:34.979471Z","steps":["trace[679611237] 'agreement among raft nodes before linearized reading' (duration: 672.221811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:34.97951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:34.307114Z","time spent":"672.390466ms","remote":"127.0.0.1:56734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:52:34.979634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.636902ms","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":"info","ts":"2026-07-09T23:52:34.979694Z","caller":"traceutil/trace.go:171","msg":"trace[771774093] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1304; }","duration":"422.768277ms","start":"2026-07-09T23:52:34.556912Z","end":"2026-07-09T23:52:34.97968Z","steps":["trace[771774093] 'agreement among raft nodes before linearized reading' (duration: 422.5692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:34.979729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:34.55689Z","time spent":"422.830679ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-09T23:52:35.18963Z","caller":"traceutil/trace.go:171","msg":"trace[1987671007] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"110.675113ms","start":"2026-07-09T23:52:35.078928Z","end":"2026-07-09T23:52:35.189603Z","steps":["trace[1987671007] 'read index received' (duration: 110.625832ms)","trace[1987671007] 'applied index is now lower than readState.Index' (duration: 48.041µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:35.189676Z","caller":"traceutil/trace.go:171","msg":"trace[651596750] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"205.950835ms","start":"2026-07-09T23:52:34.983681Z","end":"2026-07-09T23:52:35.189632Z","steps":["trace[651596750] 'process raft request' (duration: 205.740398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:35.189886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.942842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-09T23:52:35.189949Z","caller":"traceutil/trace.go:171","msg":"trace[1645933234] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1305; }","duration":"111.067516ms","start":"2026-07-09T23:52:35.078864Z","end":"2026-07-09T23:52:35.189931Z","steps":["trace[1645933234] 'agreement among raft nodes before linearized reading' (duration: 110.855019ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:36.300911Z","caller":"traceutil/trace.go:171","msg":"trace[1055407075] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"230.022573ms","start":"2026-07-09T23:52:36.070865Z","end":"2026-07-09T23:52:36.300888Z","steps":["trace[1055407075] 'read index received' (duration: 229.777756ms)","trace[1055407075] 'applied index is now lower than readState.Index' (duration: 243.717µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:36.300957Z","caller":"traceutil/trace.go:171","msg":"trace[756844249] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"579.411008ms","start":"2026-07-09T23:52:35.721527Z","end":"2026-07-09T23:52:36.300938Z","steps":["trace[756844249] 'process raft request' (duration: 579.222992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:36.30122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.334144ms","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-07-09T23:52:36.301229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:35.721504Z","time spent":"579.631375ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:36.301268Z","caller":"traceutil/trace.go:171","msg":"trace[918902816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"230.423516ms","start":"2026-07-09T23:52:36.070835Z","end":"2026-07-09T23:52:36.301258Z","steps":["trace[918902816] 'agreement among raft nodes before linearized reading' (duration: 230.271081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:36.301226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.310373ms","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-07-09T23:52:36.301366Z","caller":"traceutil/trace.go:171","msg":"trace[292872877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"230.468718ms","start":"2026-07-09T23:52:36.07089Z","end":"2026-07-09T23:52:36.301359Z","steps":["trace[292872877] 'agreement among raft nodes before linearized reading' (duration: 230.238191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:36.301529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.600742ms","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-07-09T23:52:36.301576Z","caller":"traceutil/trace.go:171","msg":"trace[1263750876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"230.665665ms","start":"2026-07-09T23:52:36.070893Z","end":"2026-07-09T23:52:36.301558Z","steps":["trace[1263750876] 'agreement among raft nodes before linearized reading' (duration: 230.538641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:36.301622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.623153ms","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-07-09T23:52:36.301671Z","caller":"traceutil/trace.go:171","msg":"trace[1405253024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"230.717976ms","start":"2026-07-09T23:52:36.070939Z","end":"2026-07-09T23:52:36.301657Z","steps":["trace[1405253024] 'agreement among raft nodes before linearized reading' (duration: 230.562421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:37.347255Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.041947404s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T23:52:38.24667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.459794ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:38.246821Z","caller":"traceutil/trace.go:171","msg":"trace[684448845] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1308; }","duration":"509.6249ms","start":"2026-07-09T23:52:37.737173Z","end":"2026-07-09T23:52:38.246798Z","steps":["trace[684448845] 'range keys from in-memory index tree' (duration: 509.435884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.247686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.159187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:52:38.247773Z","caller":"traceutil/trace.go:171","msg":"trace[1982987036] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"1.942556646s","start":"2026-07-09T23:52:36.305202Z","end":"2026-07-09T23:52:38.247758Z","steps":["trace[1982987036] 'process raft request' (duration: 1.042262414s)","trace[1982987036] 'compare' (duration: 900.081555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:38.247856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:36.30518Z","time spent":"1.942640229s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:38.565502Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218087858s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T23:52:38.566004Z","caller":"traceutil/trace.go:171","msg":"trace[235980307] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1366; }","duration":"1.677295868s","start":"2026-07-09T23:52:36.888692Z","end":"2026-07-09T23:52:38.565988Z","steps":["trace[235980307] 'read index received' (duration: 458.742775ms)","trace[235980307] 'applied index is now lower than readState.Index' (duration: 1.218552333s)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:38.566212Z","caller":"traceutil/trace.go:171","msg":"trace[480054987] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"2.260756735s","start":"2026-07-09T23:52:36.305445Z","end":"2026-07-09T23:52:38.566202Z","steps":["trace[480054987] 'process raft request' (duration: 2.260360572s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.566291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:36.305428Z","time spent":"2.260807507s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:38.566462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677783574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:52:38.566496Z","caller":"traceutil/trace.go:171","msg":"trace[1793531931] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1313; }","duration":"1.677861827s","start":"2026-07-09T23:52:36.888625Z","end":"2026-07-09T23:52:38.566486Z","steps":["trace[1793531931] 'agreement among raft nodes before linearized reading' (duration: 1.677786724s)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:38.566473Z","caller":"traceutil/trace.go:171","msg":"trace[285570957] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.369085768s","start":"2026-07-09T23:52:37.197355Z","end":"2026-07-09T23:52:38.566441Z","steps":["trace[285570957] 'process raft request' (duration: 1.368604913s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.566521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:36.888605Z","time spent":"1.677909778s","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:52:38.566551Z","caller":"traceutil/trace.go:171","msg":"trace[567294215] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"2.261019833s","start":"2026-07-09T23:52:36.305516Z","end":"2026-07-09T23:52:38.566536Z","steps":["trace[567294215] 'process raft request' (duration: 2.260390433s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.566612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:37.197326Z","time spent":"1.369211392s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:52:38.566632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:36.305491Z","time spent":"2.261103406s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:38.566746Z","caller":"traceutil/trace.go:171","msg":"trace[1430157043] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"2.260486737s","start":"2026-07-09T23:52:36.306248Z","end":"2026-07-09T23:52:38.566735Z","steps":["trace[1430157043] 'process raft request' (duration: 2.259690001s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.5668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:36.306222Z","time spent":"2.260547438s","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:38.588349Z","caller":"traceutil/trace.go:171","msg":"trace[1078101728] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"167.041523ms","start":"2026-07-09T23:52:38.42129Z","end":"2026-07-09T23:52:38.588331Z","steps":["trace[1078101728] 'process raft request' (duration: 166.887758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.588438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.15973ms","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-07-09T23:52:38.588474Z","caller":"traceutil/trace.go:171","msg":"trace[308034113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"283.287424ms","start":"2026-07-09T23:52:38.30518Z","end":"2026-07-09T23:52:38.588467Z","steps":["trace[308034113] 'agreement among raft nodes before linearized reading' (duration: 283.124119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.588482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.654065ms","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":"warn","ts":"2026-07-09T23:52:38.58847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477174509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:52:38.588537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495940928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-09T23:52:38.588544Z","caller":"traceutil/trace.go:171","msg":"trace[874297110] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1314; }","duration":"184.749768ms","start":"2026-07-09T23:52:38.403775Z","end":"2026-07-09T23:52:38.588524Z","steps":["trace[874297110] 'agreement among raft nodes before linearized reading' (duration: 184.669936ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:38.58856Z","caller":"traceutil/trace.go:171","msg":"trace[104347810] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1314; }","duration":"1.495980349s","start":"2026-07-09T23:52:37.092573Z","end":"2026-07-09T23:52:38.588553Z","steps":["trace[104347810] 'agreement among raft nodes before linearized reading' (duration: 1.495931238s)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:38.588566Z","caller":"traceutil/trace.go:171","msg":"trace[1806904312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"1.477287473s","start":"2026-07-09T23:52:37.11126Z","end":"2026-07-09T23:52:38.588547Z","steps":["trace[1806904312] 'agreement among raft nodes before linearized reading' (duration: 1.477117048s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.588586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:37.092566Z","time spent":"1.496016031s","remote":"127.0.0.1:42756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-09T23:52:38.588625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:37.1112Z","time spent":"1.477416217s","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:52:38.588777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496373292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114982"} {"level":"info","ts":"2026-07-09T23:52:38.588929Z","caller":"traceutil/trace.go:171","msg":"trace[1451871218] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1314; }","duration":"1.496501876s","start":"2026-07-09T23:52:37.092338Z","end":"2026-07-09T23:52:38.58884Z","steps":["trace[1451871218] 'agreement among raft nodes before linearized reading' (duration: 1.495961229s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.589405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:37.092319Z","time spent":"1.497066834s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":115004,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-09T23:52:46.564288Z","caller":"traceutil/trace.go:171","msg":"trace[360579293] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"220.575582ms","start":"2026-07-09T23:52:46.343689Z","end":"2026-07-09T23:52:46.564265Z","steps":["trace[360579293] 'process raft request' (duration: 220.387606ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:48.859108Z","caller":"traceutil/trace.go:171","msg":"trace[301927990] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"120.537338ms","start":"2026-07-09T23:52:48.738542Z","end":"2026-07-09T23:52:48.85908Z","steps":["trace[301927990] 'process raft request' (duration: 50.537153ms)","trace[301927990] 'compare' (duration: 69.85602ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:51.785213Z","caller":"traceutil/trace.go:171","msg":"trace[2115590248] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"115.276141ms","start":"2026-07-09T23:52:51.669903Z","end":"2026-07-09T23:52:51.785179Z","steps":["trace[2115590248] 'process raft request' (duration: 114.978071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:02.217175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.910343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:53:02.217282Z","caller":"traceutil/trace.go:171","msg":"trace[1190520367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1444; }","duration":"107.070428ms","start":"2026-07-09T23:53:02.110185Z","end":"2026-07-09T23:53:02.217256Z","steps":["trace[1190520367] 'range keys from in-memory index tree' (duration: 106.758898ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:42.369825Z","caller":"traceutil/trace.go:171","msg":"trace[1666986629] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"111.725082ms","start":"2026-07-09T23:53:42.258067Z","end":"2026-07-09T23:53:42.369792Z","steps":["trace[1666986629] 'process raft request' (duration: 111.630029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:56:58.695018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.075117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:56:58.695121Z","caller":"traceutil/trace.go:171","msg":"trace[1839611459] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2554; }","duration":"162.224411ms","start":"2026-07-09T23:56:58.53287Z","end":"2026-07-09T23:56:58.695095Z","steps":["trace[1839611459] 'count revisions from in-memory index tree' (duration: 162.000584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:56:58.695255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.312612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T23:56:58.695475Z","caller":"traceutil/trace.go:171","msg":"trace[13445783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2554; }","duration":"236.494298ms","start":"2026-07-09T23:56:58.458871Z","end":"2026-07-09T23:56:58.695365Z","steps":["trace[13445783] 'range keys from in-memory index tree' (duration: 235.969271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:56:59.346063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:56:58.895147Z","time spent":"450.910639ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T23:56:59.346044Z","caller":"traceutil/trace.go:171","msg":"trace[40741466] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2666; }","duration":"235.814976ms","start":"2026-07-09T23:56:59.110199Z","end":"2026-07-09T23:56:59.346014Z","steps":["trace[40741466] 'read index received' (duration: 235.64439ms)","trace[40741466] 'applied index is now lower than readState.Index' (duration: 169.066µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:56:59.34623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.971271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:56:59.346277Z","caller":"traceutil/trace.go:171","msg":"trace[68863405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2555; }","duration":"236.118576ms","start":"2026-07-09T23:56:59.110146Z","end":"2026-07-09T23:56:59.346265Z","steps":["trace[68863405] 'agreement among raft nodes before linearized reading' (duration: 235.972401ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:18.152355Z","caller":"traceutil/trace.go:171","msg":"trace[295883544] linearizableReadLoop","detail":"{readStateIndex:2766; appliedIndex:2765; }","duration":"122.405657ms","start":"2026-07-09T23:57:18.029926Z","end":"2026-07-09T23:57:18.152331Z","steps":["trace[295883544] 'read index received' (duration: 122.128788ms)","trace[295883544] 'applied index is now lower than readState.Index' (duration: 275.989µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:57:18.152397Z","caller":"traceutil/trace.go:171","msg":"trace[2070887116] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"128.924425ms","start":"2026-07-09T23:57:18.023444Z","end":"2026-07-09T23:57:18.152368Z","steps":["trace[2070887116] 'process raft request' (duration: 128.727779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:18.152515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.563252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:57:18.152569Z","caller":"traceutil/trace.go:171","msg":"trace[1201186708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2650; }","duration":"122.650564ms","start":"2026-07-09T23:57:18.029908Z","end":"2026-07-09T23:57:18.152558Z","steps":["trace[1201186708] 'agreement among raft nodes before linearized reading' (duration: 122.554621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:18.152744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.741192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-07-09T23:57:18.152793Z","caller":"traceutil/trace.go:171","msg":"trace[1867298947] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2650; }","duration":"109.811784ms","start":"2026-07-09T23:57:18.042967Z","end":"2026-07-09T23:57:18.152779Z","steps":["trace[1867298947] 'agreement among raft nodes before linearized reading' (duration: 109.657739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:18.152684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.974287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:57:18.153171Z","caller":"traceutil/trace.go:171","msg":"trace[1514030540] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:2650; }","duration":"114.511004ms","start":"2026-07-09T23:57:18.03864Z","end":"2026-07-09T23:57:18.153151Z","steps":["trace[1514030540] 'agreement among raft nodes before linearized reading' (duration: 113.923696ms)"],"step_count":1}