{"level":"warn","ts":"2026-05-09T05:11:16.070035Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:11:16.070122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T05:11:16.070192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:11:16.070208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-09T05:11:16.070229Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:11:16.071013Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-05-09T05:11:16.0711Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T05:11:16.075267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.90778ms"} {"level":"info","ts":"2026-05-09T05:11:16.08281Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-05-09T05:11:16.082878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T05:11:16.0829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-05-09T05:11:16.082918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T05:11:16.082929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-05-09T05:11:16.082969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-05-09T05:11:16.096369Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T05:11:16.098679Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T05:11:16.099757Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T05:11:16.101771Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T05:11:16.102504Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T05:11:16.10264Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:11:16.10401Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:11:16.106575Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:11:16.107021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-05-09T05:11:16.107337Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-05-09T05:11:16.108877Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:11:16.10912Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T05:11:16.109209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T05:11:16.109865Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-09T05:11:16.109891Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-05-09T05:11:16.883877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T05:11:16.883935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T05:11:16.883957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-05-09T05:11:16.883988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-05-09T05:11:16.884006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-09T05:11:16.884027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-05-09T05:11:16.884046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-05-09T05:11:16.885307Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:11:16.885576Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T05:11:16.885569Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:11:16.885729Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:11:16.886206Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T05:11:16.886254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T05:11:16.88639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:11:16.886479Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:11:16.88651Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:11:16.889183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T05:11:16.889678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-05-09T05:11:19.931902Z","caller":"traceutil/trace.go:171","msg":"trace[1268354782] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"128.965152ms","start":"2026-05-09T05:11:19.802904Z","end":"2026-05-09T05:11:19.931869Z","steps":["trace[1268354782] 'process raft request' (duration: 128.892441ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:19.931979Z","caller":"traceutil/trace.go:171","msg":"trace[1114212322] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"129.241529ms","start":"2026-05-09T05:11:19.802695Z","end":"2026-05-09T05:11:19.931937Z","steps":["trace[1114212322] 'process raft request' (duration: 80.948113ms)","trace[1114212322] 'compare' (duration: 47.946768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:20.482543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143599804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:11:21.73938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139662466s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:11:21.73955Z","caller":"traceutil/trace.go:171","msg":"trace[137348435] linearizableReadLoop","detail":"{readStateIndex:198; appliedIndex:196; }","duration":"1.758044245s","start":"2026-05-09T05:11:19.981491Z","end":"2026-05-09T05:11:21.739535Z","steps":["trace[137348435] 'read index received' (duration: 617.812977ms)","trace[137348435] 'applied index is now lower than readState.Index' (duration: 1.140230398s)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:21.739633Z","caller":"traceutil/trace.go:171","msg":"trace[689749132] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"1.804613212s","start":"2026-05-09T05:11:19.935009Z","end":"2026-05-09T05:11:21.739622Z","steps":["trace[689749132] 'process raft request' (duration: 664.279911ms)","trace[689749132] 'compare' (duration: 1.139531533s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:21.739689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:19.934985Z","time spent":"1.804679793s","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:11:21.739809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758299512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:21.739868Z","caller":"traceutil/trace.go:171","msg":"trace[848418788] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:193; }","duration":"1.758401893s","start":"2026-05-09T05:11:19.981451Z","end":"2026-05-09T05:11:21.739853Z","steps":["trace[848418788] 'agreement among raft nodes before linearized reading' (duration: 1.758180968s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:21.739897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:19.981437Z","time spent":"1.758452785s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-09T05:11:21.739884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341047337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:11:21.739946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.329602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:21.739968Z","caller":"traceutil/trace.go:171","msg":"trace[329003320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"1.341179741s","start":"2026-05-09T05:11:20.398762Z","end":"2026-05-09T05:11:21.739942Z","steps":["trace[329003320] 'agreement among raft nodes before linearized reading' (duration: 1.341039817s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:21.740009Z","caller":"traceutil/trace.go:171","msg":"trace[472947857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:193; }","duration":"840.424274ms","start":"2026-05-09T05:11:20.89956Z","end":"2026-05-09T05:11:21.739985Z","steps":["trace[472947857] 'agreement among raft nodes before linearized reading' (duration: 840.341932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:21.740017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:20.398741Z","time spent":"1.341267302s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:11:21.740056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:20.899532Z","time spent":"840.513666ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T05:11:21.739987Z","caller":"traceutil/trace.go:171","msg":"trace[1711908187] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"1.80321975s","start":"2026-05-09T05:11:19.936707Z","end":"2026-05-09T05:11:21.739926Z","steps":["trace[1711908187] 'process raft request' (duration: 1.80278307s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:21.740245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:19.936693Z","time spent":"1.803434115s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:11:22.245442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143599809,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:11:22.745767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143599809,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:11:23.003796Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.259358936s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T05:11:23.004185Z","caller":"traceutil/trace.go:171","msg":"trace[811436645] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"1.259801676s","start":"2026-05-09T05:11:21.744361Z","end":"2026-05-09T05:11:23.004162Z","steps":["trace[811436645] 'process raft request' (duration: 1.259633082s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.004304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:21.744348Z","time spent":"1.259901409s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:11:23.056143Z","caller":"traceutil/trace.go:171","msg":"trace[1820323110] linearizableReadLoop","detail":"{readStateIndex:202; appliedIndex:198; }","duration":"1.311546071s","start":"2026-05-09T05:11:21.744568Z","end":"2026-05-09T05:11:23.056114Z","steps":["trace[1820323110] 'read index received' (duration: 1.259452428s)","trace[1820323110] 'applied index is now lower than readState.Index' (duration: 52.092703ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:23.056151Z","caller":"traceutil/trace.go:171","msg":"trace[1760499290] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"1.310144958s","start":"2026-05-09T05:11:21.745971Z","end":"2026-05-09T05:11:23.056116Z","steps":["trace[1760499290] 'process raft request' (duration: 1.309940503s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.056481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311922319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"warn","ts":"2026-05-09T05:11:23.056486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31106333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:23.056516Z","caller":"traceutil/trace.go:171","msg":"trace[1572750889] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:197; }","duration":"1.312008351s","start":"2026-05-09T05:11:21.744498Z","end":"2026-05-09T05:11:23.056506Z","steps":["trace[1572750889] 'agreement among raft nodes before linearized reading' (duration: 1.311890288s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.056538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:21.744475Z","time spent":"1.312058173s","remote":"127.0.0.1:52766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-09T05:11:23.056533Z","caller":"traceutil/trace.go:171","msg":"trace[1800209166] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:0; response_revision:197; }","duration":"1.311158532s","start":"2026-05-09T05:11:21.745357Z","end":"2026-05-09T05:11:23.056516Z","steps":["trace[1800209166] 'agreement among raft nodes before linearized reading' (duration: 1.31105265s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.056599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:21.745339Z","time spent":"1.311250234s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" "} {"level":"warn","ts":"2026-05-09T05:11:23.056525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.93464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:23.056794Z","caller":"traceutil/trace.go:171","msg":"trace[1255323637] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"1.310733562s","start":"2026-05-09T05:11:21.746046Z","end":"2026-05-09T05:11:23.05678Z","steps":["trace[1255323637] 'process raft request' (duration: 1.309979635s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:23.056816Z","caller":"traceutil/trace.go:171","msg":"trace[2007069099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:197; }","duration":"180.272678ms","start":"2026-05-09T05:11:22.876517Z","end":"2026-05-09T05:11:23.056789Z","steps":["trace[2007069099] 'agreement among raft nodes before linearized reading' (duration: 179.92189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.056861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.368148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:23.056895Z","caller":"traceutil/trace.go:171","msg":"trace[406359380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:197; }","duration":"331.423379ms","start":"2026-05-09T05:11:22.725457Z","end":"2026-05-09T05:11:23.05688Z","steps":["trace[406359380] 'agreement among raft nodes before linearized reading' (duration: 331.385938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.056915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:22.725443Z","time spent":"331.46812ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:11:23.056911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:21.746024Z","time spent":"1.310834844s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:11:23.056728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259396647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:11:23.0569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.524196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:23.056968Z","caller":"traceutil/trace.go:171","msg":"trace[270459165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:197; }","duration":"1.259662762s","start":"2026-05-09T05:11:21.797294Z","end":"2026-05-09T05:11:23.056956Z","steps":["trace[270459165] 'agreement among raft nodes before linearized reading' (duration: 1.259392746s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.057039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:21.797279Z","time spent":"1.259749245s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:11:23.056984Z","caller":"traceutil/trace.go:171","msg":"trace[1774353902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:197; }","duration":"412.66125ms","start":"2026-05-09T05:11:22.644308Z","end":"2026-05-09T05:11:23.056969Z","steps":["trace[1774353902] 'agreement among raft nodes before linearized reading' (duration: 412.339942ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:23.05676Z","caller":"traceutil/trace.go:171","msg":"trace[44511875] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"520.1252ms","start":"2026-05-09T05:11:22.536626Z","end":"2026-05-09T05:11:23.056752Z","steps":["trace[44511875] 'process raft request' (duration: 519.441674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:23.057119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:22.5366Z","time spent":"520.488108ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5872,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:11:23.057308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:21.745955Z","time spent":"1.310262951s","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:11:23.05805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:22.644284Z","time spent":"412.806632ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T05:11:59.541428Z","caller":"traceutil/trace.go:171","msg":"trace[1145753577] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"447.125315ms","start":"2026-05-09T05:11:59.094281Z","end":"2026-05-09T05:11:59.541406Z","steps":["trace[1145753577] 'read index received' (duration: 446.947681ms)","trace[1145753577] 'applied index is now lower than readState.Index' (duration: 176.814µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:59.541535Z","caller":"traceutil/trace.go:171","msg":"trace[9391574] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"519.364328ms","start":"2026-05-09T05:11:59.022162Z","end":"2026-05-09T05:11:59.541526Z","steps":["trace[9391574] 'process raft request' (duration: 519.118412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:59.541613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.022144Z","time spent":"519.415479ms","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:11:59.541876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.587616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:59.541914Z","caller":"traceutil/trace.go:171","msg":"trace[1407835606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"447.661198ms","start":"2026-05-09T05:11:59.094244Z","end":"2026-05-09T05:11:59.541905Z","steps":["trace[1407835606] 'agreement among raft nodes before linearized reading' (duration: 447.579456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:59.541942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.094226Z","time spent":"447.710138ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:11:59.596866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.427382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T05:11:59.596877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.388396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-09T05:11:59.596908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.323162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T05:11:59.596907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.435687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:11:59.596945Z","caller":"traceutil/trace.go:171","msg":"trace[212641216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"349.547204ms","start":"2026-05-09T05:11:59.24738Z","end":"2026-05-09T05:11:59.596927Z","steps":["trace[212641216] 'agreement among raft nodes before linearized reading' (duration: 349.37566ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:59.596916Z","caller":"traceutil/trace.go:171","msg":"trace[2058638459] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"398.936934ms","start":"2026-05-09T05:11:59.197929Z","end":"2026-05-09T05:11:59.596866Z","steps":["trace[2058638459] 'process raft request' (duration: 398.661807ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:59.596964Z","caller":"traceutil/trace.go:171","msg":"trace[8651798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"350.6435ms","start":"2026-05-09T05:11:59.246308Z","end":"2026-05-09T05:11:59.596952Z","steps":["trace[8651798] 'agreement among raft nodes before linearized reading' (duration: 350.559818ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:59.596954Z","caller":"traceutil/trace.go:171","msg":"trace[447888363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"344.502198ms","start":"2026-05-09T05:11:59.252436Z","end":"2026-05-09T05:11:59.596938Z","steps":["trace[447888363] 'agreement among raft nodes before linearized reading' (duration: 344.341395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:59.596984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.247371Z","time spent":"349.606365ms","remote":"127.0.0.1:52856","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-05-09T05:11:59.596991Z","caller":"traceutil/trace.go:171","msg":"trace[1321985903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"344.55984ms","start":"2026-05-09T05:11:59.252408Z","end":"2026-05-09T05:11:59.596968Z","steps":["trace[1321985903] 'agreement among raft nodes before linearized reading' (duration: 344.393706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:59.597004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.252423Z","time spent":"344.572711ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:11:59.597041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.252392Z","time spent":"344.639542ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T05:11:59.596998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.246287Z","time spent":"350.703661ms","remote":"127.0.0.1:52856","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-05-09T05:11:59.597288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:59.197902Z","time spent":"399.099257ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:11:59.767821Z","caller":"traceutil/trace.go:171","msg":"trace[686338817] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"166.347154ms","start":"2026-05-09T05:11:59.601458Z","end":"2026-05-09T05:11:59.767805Z","steps":["trace[686338817] 'process raft request' (duration: 166.322114ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:59.767823Z","caller":"traceutil/trace.go:171","msg":"trace[620266132] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"167.355317ms","start":"2026-05-09T05:11:59.600442Z","end":"2026-05-09T05:11:59.767797Z","steps":["trace[620266132] 'process raft request' (duration: 141.823973ms)","trace[620266132] 'compare' (duration: 25.37014ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:59.76793Z","caller":"traceutil/trace.go:171","msg":"trace[1045253358] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"167.046271ms","start":"2026-05-09T05:11:59.600871Z","end":"2026-05-09T05:11:59.767917Z","steps":["trace[1045253358] 'process raft request' (duration: 166.888687ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:59.767917Z","caller":"traceutil/trace.go:171","msg":"trace[1132331036] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"167.084121ms","start":"2026-05-09T05:11:59.60082Z","end":"2026-05-09T05:11:59.767904Z","steps":["trace[1132331036] 'process raft request' (duration: 166.917098ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:01.503136Z","caller":"traceutil/trace.go:171","msg":"trace[1840724000] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"920.219554ms","start":"2026-05-09T05:12:00.582894Z","end":"2026-05-09T05:12:01.503114Z","steps":["trace[1840724000] 'process raft request' (duration: 919.976659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:01.503265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:00.582873Z","time spent":"920.335117ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:12:01.517935Z","caller":"traceutil/trace.go:171","msg":"trace[1835768008] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"424.139639ms","start":"2026-05-09T05:12:01.093776Z","end":"2026-05-09T05:12:01.517916Z","steps":["trace[1835768008] 'read index received' (duration: 409.177597ms)","trace[1835768008] 'applied index is now lower than readState.Index' (duration: 14.961492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:01.518033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.249282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:01.51806Z","caller":"traceutil/trace.go:171","msg":"trace[1708274815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"424.322813ms","start":"2026-05-09T05:12:01.093731Z","end":"2026-05-09T05:12:01.518054Z","steps":["trace[1708274815] 'agreement among raft nodes before linearized reading' (duration: 424.253902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:01.51808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:01.093712Z","time spent":"424.364065ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:12:01.51832Z","caller":"traceutil/trace.go:171","msg":"trace[312469399] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"932.392623ms","start":"2026-05-09T05:12:00.585921Z","end":"2026-05-09T05:12:01.518314Z","steps":["trace[312469399] 'process raft request' (duration: 931.863671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:01.518389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:00.585905Z","time spent":"932.434315ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:01.985447Z","caller":"traceutil/trace.go:171","msg":"trace[1310368949] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"148.920006ms","start":"2026-05-09T05:12:01.836506Z","end":"2026-05-09T05:12:01.985426Z","steps":["trace[1310368949] 'process raft request' (duration: 148.66709ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:02.23215Z","caller":"traceutil/trace.go:171","msg":"trace[584263264] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"161.446752ms","start":"2026-05-09T05:12:02.070677Z","end":"2026-05-09T05:12:02.232124Z","steps":["trace[584263264] 'process raft request' (duration: 161.294479ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:04.202507Z","caller":"traceutil/trace.go:171","msg":"trace[1144774067] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"323.97912ms","start":"2026-05-09T05:12:03.878497Z","end":"2026-05-09T05:12:04.202476Z","steps":["trace[1144774067] 'process raft request' (duration: 323.761625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:04.202654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:03.878478Z","time spent":"324.105842ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:04.218797Z","caller":"traceutil/trace.go:171","msg":"trace[183728686] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"338.930481ms","start":"2026-05-09T05:12:03.879814Z","end":"2026-05-09T05:12:04.218744Z","steps":["trace[183728686] 'process raft request' (duration: 331.892561ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:04.218934Z","caller":"traceutil/trace.go:171","msg":"trace[1024528871] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"281.961808ms","start":"2026-05-09T05:12:03.936937Z","end":"2026-05-09T05:12:04.218899Z","steps":["trace[1024528871] 'read index received' (duration: 265.337718ms)","trace[1024528871] 'applied index is now lower than readState.Index' (duration: 16.62226ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:04.218953Z","caller":"traceutil/trace.go:171","msg":"trace[1670640459] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"338.842809ms","start":"2026-05-09T05:12:03.880087Z","end":"2026-05-09T05:12:04.218929Z","steps":["trace[1670640459] 'process raft request' (duration: 338.620114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:04.219018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:03.879808Z","time spent":"339.127626ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:04.219087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:03.880066Z","time spent":"338.953122ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:04.219097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.144392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:12:04.219169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.865028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:04.21919Z","caller":"traceutil/trace.go:171","msg":"trace[1046973303] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:622; }","duration":"282.285425ms","start":"2026-05-09T05:12:03.936886Z","end":"2026-05-09T05:12:04.219171Z","steps":["trace[1046973303] 'agreement among raft nodes before linearized reading' (duration: 282.154762ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:04.219228Z","caller":"traceutil/trace.go:171","msg":"trace[907386925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"125.95419ms","start":"2026-05-09T05:12:04.093255Z","end":"2026-05-09T05:12:04.219209Z","steps":["trace[907386925] 'agreement among raft nodes before linearized reading' (duration: 125.871698ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:04.785554Z","caller":"traceutil/trace.go:171","msg":"trace[1924169018] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"168.365499ms","start":"2026-05-09T05:12:04.617167Z","end":"2026-05-09T05:12:04.785532Z","steps":["trace[1924169018] 'process raft request' (duration: 167.268184ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.374664Z","caller":"traceutil/trace.go:171","msg":"trace[1248254788] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"476.845595ms","start":"2026-05-09T05:12:07.897777Z","end":"2026-05-09T05:12:08.374623Z","steps":["trace[1248254788] 'read index received' (duration: 472.732991ms)","trace[1248254788] 'applied index is now lower than readState.Index' (duration: 4.111924ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:08.374715Z","caller":"traceutil/trace.go:171","msg":"trace[1668080009] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"505.749636ms","start":"2026-05-09T05:12:07.868912Z","end":"2026-05-09T05:12:08.374662Z","steps":["trace[1668080009] 'process raft request' (duration: 502.680936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:08.374838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.574931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2tdkx\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-05-09T05:12:08.374868Z","caller":"traceutil/trace.go:171","msg":"trace[1234838309] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2tdkx; range_end:; response_count:1; response_revision:645; }","duration":"299.655553ms","start":"2026-05-09T05:12:08.075204Z","end":"2026-05-09T05:12:08.37486Z","steps":["trace[1234838309] 'agreement among raft nodes before linearized reading' (duration: 299.54985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:08.374883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.868881Z","time spent":"505.913719ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:08.375061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.288055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T05:12:08.375129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.055479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T05:12:08.375128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.703739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:08.375179Z","caller":"traceutil/trace.go:171","msg":"trace[518760628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"139.197043ms","start":"2026-05-09T05:12:08.235967Z","end":"2026-05-09T05:12:08.375164Z","steps":["trace[518760628] 'agreement among raft nodes before linearized reading' (duration: 138.992658ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.375181Z","caller":"traceutil/trace.go:171","msg":"trace[1996715222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"477.376077ms","start":"2026-05-09T05:12:07.897727Z","end":"2026-05-09T05:12:08.375103Z","steps":["trace[1996715222] 'agreement among raft nodes before linearized reading' (duration: 477.014039ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.375203Z","caller":"traceutil/trace.go:171","msg":"trace[186273188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"280.808871ms","start":"2026-05-09T05:12:08.094372Z","end":"2026-05-09T05:12:08.375181Z","steps":["trace[186273188] 'agreement among raft nodes before linearized reading' (duration: 280.706329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:08.37522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.897693Z","time spent":"477.51705ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T05:12:08.614943Z","caller":"traceutil/trace.go:171","msg":"trace[226921538] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"229.307495ms","start":"2026-05-09T05:12:08.385606Z","end":"2026-05-09T05:12:08.614913Z","steps":["trace[226921538] 'process raft request' (duration: 229.198142ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.615186Z","caller":"traceutil/trace.go:171","msg":"trace[1052284549] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"229.090629ms","start":"2026-05-09T05:12:08.386074Z","end":"2026-05-09T05:12:08.615164Z","steps":["trace[1052284549] 'process raft request' (duration: 228.788622ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.61499Z","caller":"traceutil/trace.go:171","msg":"trace[1669995777] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"236.5737ms","start":"2026-05-09T05:12:08.37837Z","end":"2026-05-09T05:12:08.614944Z","steps":["trace[1669995777] 'process raft request' (duration: 236.241993ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.615265Z","caller":"traceutil/trace.go:171","msg":"trace[2086677584] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"228.370283ms","start":"2026-05-09T05:12:08.386879Z","end":"2026-05-09T05:12:08.615249Z","steps":["trace[2086677584] 'process raft request' (duration: 228.201789ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:08.623741Z","caller":"traceutil/trace.go:171","msg":"trace[759160825] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"196.958224ms","start":"2026-05-09T05:12:08.426767Z","end":"2026-05-09T05:12:08.623725Z","steps":["trace[759160825] 'process raft request' (duration: 196.875222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.448845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.671682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T05:12:09.449818Z","caller":"traceutil/trace.go:171","msg":"trace[1125020574] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"438.220063ms","start":"2026-05-09T05:12:09.01158Z","end":"2026-05-09T05:12:09.4498Z","steps":["trace[1125020574] 'process raft request' (duration: 437.582798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.449931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:09.011559Z","time spent":"438.301474ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:10.593767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143600732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:12:11.094447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143600732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:12:11.377425Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.868738308s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T05:12:11.381002Z","caller":"traceutil/trace.go:171","msg":"trace[732965175] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.872394682s","start":"2026-05-09T05:12:09.508582Z","end":"2026-05-09T05:12:11.380977Z","steps":["trace[732965175] 'process raft request' (duration: 1.869174888s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:11.381384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:09.508562Z","time spent":"1.872744279s","remote":"127.0.0.1:52830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:12:11.384885Z","caller":"traceutil/trace.go:171","msg":"trace[1485272485] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"1.292038279s","start":"2026-05-09T05:12:10.092825Z","end":"2026-05-09T05:12:11.384863Z","steps":["trace[1485272485] 'read index received' (duration: 1.284887215s)","trace[1485272485] 'applied index is now lower than readState.Index' (duration: 7.148354ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:11.385006Z","caller":"traceutil/trace.go:171","msg":"trace[2032745828] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.71139688s","start":"2026-05-09T05:12:09.673563Z","end":"2026-05-09T05:12:11.38496Z","steps":["trace[2032745828] 'process raft request' (duration: 1.711195755s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:11.38523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292394377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:12:11.385312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:09.673538Z","time spent":"1.711561723s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:11.385592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.17495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T05:12:11.385617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.103751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:12:11.385657Z","caller":"traceutil/trace.go:171","msg":"trace[1647033774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"765.264232ms","start":"2026-05-09T05:12:10.620377Z","end":"2026-05-09T05:12:11.385641Z","steps":["trace[1647033774] 'agreement among raft nodes before linearized reading' (duration: 765.087018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:11.38568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.922846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T05:12:11.385701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:10.620369Z","time spent":"765.319873ms","remote":"127.0.0.1:52856","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-05-09T05:12:11.385722Z","caller":"traceutil/trace.go:171","msg":"trace[722464556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"766.000898ms","start":"2026-05-09T05:12:10.619708Z","end":"2026-05-09T05:12:11.385709Z","steps":["trace[722464556] 'agreement among raft nodes before linearized reading' (duration: 765.809004ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:11.385657Z","caller":"traceutil/trace.go:171","msg":"trace[1113778546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"766.179353ms","start":"2026-05-09T05:12:10.619468Z","end":"2026-05-09T05:12:11.385647Z","steps":["trace[1113778546] 'agreement among raft nodes before linearized reading' (duration: 766.06998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:11.385761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:10.619683Z","time spent":"766.07038ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:12:11.385795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:10.61945Z","time spent":"766.329466ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:11.385593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.213472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T05:12:11.38587Z","caller":"traceutil/trace.go:171","msg":"trace[1981960875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"978.535359ms","start":"2026-05-09T05:12:10.407319Z","end":"2026-05-09T05:12:11.385855Z","steps":["trace[1981960875] 'agreement among raft nodes before linearized reading' (duration: 978.12111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:11.38592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:10.407295Z","time spent":"978.613561ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:12:11.385326Z","caller":"traceutil/trace.go:171","msg":"trace[333858499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"1.2925217s","start":"2026-05-09T05:12:10.092786Z","end":"2026-05-09T05:12:11.385307Z","steps":["trace[333858499] 'agreement among raft nodes before linearized reading' (duration: 1.292394437s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:11.38605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:10.092768Z","time spent":"1.293264337s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:12:12.224848Z","caller":"traceutil/trace.go:171","msg":"trace[866942780] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"124.873866ms","start":"2026-05-09T05:12:12.099949Z","end":"2026-05-09T05:12:12.224823Z","steps":["trace[866942780] 'process raft request' (duration: 102.962295ms)","trace[866942780] 'compare' (duration: 21.799549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:14.845555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143600796,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:12:14.904553Z","caller":"traceutil/trace.go:171","msg":"trace[49260037] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"684.863703ms","start":"2026-05-09T05:12:14.219666Z","end":"2026-05-09T05:12:14.904529Z","steps":["trace[49260037] 'process raft request' (duration: 684.72233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.904697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.219623Z","time spent":"684.995416ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:14.997317Z","caller":"traceutil/trace.go:171","msg":"trace[1341135172] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:715; }","duration":"652.536474ms","start":"2026-05-09T05:12:14.344756Z","end":"2026-05-09T05:12:14.997293Z","steps":["trace[1341135172] 'read index received' (duration: 559.715051ms)","trace[1341135172] 'applied index is now lower than readState.Index' (duration: 92.820423ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:14.99734Z","caller":"traceutil/trace.go:171","msg":"trace[1062451593] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"251.300978ms","start":"2026-05-09T05:12:14.746007Z","end":"2026-05-09T05:12:14.997308Z","steps":["trace[1062451593] 'process raft request' (duration: 251.253057ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:14.997338Z","caller":"traceutil/trace.go:171","msg":"trace[1977393700] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"451.933486ms","start":"2026-05-09T05:12:14.545371Z","end":"2026-05-09T05:12:14.997304Z","steps":["trace[1977393700] 'process raft request' (duration: 451.775852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.997496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.729799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:12:14.997522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.54535Z","time spent":"452.064499ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:12:14.997549Z","caller":"traceutil/trace.go:171","msg":"trace[62491158] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:693; }","duration":"652.82985ms","start":"2026-05-09T05:12:14.344705Z","end":"2026-05-09T05:12:14.997535Z","steps":["trace[62491158] 'agreement among raft nodes before linearized reading' (duration: 652.700617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.997587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.344688Z","time spent":"652.890732ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-05-09T05:12:21.051153Z","caller":"traceutil/trace.go:171","msg":"trace[730295072] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"121.199722ms","start":"2026-05-09T05:12:20.929879Z","end":"2026-05-09T05:12:21.051079Z","steps":["trace[730295072] 'process raft request' (duration: 52.119752ms)","trace[730295072] 'compare' (duration: 68.885325ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:21.631141Z","caller":"traceutil/trace.go:171","msg":"trace[1749167429] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"134.687081ms","start":"2026-05-09T05:12:21.496435Z","end":"2026-05-09T05:12:21.631122Z","steps":["trace[1749167429] 'process raft request' (duration: 134.64569ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:21.631415Z","caller":"traceutil/trace.go:171","msg":"trace[726837628] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"135.791875ms","start":"2026-05-09T05:12:21.495616Z","end":"2026-05-09T05:12:21.631408Z","steps":["trace[726837628] 'process raft request' (duration: 131.378384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.311759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216972551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:28.311862Z","caller":"traceutil/trace.go:171","msg":"trace[1038946277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"1.217154225s","start":"2026-05-09T05:12:27.094684Z","end":"2026-05-09T05:12:28.311838Z","steps":["trace[1038946277] 'range keys from in-memory index tree' (duration: 1.216882659s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.311894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136540861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mknbg\" ","response":"range_response_count:1 size:3963"} {"level":"warn","ts":"2026-05-09T05:12:28.311916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.094662Z","time spent":"1.217240957s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:28.311902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.218473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:12:28.311959Z","caller":"traceutil/trace.go:171","msg":"trace[867825488] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mknbg; range_end:; response_count:1; response_revision:817; }","duration":"1.136657564s","start":"2026-05-09T05:12:27.175285Z","end":"2026-05-09T05:12:28.311943Z","steps":["trace[867825488] 'range keys from in-memory index tree' (duration: 1.136297086s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:28.31199Z","caller":"traceutil/trace.go:171","msg":"trace[1737105528] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:817; }","duration":"602.361636ms","start":"2026-05-09T05:12:27.709611Z","end":"2026-05-09T05:12:28.311972Z","steps":["trace[1737105528] 'count revisions from in-memory index tree' (duration: 602.129531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.312004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.175252Z","time spent":"1.136740746s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mknbg\" "} {"level":"warn","ts":"2026-05-09T05:12:28.312041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.70959Z","time spent":"602.441068ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:12:28.312293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.952722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:12:28.312405Z","caller":"traceutil/trace.go:171","msg":"trace[133400553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"656.075255ms","start":"2026-05-09T05:12:27.656303Z","end":"2026-05-09T05:12:28.312379Z","steps":["trace[133400553] 'range keys from in-memory index tree' (duration: 655.661595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.312436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.722646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T05:12:28.312451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.656269Z","time spent":"656.173026ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:28.312477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.826396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:12:28.312483Z","caller":"traceutil/trace.go:171","msg":"trace[75547920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"749.805768ms","start":"2026-05-09T05:12:27.562667Z","end":"2026-05-09T05:12:28.312472Z","steps":["trace[75547920] 'range keys from in-memory index tree' (duration: 749.545762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.312473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.706291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T05:12:28.312513Z","caller":"traceutil/trace.go:171","msg":"trace[446835273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:817; }","duration":"581.882208ms","start":"2026-05-09T05:12:27.73062Z","end":"2026-05-09T05:12:28.312503Z","steps":["trace[446835273] 'range keys from in-memory index tree' (duration: 581.647872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.312516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.562644Z","time spent":"749.865929ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T05:12:28.312528Z","caller":"traceutil/trace.go:171","msg":"trace[1290970031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"580.798723ms","start":"2026-05-09T05:12:27.731716Z","end":"2026-05-09T05:12:28.312514Z","steps":["trace[1290970031] 'range keys from in-memory index tree' (duration: 580.513336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.31255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.73061Z","time spent":"581.934119ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:12:28.312564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:27.731668Z","time spent":"580.887525ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:12:35.822705Z","caller":"traceutil/trace.go:171","msg":"trace[2035828385] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"145.046548ms","start":"2026-05-09T05:12:35.677626Z","end":"2026-05-09T05:12:35.822672Z","steps":["trace[2035828385] 'process raft request' (duration: 136.975043ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:35.948859Z","caller":"traceutil/trace.go:171","msg":"trace[1014261565] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"270.412625ms","start":"2026-05-09T05:12:35.678422Z","end":"2026-05-09T05:12:35.948835Z","steps":["trace[1014261565] 'process raft request' (duration: 257.162682ms)","trace[1014261565] 'compare' (duration: 13.066629ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:35.949058Z","caller":"traceutil/trace.go:171","msg":"trace[326900602] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"268.540912ms","start":"2026-05-09T05:12:35.6805Z","end":"2026-05-09T05:12:35.949041Z","steps":["trace[326900602] 'process raft request' (duration: 268.258865ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:35.949415Z","caller":"traceutil/trace.go:171","msg":"trace[276199060] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:920; }","duration":"120.953756ms","start":"2026-05-09T05:12:35.828429Z","end":"2026-05-09T05:12:35.949383Z","steps":["trace[276199060] 'read index received' (duration: 107.224373ms)","trace[276199060] 'applied index is now lower than readState.Index' (duration: 13.728063ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:35.949504Z","caller":"traceutil/trace.go:171","msg":"trace[1409351227] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"121.318775ms","start":"2026-05-09T05:12:35.828179Z","end":"2026-05-09T05:12:35.949498Z","steps":["trace[1409351227] 'process raft request' (duration: 120.788173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:35.949607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.157871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-4ptzz\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-09T05:12:35.94965Z","caller":"traceutil/trace.go:171","msg":"trace[1704902353] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-4ptzz; range_end:; response_count:1; response_revision:892; }","duration":"121.231022ms","start":"2026-05-09T05:12:35.828406Z","end":"2026-05-09T05:12:35.949637Z","steps":["trace[1704902353] 'agreement among raft nodes before linearized reading' (duration: 121.077059ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:36.226936Z","caller":"traceutil/trace.go:171","msg":"trace[455244632] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"132.968401ms","start":"2026-05-09T05:12:36.093946Z","end":"2026-05-09T05:12:36.226914Z","steps":["trace[455244632] 'read index received' (duration: 102.565265ms)","trace[455244632] 'applied index is now lower than readState.Index' (duration: 30.402176ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:36.227057Z","caller":"traceutil/trace.go:171","msg":"trace[1777957176] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"269.34039ms","start":"2026-05-09T05:12:35.957705Z","end":"2026-05-09T05:12:36.227045Z","steps":["trace[1777957176] 'process raft request' (duration: 238.902503ms)","trace[1777957176] 'compare' (duration: 30.211401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:36.227154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.206127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:36.227243Z","caller":"traceutil/trace.go:171","msg":"trace[1736367883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"133.308478ms","start":"2026-05-09T05:12:36.093896Z","end":"2026-05-09T05:12:36.227204Z","steps":["trace[1736367883] 'agreement among raft nodes before linearized reading' (duration: 133.221506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.22715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.441503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-09T05:12:36.227409Z","caller":"traceutil/trace.go:171","msg":"trace[688086414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:894; }","duration":"116.760861ms","start":"2026-05-09T05:12:36.110639Z","end":"2026-05-09T05:12:36.2274Z","steps":["trace[688086414] 'agreement among raft nodes before linearized reading' (duration: 116.421543ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:36.829847Z","caller":"traceutil/trace.go:171","msg":"trace[1204159527] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"462.634041ms","start":"2026-05-09T05:12:36.367187Z","end":"2026-05-09T05:12:36.829821Z","steps":["trace[1204159527] 'read index received' (duration: 462.58708ms)","trace[1204159527] 'applied index is now lower than readState.Index' (duration: 45.741µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:36.829916Z","caller":"traceutil/trace.go:171","msg":"trace[1137438813] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"592.968091ms","start":"2026-05-09T05:12:36.236918Z","end":"2026-05-09T05:12:36.829886Z","steps":["trace[1137438813] 'process raft request' (duration: 592.694225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.830066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.236905Z","time spent":"593.080144ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4760,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:36.830085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.873556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:12:36.830122Z","caller":"traceutil/trace.go:171","msg":"trace[1899731705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"462.954148ms","start":"2026-05-09T05:12:36.367156Z","end":"2026-05-09T05:12:36.83011Z","steps":["trace[1899731705] 'agreement among raft nodes before linearized reading' (duration: 462.795635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.830185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.367144Z","time spent":"463.03209ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T05:12:36.914194Z","caller":"traceutil/trace.go:171","msg":"trace[1642106581] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"348.932481ms","start":"2026-05-09T05:12:36.565237Z","end":"2026-05-09T05:12:36.914169Z","steps":["trace[1642106581] 'process raft request' (duration: 348.741176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.914318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.565214Z","time spent":"349.040313ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:36.914318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.874956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T05:12:36.914415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.698167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:12:36.914434Z","caller":"traceutil/trace.go:171","msg":"trace[2089405110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"529.003679ms","start":"2026-05-09T05:12:36.385391Z","end":"2026-05-09T05:12:36.914395Z","steps":["trace[2089405110] 'agreement among raft nodes before linearized reading' (duration: 528.751023ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:36.914486Z","caller":"traceutil/trace.go:171","msg":"trace[1897854965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"530.819511ms","start":"2026-05-09T05:12:36.383651Z","end":"2026-05-09T05:12:36.91447Z","steps":["trace[1897854965] 'agreement among raft nodes before linearized reading' (duration: 530.564275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.914494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.385384Z","time spent":"529.100011ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:36.914532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.38364Z","time spent":"530.883902ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:36.914545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.281154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T05:12:36.91462Z","caller":"traceutil/trace.go:171","msg":"trace[1107385550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"532.394547ms","start":"2026-05-09T05:12:36.382207Z","end":"2026-05-09T05:12:36.914601Z","steps":["trace[1107385550] 'agreement among raft nodes before linearized reading' (duration: 532.174042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.914666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.382195Z","time spent":"532.461788ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:36.914706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.069313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:12:36.91474Z","caller":"traceutil/trace.go:171","msg":"trace[1119645898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"544.122915ms","start":"2026-05-09T05:12:36.370609Z","end":"2026-05-09T05:12:36.914732Z","steps":["trace[1119645898] 'agreement among raft nodes before linearized reading' (duration: 543.48428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:36.914789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:36.370599Z","time spent":"544.183196ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:12:37.06144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.009961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:12:37.061593Z","caller":"traceutil/trace.go:171","msg":"trace[141729014] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"221.482624ms","start":"2026-05-09T05:12:36.840084Z","end":"2026-05-09T05:12:37.061566Z","steps":["trace[141729014] 'process raft request' (duration: 109.241208ms)","trace[141729014] 'compare' (duration: 111.828277ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:37.092167Z","caller":"traceutil/trace.go:171","msg":"trace[1982840832] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"174.262044ms","start":"2026-05-09T05:12:36.917885Z","end":"2026-05-09T05:12:37.092147Z","steps":["trace[1982840832] 'process raft request' (duration: 174.109781ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:37.092186Z","caller":"traceutil/trace.go:171","msg":"trace[1599254520] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"171.233975ms","start":"2026-05-09T05:12:36.920933Z","end":"2026-05-09T05:12:37.092167Z","steps":["trace[1599254520] 'process raft request' (duration: 171.201344ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:37.092328Z","caller":"traceutil/trace.go:171","msg":"trace[2050643575] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"173.579749ms","start":"2026-05-09T05:12:36.918738Z","end":"2026-05-09T05:12:37.092317Z","steps":["trace[2050643575] 'process raft request' (duration: 173.342614ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:37.092389Z","caller":"traceutil/trace.go:171","msg":"trace[1521161233] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"173.203231ms","start":"2026-05-09T05:12:36.919177Z","end":"2026-05-09T05:12:37.09238Z","steps":["trace[1521161233] 'process raft request' (duration: 172.928044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:49.584949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.027528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:12:49.585054Z","caller":"traceutil/trace.go:171","msg":"trace[1875629242] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"415.544003ms","start":"2026-05-09T05:12:49.169489Z","end":"2026-05-09T05:12:49.585033Z","steps":["trace[1875629242] 'read index received' (duration: 29.406352ms)","trace[1875629242] 'applied index is now lower than readState.Index' (duration: 386.136261ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:49.585315Z","caller":"traceutil/trace.go:171","msg":"trace[1607492918] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"425.50038ms","start":"2026-05-09T05:12:49.159791Z","end":"2026-05-09T05:12:49.585291Z","steps":["trace[1607492918] 'process raft request' (duration: 39.045883ms)","trace[1607492918] 'compare' (duration: 385.521596ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:49.585413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:49.159781Z","time spent":"425.586873ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:49.585717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.215418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T05:12:49.58577Z","caller":"traceutil/trace.go:171","msg":"trace[763349753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"416.30208ms","start":"2026-05-09T05:12:49.169454Z","end":"2026-05-09T05:12:49.585756Z","steps":["trace[763349753] 'agreement among raft nodes before linearized reading' (duration: 416.148457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:49.585809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:49.169442Z","time spent":"416.356831ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:49.587523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.041767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:12:49.587579Z","caller":"traceutil/trace.go:171","msg":"trace[1935119377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"410.118509ms","start":"2026-05-09T05:12:49.177446Z","end":"2026-05-09T05:12:49.587564Z","steps":["trace[1935119377] 'agreement among raft nodes before linearized reading' (duration: 409.992026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:49.587621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:49.177436Z","time spent":"410.17477ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:12:49.587779Z","caller":"traceutil/trace.go:171","msg":"trace[1321885559] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"170.53656ms","start":"2026-05-09T05:12:49.417175Z","end":"2026-05-09T05:12:49.587711Z","steps":["trace[1321885559] 'process raft request' (duration: 170.184222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:49.587984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.246012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-09T05:12:49.588039Z","caller":"traceutil/trace.go:171","msg":"trace[1840183477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"410.322673ms","start":"2026-05-09T05:12:49.177702Z","end":"2026-05-09T05:12:49.588025Z","steps":["trace[1840183477] 'agreement among raft nodes before linearized reading' (duration: 410.143059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:49.588159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:49.177693Z","time spent":"410.450117ms","remote":"127.0.0.1:52856","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":"info","ts":"2026-05-09T05:12:49.779867Z","caller":"traceutil/trace.go:171","msg":"trace[963254824] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"188.402019ms","start":"2026-05-09T05:12:49.591442Z","end":"2026-05-09T05:12:49.779844Z","steps":["trace[963254824] 'process raft request' (duration: 188.229226ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:49.779859Z","caller":"traceutil/trace.go:171","msg":"trace[1800167930] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"190.000005ms","start":"2026-05-09T05:12:49.589797Z","end":"2026-05-09T05:12:49.779797Z","steps":["trace[1800167930] 'process raft request' (duration: 161.996935ms)","trace[1800167930] 'compare' (duration: 27.736294ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:49.779998Z","caller":"traceutil/trace.go:171","msg":"trace[2041610791] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"187.720493ms","start":"2026-05-09T05:12:49.592124Z","end":"2026-05-09T05:12:49.779844Z","steps":["trace[2041610791] 'process raft request' (duration: 187.604621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:58.115654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143601510,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:12:58.507797Z","caller":"traceutil/trace.go:171","msg":"trace[1906716001] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"892.691907ms","start":"2026-05-09T05:12:57.615083Z","end":"2026-05-09T05:12:58.507775Z","steps":["trace[1906716001] 'read index received' (duration: 892.526753ms)","trace[1906716001] 'applied index is now lower than readState.Index' (duration: 164.494µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:58.508062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.60135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:58.508106Z","caller":"traceutil/trace.go:171","msg":"trace[1319564181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"413.697922ms","start":"2026-05-09T05:12:58.094397Z","end":"2026-05-09T05:12:58.508095Z","steps":["trace[1319564181] 'agreement among raft nodes before linearized reading' (duration: 413.61516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:58.508125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.434471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T05:12:58.508135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:58.094377Z","time spent":"413.750263ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:58.508138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.956463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:12:58.508157Z","caller":"traceutil/trace.go:171","msg":"trace[613181307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"690.484573ms","start":"2026-05-09T05:12:57.817662Z","end":"2026-05-09T05:12:58.508146Z","steps":["trace[613181307] 'agreement among raft nodes before linearized reading' (duration: 690.39146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:58.508182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:57.817648Z","time spent":"690.526593ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:12:58.508183Z","caller":"traceutil/trace.go:171","msg":"trace[1552691089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"698.057145ms","start":"2026-05-09T05:12:57.810113Z","end":"2026-05-09T05:12:58.508171Z","steps":["trace[1552691089] 'agreement among raft nodes before linearized reading' (duration: 697.878751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:58.508217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:57.810095Z","time spent":"698.114057ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:58.508204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.632732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T05:12:58.508265Z","caller":"traceutil/trace.go:171","msg":"trace[2014311133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"689.722815ms","start":"2026-05-09T05:12:57.818528Z","end":"2026-05-09T05:12:58.508251Z","steps":["trace[2014311133] 'agreement among raft nodes before linearized reading' (duration: 689.51519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:58.508298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:57.818524Z","time spent":"689.768656ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:58.508357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.26973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:12:58.50839Z","caller":"traceutil/trace.go:171","msg":"trace[1078753618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"893.324731ms","start":"2026-05-09T05:12:57.615056Z","end":"2026-05-09T05:12:58.50838Z","steps":["trace[1078753618] 'agreement among raft nodes before linearized reading' (duration: 893.229209ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:58.508402Z","caller":"traceutil/trace.go:171","msg":"trace[1292068006] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"897.64621ms","start":"2026-05-09T05:12:57.610748Z","end":"2026-05-09T05:12:58.508394Z","steps":["trace[1292068006] 'process raft request' (duration: 896.910023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:58.508415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:57.615039Z","time spent":"893.369263ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:12:58.508463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:57.610719Z","time spent":"897.697862ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:59.054537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.057551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:12:59.055055Z","caller":"traceutil/trace.go:171","msg":"trace[536804307] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"542.873749ms","start":"2026-05-09T05:12:58.512158Z","end":"2026-05-09T05:12:59.055032Z","steps":["trace[536804307] 'process raft request' (duration: 542.463039ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:59.055057Z","caller":"traceutil/trace.go:171","msg":"trace[1585828726] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"542.970542ms","start":"2026-05-09T05:12:58.512059Z","end":"2026-05-09T05:12:59.055029Z","steps":["trace[1585828726] 'process raft request' (duration: 326.352398ms)","trace[1585828726] 'compare' (duration: 215.920418ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:59.055068Z","caller":"traceutil/trace.go:171","msg":"trace[524311911] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"542.579662ms","start":"2026-05-09T05:12:58.512468Z","end":"2026-05-09T05:12:59.055047Z","steps":["trace[524311911] 'process raft request' (duration: 542.192453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:59.055169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:58.512138Z","time spent":"542.975412ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:59.055188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:58.512459Z","time spent":"542.669064ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:59.055194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:58.512027Z","time spent":"543.090684ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:01.679075Z","caller":"traceutil/trace.go:171","msg":"trace[1989842816] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"442.54321ms","start":"2026-05-09T05:13:01.236504Z","end":"2026-05-09T05:13:01.679047Z","steps":["trace[1989842816] 'read index received' (duration: 392.186312ms)","trace[1989842816] 'applied index is now lower than readState.Index' (duration: 50.355758ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:01.679536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.062553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:13:01.679604Z","caller":"traceutil/trace.go:171","msg":"trace[319678459] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1017; }","duration":"443.179526ms","start":"2026-05-09T05:13:01.236406Z","end":"2026-05-09T05:13:01.679585Z","steps":["trace[319678459] 'agreement among raft nodes before linearized reading' (duration: 443.020272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:01.679644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:01.236391Z","time spent":"443.243207ms","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:13:01.679611Z","caller":"traceutil/trace.go:171","msg":"trace[418120420] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"468.678221ms","start":"2026-05-09T05:13:01.210894Z","end":"2026-05-09T05:13:01.679572Z","steps":["trace[418120420] 'process raft request' (duration: 417.813731ms)","trace[418120420] 'compare' (duration: 50.220796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:01.679795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:01.210873Z","time spent":"468.818445ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:02.009982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.041768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:02.01009Z","caller":"traceutil/trace.go:171","msg":"trace[1922099414] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1017; }","duration":"192.181791ms","start":"2026-05-09T05:13:01.81787Z","end":"2026-05-09T05:13:02.010052Z","steps":["trace[1922099414] 'count revisions from in-memory index tree' (duration: 191.975546ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:02.274105Z","caller":"traceutil/trace.go:171","msg":"trace[642923222] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"182.482077ms","start":"2026-05-09T05:13:02.091607Z","end":"2026-05-09T05:13:02.274089Z","steps":["trace[642923222] 'read index received' (duration: 182.355595ms)","trace[642923222] 'applied index is now lower than readState.Index' (duration: 125.612µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:02.274178Z","caller":"traceutil/trace.go:171","msg":"trace[1860818113] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"192.507958ms","start":"2026-05-09T05:13:02.081655Z","end":"2026-05-09T05:13:02.274163Z","steps":["trace[1860818113] 'process raft request' (duration: 192.344744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:02.27424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.610331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:02.274295Z","caller":"traceutil/trace.go:171","msg":"trace[2051020466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"182.678463ms","start":"2026-05-09T05:13:02.091601Z","end":"2026-05-09T05:13:02.27428Z","steps":["trace[2051020466] 'agreement among raft nodes before linearized reading' (duration: 182.582571ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:02.722222Z","caller":"traceutil/trace.go:171","msg":"trace[323335232] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1020; }","duration":"446.548214ms","start":"2026-05-09T05:13:02.275654Z","end":"2026-05-09T05:13:02.722203Z","steps":["trace[323335232] 'process raft request' (duration: 446.426721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:02.722437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:02.275613Z","time spent":"446.672707ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-05-09T05:13:02.977593Z","caller":"traceutil/trace.go:171","msg":"trace[1063026382] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"457.334211ms","start":"2026-05-09T05:13:02.520222Z","end":"2026-05-09T05:13:02.977556Z","steps":["trace[1063026382] 'process raft request' (duration: 397.283599ms)","trace[1063026382] 'compare' (duration: 59.763985ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:02.977611Z","caller":"traceutil/trace.go:171","msg":"trace[35585839] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"368.799464ms","start":"2026-05-09T05:13:02.60877Z","end":"2026-05-09T05:13:02.977569Z","steps":["trace[35585839] 'read index received' (duration: 113.315207ms)","trace[35585839] 'applied index is now lower than readState.Index' (duration: 255.483007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:02.977761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:02.520201Z","time spent":"457.474865ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:02.977777Z","caller":"traceutil/trace.go:171","msg":"trace[764645776] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"118.854064ms","start":"2026-05-09T05:13:02.858894Z","end":"2026-05-09T05:13:02.977748Z","steps":["trace[764645776] 'process raft request' (duration: 118.607419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:02.977928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.172694ms","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":"warn","ts":"2026-05-09T05:13:02.977989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.191353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T05:13:02.978038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.303507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"warn","ts":"2026-05-09T05:13:02.978067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.945406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:13:02.978082Z","caller":"traceutil/trace.go:171","msg":"trace[1712677209] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1022; }","duration":"253.377309ms","start":"2026-05-09T05:13:02.724693Z","end":"2026-05-09T05:13:02.97807Z","steps":["trace[1712677209] 'agreement among raft nodes before linearized reading' (duration: 253.229886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:02.978106Z","caller":"traceutil/trace.go:171","msg":"trace[2126216526] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1022; }","duration":"285.043318ms","start":"2026-05-09T05:13:02.693055Z","end":"2026-05-09T05:13:02.978098Z","steps":["trace[2126216526] 'agreement among raft nodes before linearized reading' (duration: 284.979696ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:02.978065Z","caller":"traceutil/trace.go:171","msg":"trace[1139465807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"369.286725ms","start":"2026-05-09T05:13:02.608761Z","end":"2026-05-09T05:13:02.978047Z","steps":["trace[1139465807] 'agreement among raft nodes before linearized reading' (duration: 369.009379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:02.97821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:02.60871Z","time spent":"369.473519ms","remote":"127.0.0.1:52856","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-05-09T05:13:02.978008Z","caller":"traceutil/trace.go:171","msg":"trace[1887105186] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1022; }","duration":"336.311637ms","start":"2026-05-09T05:13:02.641677Z","end":"2026-05-09T05:13:02.977989Z","steps":["trace[1887105186] 'agreement among raft nodes before linearized reading' (duration: 336.149693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:02.978396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:02.64166Z","time spent":"336.706786ms","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:13:03.137159Z","caller":"traceutil/trace.go:171","msg":"trace[537731654] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"150.216725ms","start":"2026-05-09T05:13:02.986893Z","end":"2026-05-09T05:13:03.137109Z","steps":["trace[537731654] 'read index received' (duration: 116.478909ms)","trace[537731654] 'applied index is now lower than readState.Index' (duration: 33.736716ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:03.137201Z","caller":"traceutil/trace.go:171","msg":"trace[1135484783] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"153.90197ms","start":"2026-05-09T05:13:02.98327Z","end":"2026-05-09T05:13:03.137172Z","steps":["trace[1135484783] 'process raft request' (duration: 120.112302ms)","trace[1135484783] 'compare' (duration: 33.608774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:03.137416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.489492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-05-09T05:13:03.137546Z","caller":"traceutil/trace.go:171","msg":"trace[1391163716] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1023; }","duration":"150.666926ms","start":"2026-05-09T05:13:02.986862Z","end":"2026-05-09T05:13:03.137529Z","steps":["trace[1391163716] 'agreement among raft nodes before linearized reading' (duration: 150.381079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.5924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143601578,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:13:05.717987Z","caller":"traceutil/trace.go:171","msg":"trace[2116191855] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"626.315218ms","start":"2026-05-09T05:13:05.091657Z","end":"2026-05-09T05:13:05.717972Z","steps":["trace[2116191855] 'read index received' (duration: 626.188505ms)","trace[2116191855] 'applied index is now lower than readState.Index' (duration: 126.193µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:05.718055Z","caller":"traceutil/trace.go:171","msg":"trace[965623253] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"734.127527ms","start":"2026-05-09T05:13:04.983922Z","end":"2026-05-09T05:13:05.718049Z","steps":["trace[965623253] 'process raft request' (duration: 733.968634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.718121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:04.983903Z","time spent":"734.166949ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:05.718207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.557233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:05.718221Z","caller":"traceutil/trace.go:171","msg":"trace[467662907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"626.605074ms","start":"2026-05-09T05:13:05.091612Z","end":"2026-05-09T05:13:05.718217Z","steps":["trace[467662907] 'agreement among raft nodes before linearized reading' (duration: 626.578194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.718233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.091596Z","time spent":"626.635585ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:13:05.718537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.323665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T05:13:05.718551Z","caller":"traceutil/trace.go:171","msg":"trace[125003220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"502.364786ms","start":"2026-05-09T05:13:05.216182Z","end":"2026-05-09T05:13:05.718547Z","steps":["trace[125003220] 'agreement among raft nodes before linearized reading' (duration: 502.290434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.718527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.372642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T05:13:05.718563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.216173Z","time spent":"502.387866ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T05:13:05.718588Z","caller":"traceutil/trace.go:171","msg":"trace[651020977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"577.482304ms","start":"2026-05-09T05:13:05.14109Z","end":"2026-05-09T05:13:05.718572Z","steps":["trace[651020977] 'agreement among raft nodes before linearized reading' (duration: 577.321591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.718622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.141076Z","time spent":"577.539045ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T05:13:05.718686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.52412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T05:13:05.718702Z","caller":"traceutil/trace.go:171","msg":"trace[1029801471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"542.56031ms","start":"2026-05-09T05:13:05.176135Z","end":"2026-05-09T05:13:05.718696Z","steps":["trace[1029801471] 'agreement among raft nodes before linearized reading' (duration: 542.519599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.718713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.176121Z","time spent":"542.590352ms","remote":"127.0.0.1:52856","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-05-09T05:13:05.718755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.381479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:13:05.718825Z","caller":"traceutil/trace.go:171","msg":"trace[927969917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"503.519062ms","start":"2026-05-09T05:13:05.215292Z","end":"2026-05-09T05:13:05.718811Z","steps":["trace[927969917] 'agreement among raft nodes before linearized reading' (duration: 503.310037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:05.718858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.21528Z","time spent":"503.572654ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T05:13:06.71894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.751882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:13:06.719074Z","caller":"traceutil/trace.go:171","msg":"trace[1056574218] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"997.295132ms","start":"2026-05-09T05:13:05.721753Z","end":"2026-05-09T05:13:06.719048Z","steps":["trace[1056574218] 'process raft request' (duration: 452.328695ms)","trace[1056574218] 'compare' (duration: 544.470746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:06.719162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.721731Z","time spent":"997.387075ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:06.862576Z","caller":"traceutil/trace.go:171","msg":"trace[341266492] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"1.140626521s","start":"2026-05-09T05:13:05.721906Z","end":"2026-05-09T05:13:06.862533Z","steps":["trace[341266492] 'process raft request' (duration: 1.140479367s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:06.86266Z","caller":"traceutil/trace.go:171","msg":"trace[1599310275] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"1.140668622s","start":"2026-05-09T05:13:05.72196Z","end":"2026-05-09T05:13:06.862628Z","steps":["trace[1599310275] 'process raft request' (duration: 1.140469387s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:06.862663Z","caller":"traceutil/trace.go:171","msg":"trace[270073537] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"1.140268322s","start":"2026-05-09T05:13:05.72237Z","end":"2026-05-09T05:13:06.862638Z","steps":["trace[270073537] 'process raft request' (duration: 1.140086348s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:06.862657Z","caller":"traceutil/trace.go:171","msg":"trace[903340398] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"1.140772194s","start":"2026-05-09T05:13:05.721848Z","end":"2026-05-09T05:13:06.86262Z","steps":["trace[903340398] 'process raft request' (duration: 1.133054776s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:06.862743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.7219Z","time spent":"1.140764774s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:06.862809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.721939Z","time spent":"1.140787764s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:06.862808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.722329Z","time spent":"1.140399625s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:06.862831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:05.721834Z","time spent":"1.140910086s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:06.863067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.07368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:06.863105Z","caller":"traceutil/trace.go:171","msg":"trace[115427165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"768.167361ms","start":"2026-05-09T05:13:06.094926Z","end":"2026-05-09T05:13:06.863093Z","steps":["trace[115427165] 'agreement among raft nodes before linearized reading' (duration: 768.08728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:06.863134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:06.094904Z","time spent":"768.223723ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:13:06.862508Z","caller":"traceutil/trace.go:171","msg":"trace[1027303581] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1068; }","duration":"767.455585ms","start":"2026-05-09T05:13:06.095033Z","end":"2026-05-09T05:13:06.862488Z","steps":["trace[1027303581] 'read index received' (duration: 79.063129ms)","trace[1027303581] 'applied index is now lower than readState.Index' (duration: 688.391456ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:12.95129Z","caller":"traceutil/trace.go:171","msg":"trace[316828013] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"106.968741ms","start":"2026-05-09T05:13:12.84429Z","end":"2026-05-09T05:13:12.951259Z","steps":["trace[316828013] 'process raft request' (duration: 106.751496ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:13.113825Z","caller":"traceutil/trace.go:171","msg":"trace[872289734] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"121.684879ms","start":"2026-05-09T05:13:12.99212Z","end":"2026-05-09T05:13:13.113805Z","steps":["trace[872289734] 'process raft request' (duration: 115.221981ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:13.113941Z","caller":"traceutil/trace.go:171","msg":"trace[1647034280] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"121.781212ms","start":"2026-05-09T05:13:12.992153Z","end":"2026-05-09T05:13:13.113935Z","steps":["trace[1647034280] 'process raft request' (duration: 121.574377ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:15.336862Z","caller":"traceutil/trace.go:171","msg":"trace[1667812431] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"215.673481ms","start":"2026-05-09T05:13:15.121164Z","end":"2026-05-09T05:13:15.336838Z","steps":["trace[1667812431] 'process raft request' (duration: 180.556063ms)","trace[1667812431] 'compare' (duration: 34.931144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:27.350163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.975492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T05:13:27.350319Z","caller":"traceutil/trace.go:171","msg":"trace[1458248450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"154.119056ms","start":"2026-05-09T05:13:27.19611Z","end":"2026-05-09T05:13:27.350229Z","steps":["trace[1458248450] 'range keys from in-memory index tree' (duration: 153.741737ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:27.694827Z","caller":"traceutil/trace.go:171","msg":"trace[839604827] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"291.553198ms","start":"2026-05-09T05:13:27.403257Z","end":"2026-05-09T05:13:27.69481Z","steps":["trace[839604827] 'read index received' (duration: 291.417814ms)","trace[839604827] 'applied index is now lower than readState.Index' (duration: 134.734µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:27.695105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.957822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:27.695188Z","caller":"traceutil/trace.go:171","msg":"trace[805529680] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1100; }","duration":"154.051454ms","start":"2026-05-09T05:13:27.541086Z","end":"2026-05-09T05:13:27.695138Z","steps":["trace[805529680] 'agreement among raft nodes before linearized reading' (duration: 153.979812ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:27.695157Z","caller":"traceutil/trace.go:171","msg":"trace[818629841] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"337.30443ms","start":"2026-05-09T05:13:27.357818Z","end":"2026-05-09T05:13:27.695123Z","steps":["trace[818629841] 'process raft request' (duration: 336.905621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:27.695168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.887325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T05:13:27.695328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:27.357798Z","time spent":"337.445593ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:27.695343Z","caller":"traceutil/trace.go:171","msg":"trace[1270311746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"292.11413ms","start":"2026-05-09T05:13:27.403211Z","end":"2026-05-09T05:13:27.695326Z","steps":["trace[1270311746] 'agreement among raft nodes before linearized reading' (duration: 291.790963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:28.548196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.315457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:13:28.548324Z","caller":"traceutil/trace.go:171","msg":"trace[168747122] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"850.091736ms","start":"2026-05-09T05:13:27.698198Z","end":"2026-05-09T05:13:28.548289Z","steps":["trace[168747122] 'process raft request' (duration: 656.594645ms)","trace[168747122] 'compare' (duration: 193.082222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:28.548392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:27.698181Z","time spent":"850.182359ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:28.776337Z","caller":"traceutil/trace.go:171","msg":"trace[497137577] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"794.478997ms","start":"2026-05-09T05:13:27.981832Z","end":"2026-05-09T05:13:28.776311Z","steps":["trace[497137577] 'process raft request' (duration: 781.56937ms)","trace[497137577] 'compare' (duration: 12.352254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:28.77649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:27.981794Z","time spent":"794.61508ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:28.776541Z","caller":"traceutil/trace.go:171","msg":"trace[2097625475] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1142; }","duration":"681.878137ms","start":"2026-05-09T05:13:28.094618Z","end":"2026-05-09T05:13:28.776496Z","steps":["trace[2097625475] 'read index received' (duration: 260.078934ms)","trace[2097625475] 'applied index is now lower than readState.Index' (duration: 421.796263ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:28.776709Z","caller":"traceutil/trace.go:171","msg":"trace[2046216136] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"776.860912ms","start":"2026-05-09T05:13:27.999837Z","end":"2026-05-09T05:13:28.776698Z","steps":["trace[2046216136] 'process raft request' (duration: 776.408121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:28.776769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:27.99979Z","time spent":"776.952144ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:28.776945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.190134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:28.777027Z","caller":"traceutil/trace.go:171","msg":"trace[1799204605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"682.428099ms","start":"2026-05-09T05:13:28.094584Z","end":"2026-05-09T05:13:28.777012Z","steps":["trace[1799204605] 'agreement among raft nodes before linearized reading' (duration: 682.05694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:28.777075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:28.094526Z","time spent":"682.536322ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:13:30.902245Z","caller":"traceutil/trace.go:171","msg":"trace[1777480317] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"345.068558ms","start":"2026-05-09T05:13:30.557145Z","end":"2026-05-09T05:13:30.902213Z","steps":["trace[1777480317] 'process raft request' (duration: 344.849653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:30.902701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:30.557128Z","time spent":"345.244191ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:30.910388Z","caller":"traceutil/trace.go:171","msg":"trace[1101292493] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"127.239897ms","start":"2026-05-09T05:13:30.783084Z","end":"2026-05-09T05:13:30.910324Z","steps":["trace[1101292493] 'process raft request' (duration: 126.848828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:33.594371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143601796,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:13:33.702242Z","caller":"traceutil/trace.go:171","msg":"trace[1240889058] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"790.963776ms","start":"2026-05-09T05:13:32.911251Z","end":"2026-05-09T05:13:33.702215Z","steps":["trace[1240889058] 'process raft request' (duration: 790.784452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:33.702442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:32.911235Z","time spent":"791.089799ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:34.954617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.252383031s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-09T05:13:35.056918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.027947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:13:35.057248Z","caller":"traceutil/trace.go:171","msg":"trace[1935664317] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1157; }","duration":"1.963029899s","start":"2026-05-09T05:13:33.094191Z","end":"2026-05-09T05:13:35.057221Z","steps":["trace[1935664317] 'read index received' (duration: 608.016578ms)","trace[1935664317] 'applied index is now lower than readState.Index' (duration: 1.355011301s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:35.057509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.963294605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:35.0575Z","caller":"traceutil/trace.go:171","msg":"trace[1912741006] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"2.14132051s","start":"2026-05-09T05:13:32.916144Z","end":"2026-05-09T05:13:35.057465Z","steps":["trace[1912741006] 'process raft request' (duration: 2.038585607s)","trace[1912741006] 'compare' (duration: 101.29287ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:35.057587Z","caller":"traceutil/trace.go:171","msg":"trace[1940820629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"1.963425408s","start":"2026-05-09T05:13:33.094144Z","end":"2026-05-09T05:13:35.057569Z","steps":["trace[1940820629] 'agreement among raft nodes before linearized reading' (duration: 1.963283915s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.057631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.094121Z","time spent":"1.963498499s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:13:35.057719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:32.916127Z","time spent":"2.141459144s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:35.057832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.953586112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-09T05:13:35.058462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302828381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:13:35.058521Z","caller":"traceutil/trace.go:171","msg":"trace[1340320793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1116; }","duration":"1.302917793s","start":"2026-05-09T05:13:33.755589Z","end":"2026-05-09T05:13:35.058507Z","steps":["trace[1340320793] 'agreement among raft nodes before linearized reading' (duration: 1.30279088s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.058509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902089317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-09T05:13:35.058565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.755573Z","time spent":"1.302979694s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T05:13:35.058614Z","caller":"traceutil/trace.go:171","msg":"trace[1892248584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1116; }","duration":"1.902244871s","start":"2026-05-09T05:13:33.156349Z","end":"2026-05-09T05:13:35.058594Z","steps":["trace[1892248584] 'agreement among raft nodes before linearized reading' (duration: 1.901899313s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.058643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.934062513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T05:13:35.058667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.156332Z","time spent":"1.902326112s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:13:35.0579Z","caller":"traceutil/trace.go:171","msg":"trace[2090843493] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"1.636302972s","start":"2026-05-09T05:13:33.421579Z","end":"2026-05-09T05:13:35.057882Z","steps":["trace[2090843493] 'process raft request' (duration: 1.635487644s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:35.058717Z","caller":"traceutil/trace.go:171","msg":"trace[1811934020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1116; }","duration":"1.934185335s","start":"2026-05-09T05:13:33.124513Z","end":"2026-05-09T05:13:35.058699Z","steps":["trace[1811934020] 'agreement among raft nodes before linearized reading' (duration: 1.933933359s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.058778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.124492Z","time spent":"1.934273607s","remote":"127.0.0.1:52856","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-05-09T05:13:35.058239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752634188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-09T05:13:35.058889Z","caller":"traceutil/trace.go:171","msg":"trace[2055116024] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:1116; }","duration":"1.753337315s","start":"2026-05-09T05:13:33.305522Z","end":"2026-05-09T05:13:35.058859Z","steps":["trace[2055116024] 'agreement among raft nodes before linearized reading' (duration: 1.752613828s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.058948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.305495Z","time spent":"1.753435128s","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-05-09T05:13:35.05878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.421538Z","time spent":"1.637186983s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:35.058634Z","caller":"traceutil/trace.go:171","msg":"trace[1385627638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1116; }","duration":"1.954268298s","start":"2026-05-09T05:13:33.104188Z","end":"2026-05-09T05:13:35.058457Z","steps":["trace[1385627638] 'agreement among raft nodes before linearized reading' (duration: 1.953357627s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.059996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:33.104168Z","time spent":"1.955807253s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:13:35.058312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033605027s","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-05-09T05:13:35.060423Z","caller":"traceutil/trace.go:171","msg":"trace[2126219451] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1116; }","duration":"1.035749486s","start":"2026-05-09T05:13:34.024653Z","end":"2026-05-09T05:13:35.060402Z","steps":["trace[2126219451] 'agreement among raft nodes before linearized reading' (duration: 1.033618787s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.060572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:34.02463Z","time spent":"1.035918821s","remote":"127.0.0.1:52862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:13:35.35842Z","caller":"traceutil/trace.go:171","msg":"trace[934841032] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"295.795625ms","start":"2026-05-09T05:13:35.062595Z","end":"2026-05-09T05:13:35.358391Z","steps":["trace[934841032] 'read index received' (duration: 259.250874ms)","trace[934841032] 'applied index is now lower than readState.Index' (duration: 36.542961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:35.358597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.940098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:35.358668Z","caller":"traceutil/trace.go:171","msg":"trace[521701151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"296.093021ms","start":"2026-05-09T05:13:35.062536Z","end":"2026-05-09T05:13:35.358629Z","steps":["trace[521701151] 'agreement among raft nodes before linearized reading' (duration: 295.961258ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:35.386857Z","caller":"traceutil/trace.go:171","msg":"trace[1437389774] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"323.429701ms","start":"2026-05-09T05:13:35.063403Z","end":"2026-05-09T05:13:35.386832Z","steps":["trace[1437389774] 'process raft request' (duration: 323.005151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.386989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:35.063386Z","time spent":"323.528553ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:35.387335Z","caller":"traceutil/trace.go:171","msg":"trace[1526382015] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"322.731575ms","start":"2026-05-09T05:13:35.064561Z","end":"2026-05-09T05:13:35.387293Z","steps":["trace[1526382015] 'process raft request' (duration: 322.53344ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:35.387377Z","caller":"traceutil/trace.go:171","msg":"trace[337160984] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"322.240413ms","start":"2026-05-09T05:13:35.065058Z","end":"2026-05-09T05:13:35.387298Z","steps":["trace[337160984] 'process raft request' (duration: 322.178461ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:35.387339Z","caller":"traceutil/trace.go:171","msg":"trace[633885882] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"322.745665ms","start":"2026-05-09T05:13:35.064519Z","end":"2026-05-09T05:13:35.387265Z","steps":["trace[633885882] 'process raft request' (duration: 322.049139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:35.387426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:35.064543Z","time spent":"322.849657ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:35.387458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:35.065025Z","time spent":"322.391417ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:35.387577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:35.064494Z","time spent":"322.96595ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:49.412212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31798874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:13:49.412268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253165029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:49.412295Z","caller":"traceutil/trace.go:171","msg":"trace[1172331687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"1.318121193s","start":"2026-05-09T05:13:48.094157Z","end":"2026-05-09T05:13:49.412278Z","steps":["trace[1172331687] 'range keys from in-memory index tree' (duration: 1.317914038s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:49.412306Z","caller":"traceutil/trace.go:171","msg":"trace[684788069] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1211; }","duration":"1.25323528s","start":"2026-05-09T05:13:48.15906Z","end":"2026-05-09T05:13:49.412296Z","steps":["trace[684788069] 'count revisions from in-memory index tree' (duration: 1.253105417s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:49.412333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:48.159048Z","time spent":"1.253277851s","remote":"127.0.0.1:53080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:13:49.412337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:48.094137Z","time spent":"1.318188814s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:13:49.41235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681860051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:13:49.412355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.628136345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T05:13:49.412438Z","caller":"traceutil/trace.go:171","msg":"trace[442116302] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1211; }","duration":"1.681997364s","start":"2026-05-09T05:13:47.73042Z","end":"2026-05-09T05:13:49.412418Z","steps":["trace[442116302] 'count revisions from in-memory index tree' (duration: 1.6817898s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:49.412475Z","caller":"traceutil/trace.go:171","msg":"trace[1126321094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"1.628305279s","start":"2026-05-09T05:13:47.784147Z","end":"2026-05-09T05:13:49.412452Z","steps":["trace[1126321094] 'range keys from in-memory index tree' (duration: 1.627996412s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:49.412486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:47.730401Z","time spent":"1.682074356s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:13:49.412539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:47.784136Z","time spent":"1.628387541s","remote":"127.0.0.1:52856","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-05-09T05:13:49.413008Z","caller":"traceutil/trace.go:171","msg":"trace[1137904661] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"286.234955ms","start":"2026-05-09T05:13:49.126755Z","end":"2026-05-09T05:13:49.41299Z","steps":["trace[1137904661] 'process raft request' (duration: 286.069441ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:49.421383Z","caller":"traceutil/trace.go:171","msg":"trace[1032001600] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"201.007594ms","start":"2026-05-09T05:13:49.220355Z","end":"2026-05-09T05:13:49.421363Z","steps":["trace[1032001600] 'process raft request' (duration: 200.567034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:49.421455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.237214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:13:49.421486Z","caller":"traceutil/trace.go:171","msg":"trace[1435880041] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1213; }","duration":"136.274294ms","start":"2026-05-09T05:13:49.285204Z","end":"2026-05-09T05:13:49.421478Z","steps":["trace[1435880041] 'agreement among raft nodes before linearized reading' (duration: 136.210583ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:49.421296Z","caller":"traceutil/trace.go:171","msg":"trace[340419877] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1259; }","duration":"136.07151ms","start":"2026-05-09T05:13:49.285209Z","end":"2026-05-09T05:13:49.42128Z","steps":["trace[340419877] 'read index received' (duration: 127.681777ms)","trace[340419877] 'applied index is now lower than readState.Index' (duration: 8.388703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:01.823932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.236893ms","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-05-09T05:14:01.824062Z","caller":"traceutil/trace.go:171","msg":"trace[1793538613] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1253; }","duration":"112.392746ms","start":"2026-05-09T05:14:01.711651Z","end":"2026-05-09T05:14:01.824044Z","steps":["trace[1793538613] 'range keys from in-memory index tree' (duration: 112.219862ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:01.824429Z","caller":"traceutil/trace.go:171","msg":"trace[268369257] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"314.380093ms","start":"2026-05-09T05:14:01.510036Z","end":"2026-05-09T05:14:01.824416Z","steps":["trace[268369257] 'process raft request' (duration: 305.630261ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:01.82442Z","caller":"traceutil/trace.go:171","msg":"trace[711654799] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1304; }","duration":"288.067777ms","start":"2026-05-09T05:14:01.536318Z","end":"2026-05-09T05:14:01.824386Z","steps":["trace[711654799] 'read index received' (duration: 279.358607ms)","trace[711654799] 'applied index is now lower than readState.Index' (duration: 8.70824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:01.824525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:01.510016Z","time spent":"314.451944ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:01.825184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.527938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T05:14:01.825233Z","caller":"traceutil/trace.go:171","msg":"trace[1531795199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"288.610759ms","start":"2026-05-09T05:14:01.536609Z","end":"2026-05-09T05:14:01.82522Z","steps":["trace[1531795199] 'agreement among raft nodes before linearized reading' (duration: 287.908893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:01.825578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.244934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:14:01.825639Z","caller":"traceutil/trace.go:171","msg":"trace[1467046321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"289.327546ms","start":"2026-05-09T05:14:01.536297Z","end":"2026-05-09T05:14:01.825624Z","steps":["trace[1467046321] 'agreement among raft nodes before linearized reading' (duration: 288.19243ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:01.825908Z","caller":"traceutil/trace.go:171","msg":"trace[1854395465] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"312.833747ms","start":"2026-05-09T05:14:01.51306Z","end":"2026-05-09T05:14:01.825894Z","steps":["trace[1854395465] 'process raft request' (duration: 311.25056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:01.825985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:01.513036Z","time spent":"312.904809ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:03.043158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.588579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:14:03.043262Z","caller":"traceutil/trace.go:171","msg":"trace[1815123165] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"160.272547ms","start":"2026-05-09T05:14:02.882972Z","end":"2026-05-09T05:14:03.043245Z","steps":["trace[1815123165] 'read index received' (duration: 54.453643ms)","trace[1815123165] 'applied index is now lower than readState.Index' (duration: 105.817924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:03.043361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.38784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:03.043399Z","caller":"traceutil/trace.go:171","msg":"trace[1057955534] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1261; }","duration":"160.474452ms","start":"2026-05-09T05:14:02.882911Z","end":"2026-05-09T05:14:03.043386Z","steps":["trace[1057955534] 'agreement among raft nodes before linearized reading' (duration: 160.37886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:03.043616Z","caller":"traceutil/trace.go:171","msg":"trace[1624600590] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"188.526326ms","start":"2026-05-09T05:14:02.855059Z","end":"2026-05-09T05:14:03.043585Z","steps":["trace[1624600590] 'process raft request' (duration: 82.443365ms)","trace[1624600590] 'compare' (duration: 105.456596ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:03.647564Z","caller":"traceutil/trace.go:171","msg":"trace[1461076675] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"170.42675ms","start":"2026-05-09T05:14:03.477103Z","end":"2026-05-09T05:14:03.64753Z","steps":["trace[1461076675] 'read index received' (duration: 121.980333ms)","trace[1461076675] 'applied index is now lower than readState.Index' (duration: 48.444527ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:03.647792Z","caller":"traceutil/trace.go:171","msg":"trace[2034210391] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"187.015092ms","start":"2026-05-09T05:14:03.460744Z","end":"2026-05-09T05:14:03.64776Z","steps":["trace[2034210391] 'process raft request' (duration: 138.36616ms)","trace[2034210391] 'compare' (duration: 48.11963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:03.648435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.31093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T05:14:03.648629Z","caller":"traceutil/trace.go:171","msg":"trace[986574844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"171.519765ms","start":"2026-05-09T05:14:03.477092Z","end":"2026-05-09T05:14:03.648612Z","steps":["trace[986574844] 'agreement among raft nodes before linearized reading' (duration: 170.550222ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:05.255475Z","caller":"traceutil/trace.go:171","msg":"trace[1136957133] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"124.135142ms","start":"2026-05-09T05:14:05.131295Z","end":"2026-05-09T05:14:05.25543Z","steps":["trace[1136957133] 'process raft request' (duration: 123.893006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:05.671755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.757934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-p5nl8\" ","response":"range_response_count:1 size:11053"} {"level":"info","ts":"2026-05-09T05:14:05.671833Z","caller":"traceutil/trace.go:171","msg":"trace[1618134255] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-p5nl8; range_end:; response_count:1; response_revision:1280; }","duration":"156.874587ms","start":"2026-05-09T05:14:05.51494Z","end":"2026-05-09T05:14:05.671814Z","steps":["trace[1618134255] 'range keys from in-memory index tree' (duration: 156.626152ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:05.789282Z","caller":"traceutil/trace.go:171","msg":"trace[80751864] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"128.000132ms","start":"2026-05-09T05:14:05.661265Z","end":"2026-05-09T05:14:05.789265Z","steps":["trace[80751864] 'read index received' (duration: 127.92542ms)","trace[80751864] 'applied index is now lower than readState.Index' (duration: 74.092µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:05.789373Z","caller":"traceutil/trace.go:171","msg":"trace[651811708] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"135.916663ms","start":"2026-05-09T05:14:05.653423Z","end":"2026-05-09T05:14:05.78934Z","steps":["trace[651811708] 'process raft request' (duration: 135.727949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:05.78946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.183196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T05:14:05.78948Z","caller":"traceutil/trace.go:171","msg":"trace[1982292894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"128.253157ms","start":"2026-05-09T05:14:05.661221Z","end":"2026-05-09T05:14:05.789474Z","steps":["trace[1982292894] 'agreement among raft nodes before linearized reading' (duration: 128.107604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:06.339021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143602174,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:14:06.840081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143602174,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:14:07.166313Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.377101941s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T05:14:07.166619Z","caller":"traceutil/trace.go:171","msg":"trace[1462324798] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"1.481819584s","start":"2026-05-09T05:14:05.684779Z","end":"2026-05-09T05:14:07.166599Z","steps":["trace[1462324798] 'process raft request' (duration: 1.481699142s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.166735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.684754Z","time spent":"1.481916477s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11367,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:07.223396Z","caller":"traceutil/trace.go:171","msg":"trace[917154253] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1334; }","duration":"1.384922801s","start":"2026-05-09T05:14:05.838455Z","end":"2026-05-09T05:14:07.223378Z","steps":["trace[917154253] 'read index received' (duration: 1.328383317s)","trace[917154253] 'applied index is now lower than readState.Index' (duration: 56.538314ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:07.223581Z","caller":"traceutil/trace.go:171","msg":"trace[1479834365] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"1.429914078s","start":"2026-05-09T05:14:05.79366Z","end":"2026-05-09T05:14:07.223574Z","steps":["trace[1479834365] 'process raft request' (duration: 1.429597321s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.223653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.793641Z","time spent":"1.42995808s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:07.223758Z","caller":"traceutil/trace.go:171","msg":"trace[42894259] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.331291294s","start":"2026-05-09T05:14:05.892462Z","end":"2026-05-09T05:14:07.223753Z","steps":["trace[42894259] 'process raft request' (duration: 1.330888725s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.223789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.892443Z","time spent":"1.331329066s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:07.223909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385465074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:14:07.223931Z","caller":"traceutil/trace.go:171","msg":"trace[1389392403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1284; }","duration":"1.385541415s","start":"2026-05-09T05:14:05.838385Z","end":"2026-05-09T05:14:07.223927Z","steps":["trace[1389392403] 'agreement among raft nodes before linearized reading' (duration: 1.385459553s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.223945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.838363Z","time spent":"1.385579216s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:14:07.757023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.109211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:14:07.757277Z","caller":"traceutil/trace.go:171","msg":"trace[1426962991] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1336; }","duration":"533.820488ms","start":"2026-05-09T05:14:07.223439Z","end":"2026-05-09T05:14:07.75726Z","steps":["trace[1426962991] 'read index received' (duration: 56.39513ms)","trace[1426962991] 'applied index is now lower than readState.Index' (duration: 477.424478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:07.757433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916403115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:14:07.757466Z","caller":"traceutil/trace.go:171","msg":"trace[120652967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"1.916477697s","start":"2026-05-09T05:14:05.840979Z","end":"2026-05-09T05:14:07.757457Z","steps":["trace[120652967] 'agreement among raft nodes before linearized reading' (duration: 1.916334614s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.757502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.84096Z","time spent":"1.916533709s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T05:14:07.757763Z","caller":"traceutil/trace.go:171","msg":"trace[1373003605] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"585.032649ms","start":"2026-05-09T05:14:07.172717Z","end":"2026-05-09T05:14:07.75775Z","steps":["trace[1373003605] 'process raft request' (duration: 107.108469ms)","trace[1373003605] 'compare' (duration: 476.850905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:07.757826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.172703Z","time spent":"585.091981ms","remote":"127.0.0.1:52862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:07.758039Z","caller":"traceutil/trace.go:171","msg":"trace[1013509201] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"584.728222ms","start":"2026-05-09T05:14:07.173301Z","end":"2026-05-09T05:14:07.758029Z","steps":["trace[1013509201] 'process raft request' (duration: 583.818771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.758099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.173295Z","time spent":"584.773373ms","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":913,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:07.758371Z","caller":"traceutil/trace.go:171","msg":"trace[534526932] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"580.269359ms","start":"2026-05-09T05:14:07.178063Z","end":"2026-05-09T05:14:07.758333Z","steps":["trace[534526932] 'process raft request' (duration: 579.146323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.758438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.178053Z","time spent":"580.352282ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:07.758587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306999845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:07.758621Z","caller":"traceutil/trace.go:171","msg":"trace[561213560] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1287; }","duration":"1.307073216s","start":"2026-05-09T05:14:06.45154Z","end":"2026-05-09T05:14:07.758613Z","steps":["trace[561213560] 'agreement among raft nodes before linearized reading' (duration: 1.307013395s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.758654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:06.45152Z","time spent":"1.307126407s","remote":"127.0.0.1:57666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:14:07.758793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665021549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:07.758824Z","caller":"traceutil/trace.go:171","msg":"trace[124901518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"1.66508966s","start":"2026-05-09T05:14:06.093727Z","end":"2026-05-09T05:14:07.758817Z","steps":["trace[124901518] 'agreement among raft nodes before linearized reading' (duration: 1.665046299s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.758853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:06.093705Z","time spent":"1.665142952s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:14:07.759027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912206398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:14:07.759059Z","caller":"traceutil/trace.go:171","msg":"trace[1766742787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1287; }","duration":"1.91227601s","start":"2026-05-09T05:14:05.846774Z","end":"2026-05-09T05:14:07.75905Z","steps":["trace[1766742787] 'agreement among raft nodes before linearized reading' (duration: 1.912176837s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.75909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.846754Z","time spent":"1.912329061s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:14:07.759247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912485325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T05:14:07.759278Z","caller":"traceutil/trace.go:171","msg":"trace[2062852909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"1.912536156s","start":"2026-05-09T05:14:05.846735Z","end":"2026-05-09T05:14:07.759271Z","steps":["trace[2062852909] 'agreement among raft nodes before linearized reading' (duration: 1.912462094s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.759307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:05.846724Z","time spent":"1.912576817s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:14:07.826108Z","caller":"traceutil/trace.go:171","msg":"trace[948771351] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"596.745349ms","start":"2026-05-09T05:14:07.229317Z","end":"2026-05-09T05:14:07.826062Z","steps":["trace[948771351] 'process raft request' (duration: 596.456762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:07.826395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.229303Z","time spent":"596.946014ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:08.717555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.788892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:14:08.717744Z","caller":"traceutil/trace.go:171","msg":"trace[344582082] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"949.995934ms","start":"2026-05-09T05:14:07.767729Z","end":"2026-05-09T05:14:08.717725Z","steps":["trace[344582082] 'process raft request' (duration: 489.953777ms)","trace[344582082] 'compare' (duration: 459.363541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:08.717838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.767688Z","time spent":"950.099536ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:08.719743Z","caller":"traceutil/trace.go:171","msg":"trace[1121426109] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"950.844544ms","start":"2026-05-09T05:14:07.768843Z","end":"2026-05-09T05:14:08.719688Z","steps":["trace[1121426109] 'process raft request' (duration: 948.833018ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:08.719798Z","caller":"traceutil/trace.go:171","msg":"trace[1831868580] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1340; }","duration":"884.224358ms","start":"2026-05-09T05:14:07.835541Z","end":"2026-05-09T05:14:08.719766Z","steps":["trace[1831868580] 'read index received' (duration: 422.173014ms)","trace[1831868580] 'applied index is now lower than readState.Index' (duration: 462.049934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:08.719898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.76882Z","time spent":"950.994977ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:08.719935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.369061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:08.719972Z","caller":"traceutil/trace.go:171","msg":"trace[655445655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"884.433982ms","start":"2026-05-09T05:14:07.835528Z","end":"2026-05-09T05:14:08.719962Z","steps":["trace[655445655] 'agreement among raft nodes before linearized reading' (duration: 884.34687ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:08.720048Z","caller":"traceutil/trace.go:171","msg":"trace[736425114] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"951.172691ms","start":"2026-05-09T05:14:07.768858Z","end":"2026-05-09T05:14:08.720031Z","steps":["trace[736425114] 'process raft request' (duration: 950.751781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:08.720009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.835467Z","time spent":"884.534474ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:14:08.720125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.768819Z","time spent":"951.266853ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:08.720237Z","caller":"traceutil/trace.go:171","msg":"trace[639182867] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"926.236566ms","start":"2026-05-09T05:14:07.793952Z","end":"2026-05-09T05:14:08.720189Z","steps":["trace[639182867] 'process raft request' (duration: 925.760506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:08.720456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:07.793922Z","time spent":"926.41008ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:08.963119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.756741ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T05:14:09.464215Z","caller":"traceutil/trace.go:171","msg":"trace[1406391432] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"109.133826ms","start":"2026-05-09T05:14:09.355059Z","end":"2026-05-09T05:14:09.464193Z","steps":["trace[1406391432] 'process raft request' (duration: 108.980302ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:10.446428Z","caller":"traceutil/trace.go:171","msg":"trace[1718984580] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"193.932542ms","start":"2026-05-09T05:14:10.252468Z","end":"2026-05-09T05:14:10.446401Z","steps":["trace[1718984580] 'read index received' (duration: 193.646205ms)","trace[1718984580] 'applied index is now lower than readState.Index' (duration: 285.237µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:10.446492Z","caller":"traceutil/trace.go:171","msg":"trace[761576021] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"245.558442ms","start":"2026-05-09T05:14:10.200896Z","end":"2026-05-09T05:14:10.446455Z","steps":["trace[761576021] 'process raft request' (duration: 245.317926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:10.446571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.080765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:10.446604Z","caller":"traceutil/trace.go:171","msg":"trace[117557158] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1296; }","duration":"194.188607ms","start":"2026-05-09T05:14:10.252405Z","end":"2026-05-09T05:14:10.446594Z","steps":["trace[117557158] 'agreement among raft nodes before linearized reading' (duration: 194.103015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:11.537887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.924344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:14:11.537974Z","caller":"traceutil/trace.go:171","msg":"trace[1889041465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"813.068048ms","start":"2026-05-09T05:14:10.724886Z","end":"2026-05-09T05:14:11.537954Z","steps":["trace[1889041465] 'range keys from in-memory index tree' (duration: 812.728539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:11.538021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:10.724864Z","time spent":"813.146889ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:14:11.538036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.968972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T05:14:11.53816Z","caller":"traceutil/trace.go:171","msg":"trace[452478811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"812.146956ms","start":"2026-05-09T05:14:10.725985Z","end":"2026-05-09T05:14:11.538132Z","steps":["trace[452478811] 'range keys from in-memory index tree' (duration: 811.747027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:11.538183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.558742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T05:14:11.53824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:10.725977Z","time spent":"812.242498ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:14:11.53826Z","caller":"traceutil/trace.go:171","msg":"trace[284672603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"811.686345ms","start":"2026-05-09T05:14:10.726555Z","end":"2026-05-09T05:14:11.538241Z","steps":["trace[284672603] 'range keys from in-memory index tree' (duration: 811.370798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:11.538305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:10.726527Z","time spent":"811.768427ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:14:11.538728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.855014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:14:11.538799Z","caller":"traceutil/trace.go:171","msg":"trace[72804906] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"443.312462ms","start":"2026-05-09T05:14:11.095474Z","end":"2026-05-09T05:14:11.538787Z","steps":["trace[72804906] 'read index received' (duration: 94.692µs)","trace[72804906] 'applied index is now lower than readState.Index' (duration: 443.21431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:11.538897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.441985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:11.538935Z","caller":"traceutil/trace.go:171","msg":"trace[1347522748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"443.526367ms","start":"2026-05-09T05:14:11.095398Z","end":"2026-05-09T05:14:11.538925Z","steps":["trace[1347522748] 'agreement among raft nodes before linearized reading' (duration: 443.465936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:11.538951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.319707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-09T05:14:11.538962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:11.095374Z","time spent":"443.581858ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:14:11.538977Z","caller":"traceutil/trace.go:171","msg":"trace[412356848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"278.383469ms","start":"2026-05-09T05:14:11.260586Z","end":"2026-05-09T05:14:11.53897Z","steps":["trace[412356848] 'agreement among raft nodes before linearized reading' (duration: 278.292237ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:11.539124Z","caller":"traceutil/trace.go:171","msg":"trace[1431937084] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"797.708543ms","start":"2026-05-09T05:14:10.741402Z","end":"2026-05-09T05:14:11.539111Z","steps":["trace[1431937084] 'process raft request' (duration: 209.398599ms)","trace[1431937084] 'compare' (duration: 587.225919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:11.539207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:10.741385Z","time spent":"797.766634ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:11.860114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.462733ms","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-05-09T05:14:11.860235Z","caller":"traceutil/trace.go:171","msg":"trace[1250655040] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1297; }","duration":"148.595106ms","start":"2026-05-09T05:14:11.711621Z","end":"2026-05-09T05:14:11.860216Z","steps":["trace[1250655040] 'range keys from in-memory index tree' (duration: 148.448263ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:11.8607Z","caller":"traceutil/trace.go:171","msg":"trace[1185147208] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"318.206537ms","start":"2026-05-09T05:14:11.542479Z","end":"2026-05-09T05:14:11.860686Z","steps":["trace[1185147208] 'process raft request' (duration: 256.800571ms)","trace[1185147208] 'compare' (duration: 61.296263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:11.860799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:11.54245Z","time spent":"318.288679ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:11.907188Z","caller":"traceutil/trace.go:171","msg":"trace[456560918] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"364.278659ms","start":"2026-05-09T05:14:11.542883Z","end":"2026-05-09T05:14:11.907161Z","steps":["trace[456560918] 'process raft request' (duration: 364.064934ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:11.907205Z","caller":"traceutil/trace.go:171","msg":"trace[1478291795] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"363.477491ms","start":"2026-05-09T05:14:11.543703Z","end":"2026-05-09T05:14:11.90718Z","steps":["trace[1478291795] 'process raft request' (duration: 363.372588ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:11.907285Z","caller":"traceutil/trace.go:171","msg":"trace[642087521] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"110.693852ms","start":"2026-05-09T05:14:11.796556Z","end":"2026-05-09T05:14:11.90725Z","steps":["trace[642087521] 'process raft request' (duration: 110.58612ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:11.907208Z","caller":"traceutil/trace.go:171","msg":"trace[725486169] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1350; }","duration":"326.55715ms","start":"2026-05-09T05:14:11.580628Z","end":"2026-05-09T05:14:11.907185Z","steps":["trace[725486169] 'read index received' (duration: 218.705043ms)","trace[725486169] 'applied index is now lower than readState.Index' (duration: 107.850747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:11.90735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:11.543661Z","time spent":"363.611484ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:11.907336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:11.542859Z","time spent":"364.400642ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:11.907345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.689693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:11.907243Z","caller":"traceutil/trace.go:171","msg":"trace[1146958070] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"363.133222ms","start":"2026-05-09T05:14:11.544083Z","end":"2026-05-09T05:14:11.907216Z","steps":["trace[1146958070] 'process raft request' (duration: 363.02809ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:11.907523Z","caller":"traceutil/trace.go:171","msg":"trace[1104467165] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1302; }","duration":"326.936808ms","start":"2026-05-09T05:14:11.580576Z","end":"2026-05-09T05:14:11.907513Z","steps":["trace[1104467165] 'agreement among raft nodes before linearized reading' (duration: 326.681402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:11.90757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:11.580557Z","time spent":"326.990369ms","remote":"127.0.0.1:60246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:14:11.907773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:11.544074Z","time spent":"363.621634ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:12.034476Z","caller":"traceutil/trace.go:171","msg":"trace[1570799785] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"120.841796ms","start":"2026-05-09T05:14:11.913602Z","end":"2026-05-09T05:14:12.034444Z","steps":["trace[1570799785] 'process raft request' (duration: 94.167001ms)","trace[1570799785] 'compare' (duration: 26.494261ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:12.034894Z","caller":"traceutil/trace.go:171","msg":"trace[1831542288] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"120.066089ms","start":"2026-05-09T05:14:11.914755Z","end":"2026-05-09T05:14:12.034821Z","steps":["trace[1831542288] 'process raft request' (duration: 119.648679ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:12.058234Z","caller":"traceutil/trace.go:171","msg":"trace[1537370179] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"113.066167ms","start":"2026-05-09T05:14:11.945133Z","end":"2026-05-09T05:14:12.058199Z","steps":["trace[1537370179] 'process raft request' (duration: 112.904123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.046689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265710143602228,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:14:14.156913Z","caller":"traceutil/trace.go:171","msg":"trace[1917644591] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"610.457744ms","start":"2026-05-09T05:14:13.546429Z","end":"2026-05-09T05:14:14.156887Z","steps":["trace[1917644591] 'read index received' (duration: 610.208328ms)","trace[1917644591] 'applied index is now lower than readState.Index' (duration: 248.526µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:14.157041Z","caller":"traceutil/trace.go:171","msg":"trace[655804630] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"610.676249ms","start":"2026-05-09T05:14:13.546329Z","end":"2026-05-09T05:14:14.157005Z","steps":["trace[655804630] 'process raft request' (duration: 610.384333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.627868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-05-09T05:14:14.157125Z","caller":"traceutil/trace.go:171","msg":"trace[368605508] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1310; }","duration":"610.910815ms","start":"2026-05-09T05:14:13.546204Z","end":"2026-05-09T05:14:14.157114Z","steps":["trace[368605508] 'agreement among raft nodes before linearized reading' (duration: 610.804673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:13.546161Z","time spent":"611.003847ms","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-09T05:14:14.157189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:13.546295Z","time spent":"610.817403ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:14.157245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.966429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:14.157319Z","caller":"traceutil/trace.go:171","msg":"trace[656363623] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1310; }","duration":"425.083091ms","start":"2026-05-09T05:14:13.73222Z","end":"2026-05-09T05:14:14.157303Z","steps":["trace[656363623] 'agreement among raft nodes before linearized reading' (duration: 424.872756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:13.732143Z","time spent":"425.225604ms","remote":"127.0.0.1:60296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:14:14.157456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.680943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:14:14.157533Z","caller":"traceutil/trace.go:171","msg":"trace[1282915820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"117.843017ms","start":"2026-05-09T05:14:14.039671Z","end":"2026-05-09T05:14:14.157514Z","steps":["trace[1282915820] 'agreement among raft nodes before linearized reading' (duration: 117.648693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.370078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:14:14.157666Z","caller":"traceutil/trace.go:171","msg":"trace[1676779012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"245.442449ms","start":"2026-05-09T05:14:13.912212Z","end":"2026-05-09T05:14:14.157655Z","steps":["trace[1676779012] 'agreement among raft nodes before linearized reading' (duration: 245.300726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.379462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-09T05:14:14.157864Z","caller":"traceutil/trace.go:171","msg":"trace[2118760408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"289.489835ms","start":"2026-05-09T05:14:13.868361Z","end":"2026-05-09T05:14:14.157851Z","steps":["trace[2118760408] 'agreement among raft nodes before linearized reading' (duration: 289.348182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.563928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:14.157932Z","caller":"traceutil/trace.go:171","msg":"trace[665710246] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1310; }","duration":"237.65824ms","start":"2026-05-09T05:14:13.920261Z","end":"2026-05-09T05:14:14.15792Z","steps":["trace[665710246] 'agreement among raft nodes before linearized reading' (duration: 237.571888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.157872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.435188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T05:14:14.157802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.45607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:14:14.15802Z","caller":"traceutil/trace.go:171","msg":"trace[131182175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"245.709776ms","start":"2026-05-09T05:14:13.912299Z","end":"2026-05-09T05:14:14.158008Z","steps":["trace[131182175] 'agreement among raft nodes before linearized reading' (duration: 245.44676ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:14.158065Z","caller":"traceutil/trace.go:171","msg":"trace[661686509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"245.727835ms","start":"2026-05-09T05:14:13.912305Z","end":"2026-05-09T05:14:14.158033Z","steps":["trace[661686509] 'agreement among raft nodes before linearized reading' (duration: 245.027459ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:14.528501Z","caller":"traceutil/trace.go:171","msg":"trace[1464294290] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"363.137442ms","start":"2026-05-09T05:14:14.165333Z","end":"2026-05-09T05:14:14.52847Z","steps":["trace[1464294290] 'process raft request' (duration: 363.074051ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:14.528612Z","caller":"traceutil/trace.go:171","msg":"trace[1973433553] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"364.097685ms","start":"2026-05-09T05:14:14.164491Z","end":"2026-05-09T05:14:14.528589Z","steps":["trace[1973433553] 'process raft request' (duration: 333.790876ms)","trace[1973433553] 'compare' (duration: 29.623623ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:14.528636Z","caller":"traceutil/trace.go:171","msg":"trace[1619682237] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"364.023883ms","start":"2026-05-09T05:14:14.16457Z","end":"2026-05-09T05:14:14.528594Z","steps":["trace[1619682237] 'process raft request' (duration: 363.711616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.528702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:14.165326Z","time spent":"363.257686ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:14.528722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:14.164475Z","time spent":"364.178207ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:14.528856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:14.164548Z","time spent":"364.176986ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:14.528879Z","caller":"traceutil/trace.go:171","msg":"trace[2129754972] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"363.567023ms","start":"2026-05-09T05:14:14.165269Z","end":"2026-05-09T05:14:14.528836Z","steps":["trace[2129754972] 'process raft request' (duration: 363.064071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:14.529172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:14.165255Z","time spent":"363.703656ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:20.331382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.576222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:14:20.331484Z","caller":"traceutil/trace.go:171","msg":"trace[635302268] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"1.236359496s","start":"2026-05-09T05:14:19.095108Z","end":"2026-05-09T05:14:20.331468Z","steps":["trace[635302268] 'read index received' (duration: 329.660161ms)","trace[635302268] 'applied index is now lower than readState.Index' (duration: 906.698295ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:20.331764Z","caller":"traceutil/trace.go:171","msg":"trace[1379822813] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.262463068s","start":"2026-05-09T05:14:19.069263Z","end":"2026-05-09T05:14:20.331726Z","steps":["trace[1379822813] 'process raft request' (duration: 355.477066ms)","trace[1379822813] 'compare' (duration: 906.45122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:20.331805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236691494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:20.331864Z","caller":"traceutil/trace.go:171","msg":"trace[678871420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"1.236806857s","start":"2026-05-09T05:14:19.095041Z","end":"2026-05-09T05:14:20.331848Z","steps":["trace[678871420] 'agreement among raft nodes before linearized reading' (duration: 1.236676854s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:20.331892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:19.095021Z","time spent":"1.236867868s","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:14:20.331922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:19.069236Z","time spent":"1.262613752s","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:14:20.544975Z","caller":"traceutil/trace.go:171","msg":"trace[1623073025] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"407.859054ms","start":"2026-05-09T05:14:20.137082Z","end":"2026-05-09T05:14:20.544941Z","steps":["trace[1623073025] 'process raft request' (duration: 365.410845ms)","trace[1623073025] 'compare' (duration: 42.096681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:20.545397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.859499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T05:14:20.545442Z","caller":"traceutil/trace.go:171","msg":"trace[150672142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"317.943971ms","start":"2026-05-09T05:14:20.227486Z","end":"2026-05-09T05:14:20.54543Z","steps":["trace[150672142] 'agreement among raft nodes before linearized reading' (duration: 317.778537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:20.545474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:20.227471Z","time spent":"317.995692ms","remote":"127.0.0.1:52856","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-05-09T05:14:20.545589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:20.137055Z","time spent":"408.354905ms","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:14:20.545182Z","caller":"traceutil/trace.go:171","msg":"trace[318531432] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"339.846646ms","start":"2026-05-09T05:14:20.205316Z","end":"2026-05-09T05:14:20.545162Z","steps":["trace[318531432] 'process raft request' (duration: 339.58046ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:20.545136Z","caller":"traceutil/trace.go:171","msg":"trace[1401276850] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"213.609695ms","start":"2026-05-09T05:14:20.33151Z","end":"2026-05-09T05:14:20.54512Z","steps":["trace[1401276850] 'read index received' (duration: 170.991083ms)","trace[1401276850] 'applied index is now lower than readState.Index' (duration: 42.617622ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:20.545347Z","caller":"traceutil/trace.go:171","msg":"trace[109449474] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"208.140479ms","start":"2026-05-09T05:14:20.337195Z","end":"2026-05-09T05:14:20.545336Z","steps":["trace[109449474] 'process raft request' (duration: 207.863663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:20.546055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.91282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:14:20.546026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:20.205296Z","time spent":"340.500771ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:20.546113Z","caller":"traceutil/trace.go:171","msg":"trace[1109312299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"213.005581ms","start":"2026-05-09T05:14:20.333089Z","end":"2026-05-09T05:14:20.546095Z","steps":["trace[1109312299] 'agreement among raft nodes before linearized reading' (duration: 212.921689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:20.546193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.927371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-09T05:14:20.546258Z","caller":"traceutil/trace.go:171","msg":"trace[898907304] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1357; }","duration":"210.013462ms","start":"2026-05-09T05:14:20.336227Z","end":"2026-05-09T05:14:20.546241Z","steps":["trace[898907304] 'agreement among raft nodes before linearized reading' (duration: 209.845958ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:26.893568Z","caller":"traceutil/trace.go:171","msg":"trace[855696842] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"192.087529ms","start":"2026-05-09T05:14:26.701435Z","end":"2026-05-09T05:14:26.893523Z","steps":["trace[855696842] 'process raft request' (duration: 179.821506ms)","trace[855696842] 'compare' (duration: 11.983347ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:26.893614Z","caller":"traceutil/trace.go:171","msg":"trace[578644900] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"191.825854ms","start":"2026-05-09T05:14:26.701763Z","end":"2026-05-09T05:14:26.893588Z","steps":["trace[578644900] 'process raft request' (duration: 191.777123ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:26.893757Z","caller":"traceutil/trace.go:171","msg":"trace[34187614] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"192.070238ms","start":"2026-05-09T05:14:26.701679Z","end":"2026-05-09T05:14:26.893749Z","steps":["trace[34187614] 'process raft request' (duration: 191.786332ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:26.893823Z","caller":"traceutil/trace.go:171","msg":"trace[1430721527] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"192.222512ms","start":"2026-05-09T05:14:26.701572Z","end":"2026-05-09T05:14:26.893795Z","steps":["trace[1430721527] 'process raft request' (duration: 191.834183ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:26.894072Z","caller":"traceutil/trace.go:171","msg":"trace[323068412] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"192.357576ms","start":"2026-05-09T05:14:26.701699Z","end":"2026-05-09T05:14:26.894057Z","steps":["trace[323068412] 'process raft request' (duration: 191.800673ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:15:03.198972Z","caller":"traceutil/trace.go:171","msg":"trace[1005328399] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"100.520048ms","start":"2026-05-09T05:15:03.098427Z","end":"2026-05-09T05:15:03.198947Z","steps":["trace[1005328399] 'process raft request' (duration: 96.919475ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:15:45.87913Z","caller":"traceutil/trace.go:171","msg":"trace[1679112008] linearizableReadLoop","detail":"{readStateIndex:1826; appliedIndex:1825; }","duration":"135.36541ms","start":"2026-05-09T05:15:45.743735Z","end":"2026-05-09T05:15:45.879101Z","steps":["trace[1679112008] 'read index received' (duration: 135.151145ms)","trace[1679112008] 'applied index is now lower than readState.Index' (duration: 213.255µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:15:45.879235Z","caller":"traceutil/trace.go:171","msg":"trace[807076027] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"155.490076ms","start":"2026-05-09T05:15:45.723698Z","end":"2026-05-09T05:15:45.879188Z","steps":["trace[807076027] 'process raft request' (duration: 155.2466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:15:45.879389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.631626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:15:45.879429Z","caller":"traceutil/trace.go:171","msg":"trace[627096347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1754; }","duration":"135.735338ms","start":"2026-05-09T05:15:45.743681Z","end":"2026-05-09T05:15:45.879417Z","steps":["trace[627096347] 'agreement among raft nodes before linearized reading' (duration: 135.573984ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:15:50.064275Z","caller":"traceutil/trace.go:171","msg":"trace[1241982979] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"129.485114ms","start":"2026-05-09T05:15:49.934772Z","end":"2026-05-09T05:15:50.064257Z","steps":["trace[1241982979] 'process raft request' (duration: 129.449123ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:15:50.064443Z","caller":"traceutil/trace.go:171","msg":"trace[295550118] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"129.628978ms","start":"2026-05-09T05:15:49.934757Z","end":"2026-05-09T05:15:50.064386Z","steps":["trace[295550118] 'process raft request' (duration: 93.974263ms)","trace[295550118] 'compare' (duration: 35.356008ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:16:34.584645Z","caller":"traceutil/trace.go:171","msg":"trace[1912963869] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"118.458183ms","start":"2026-05-09T05:16:34.46615Z","end":"2026-05-09T05:16:34.584609Z","steps":["trace[1912963869] 'process raft request' (duration: 117.266235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:36.214388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.156916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:16:36.21453Z","caller":"traceutil/trace.go:171","msg":"trace[270216170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1984; }","duration":"118.36041ms","start":"2026-05-09T05:16:36.096139Z","end":"2026-05-09T05:16:36.2145Z","steps":["trace[270216170] 'range keys from in-memory index tree' (duration: 118.041803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:36.557855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.676906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:16:36.557996Z","caller":"traceutil/trace.go:171","msg":"trace[1349004363] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"180.733274ms","start":"2026-05-09T05:16:36.377241Z","end":"2026-05-09T05:16:36.557974Z","steps":["trace[1349004363] 'read index received' (duration: 57.651µs)","trace[1349004363] 'applied index is now lower than readState.Index' (duration: 180.674033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:16:36.558433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.082682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T05:16:36.558531Z","caller":"traceutil/trace.go:171","msg":"trace[1164869580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1986; }","duration":"181.328027ms","start":"2026-05-09T05:16:36.377179Z","end":"2026-05-09T05:16:36.558507Z","steps":["trace[1164869580] 'agreement among raft nodes before linearized reading' (duration: 180.934738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:36.55872Z","caller":"traceutil/trace.go:171","msg":"trace[1681924550] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"339.53037ms","start":"2026-05-09T05:16:36.219174Z","end":"2026-05-09T05:16:36.558704Z","steps":["trace[1681924550] 'process raft request' (duration: 156.878891ms)","trace[1681924550] 'compare' (duration: 181.056922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:16:36.558829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:16:36.219149Z","time spent":"339.597482ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:16:36.905592Z","caller":"traceutil/trace.go:171","msg":"trace[487209290] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"341.554576ms","start":"2026-05-09T05:16:36.564009Z","end":"2026-05-09T05:16:36.905564Z","steps":["trace[487209290] 'process raft request' (duration: 341.364841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:36.905888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:16:36.563981Z","time spent":"341.782721ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:16:36.923809Z","caller":"traceutil/trace.go:171","msg":"trace[443712754] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"359.048751ms","start":"2026-05-09T05:16:36.56473Z","end":"2026-05-09T05:16:36.923779Z","steps":["trace[443712754] 'process raft request' (duration: 358.98942ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:36.923911Z","caller":"traceutil/trace.go:171","msg":"trace[1088633685] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"359.247596ms","start":"2026-05-09T05:16:36.56463Z","end":"2026-05-09T05:16:36.923877Z","steps":["trace[1088633685] 'process raft request' (duration: 358.853826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:36.924029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:16:36.564708Z","time spent":"359.188094ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:16:36.924105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:16:36.564616Z","time spent":"359.393299ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:17:08.699599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.431261ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-09T05:17:09.761509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.713786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-09T05:17:09.761599Z","caller":"traceutil/trace.go:171","msg":"trace[1153299601] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2137; }","duration":"111.87977ms","start":"2026-05-09T05:17:09.649697Z","end":"2026-05-09T05:17:09.761577Z","steps":["trace[1153299601] 'range keys from in-memory index tree' (duration: 111.45468ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:17:34.627235Z","caller":"traceutil/trace.go:171","msg":"trace[1886022665] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"348.712498ms","start":"2026-05-09T05:17:34.278496Z","end":"2026-05-09T05:17:34.627209Z","steps":["trace[1886022665] 'process raft request' (duration: 348.581665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:17:34.627414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:17:34.278484Z","time spent":"348.838231ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:17:40.229145Z","caller":"traceutil/trace.go:171","msg":"trace[1033290905] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"116.008394ms","start":"2026-05-09T05:17:40.113097Z","end":"2026-05-09T05:17:40.229106Z","steps":["trace[1033290905] 'process raft request' (duration: 115.774388ms)"],"step_count":1}