{"level":"warn","ts":"2026-04-02T22:30:55.154232Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T22:30:55.154347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T22:30:55.154425Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T22:30:55.154438Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-02T22:30:55.154455Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T22:30:55.155127Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-04-02T22:30:55.155202Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T22:30:55.159217Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.764494ms"} {"level":"info","ts":"2026-04-02T22:30:55.188649Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-04-02T22:30:55.189073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T22:30:55.189106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-04-02T22:30:55.189116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T22:30:55.189128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-04-02T22:30:55.189169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-04-02T22:30:55.213315Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T22:30:55.218755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T22:30:55.222318Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T22:30:55.227159Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T22:30:55.227421Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T22:30:55.227489Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T22:30:55.2275Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T22:30:55.227743Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T22:30:55.232166Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-02T22:30:55.232258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-02T22:30:55.232049Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T22:30:55.232544Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T22:30:55.232582Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T22:30:55.245755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-04-02T22:30:55.245942Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-02T22:30:55.890173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T22:30:55.890261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T22:30:55.890284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-04-02T22:30:55.890303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-04-02T22:30:55.89032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-02T22:30:55.890337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-04-02T22:30:55.89035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-02T22:30:55.89158Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T22:30:55.891751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:30:55.891831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:30:55.892097Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:30:55.892284Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T22:30:55.892331Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T22:30:55.894937Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T22:30:55.895938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-04-02T22:30:55.901729Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:30:55.901817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:30:55.90184Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:31:19.035019Z","caller":"traceutil/trace.go:171","msg":"trace[1558598145] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"181.465591ms","start":"2026-04-02T22:31:18.853537Z","end":"2026-04-02T22:31:19.035002Z","steps":["trace[1558598145] 'read index received' (duration: 181.394949ms)","trace[1558598145] 'applied index is now lower than readState.Index' (duration: 69.632µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:19.035174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.592363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:19.03521Z","caller":"traceutil/trace.go:171","msg":"trace[1211053259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"181.676605ms","start":"2026-04-02T22:31:18.853527Z","end":"2026-04-02T22:31:19.035203Z","steps":["trace[1211053259] 'agreement among raft nodes before linearized reading' (duration: 181.583193ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:26.151374Z","caller":"traceutil/trace.go:171","msg":"trace[1336239653] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:529; }","duration":"407.868842ms","start":"2026-04-02T22:31:25.743483Z","end":"2026-04-02T22:31:26.151352Z","steps":["trace[1336239653] 'read index received' (duration: 399.875483ms)","trace[1336239653] 'applied index is now lower than readState.Index' (duration: 7.992509ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:26.151485Z","caller":"traceutil/trace.go:171","msg":"trace[1410492072] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"485.004501ms","start":"2026-04-02T22:31:25.666471Z","end":"2026-04-02T22:31:26.151476Z","steps":["trace[1410492072] 'process raft request' (duration: 484.826377ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:26.151556Z","caller":"traceutil/trace.go:171","msg":"trace[1611329553] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"502.932109ms","start":"2026-04-02T22:31:25.648586Z","end":"2026-04-02T22:31:26.151518Z","steps":["trace[1611329553] 'process raft request' (duration: 502.580862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:26.151681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.19267ms","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-04-02T22:31:26.15169Z","caller":"traceutil/trace.go:171","msg":"trace[1569930746] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"505.513707ms","start":"2026-04-02T22:31:25.646159Z","end":"2026-04-02T22:31:26.151673Z","steps":["trace[1569930746] 'process raft request' (duration: 497.186251ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:26.151725Z","caller":"traceutil/trace.go:171","msg":"trace[473449003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"408.282021ms","start":"2026-04-02T22:31:25.74343Z","end":"2026-04-02T22:31:26.151712Z","steps":["trace[473449003] 'agreement among raft nodes before linearized reading' (duration: 408.195159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:26.151699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.933069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:31:26.151756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:25.74341Z","time spent":"408.337442ms","remote":"127.0.0.1:51216","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-04-02T22:31:26.151785Z","caller":"traceutil/trace.go:171","msg":"trace[1420113700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"300.061152ms","start":"2026-04-02T22:31:25.851696Z","end":"2026-04-02T22:31:26.151757Z","steps":["trace[1420113700] 'agreement among raft nodes before linearized reading' (duration: 299.915569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:26.151841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:25.851673Z","time spent":"300.155154ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:31:26.152484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:25.666439Z","time spent":"485.071791ms","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:31:26.153069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:25.646139Z","time spent":"505.581918ms","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:31:26.153182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:25.648576Z","time spent":"503.070172ms","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:31:29.449577Z","caller":"traceutil/trace.go:171","msg":"trace[153812391] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"552.559375ms","start":"2026-04-02T22:31:28.896992Z","end":"2026-04-02T22:31:29.449552Z","steps":["trace[153812391] 'process raft request' (duration: 552.193936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:29.449766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:28.896959Z","time spent":"552.687288ms","remote":"127.0.0.1:51058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:31:29.450451Z","caller":"traceutil/trace.go:171","msg":"trace[2034399583] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"495.110386ms","start":"2026-04-02T22:31:28.955317Z","end":"2026-04-02T22:31:29.450428Z","steps":["trace[2034399583] 'process raft request' (duration: 494.959092ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:29.450456Z","caller":"traceutil/trace.go:171","msg":"trace[2001253921] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"341.633459ms","start":"2026-04-02T22:31:29.108792Z","end":"2026-04-02T22:31:29.450425Z","steps":["trace[2001253921] 'read index received' (duration: 340.478032ms)","trace[2001253921] 'applied index is now lower than readState.Index' (duration: 1.154026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:29.450583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:28.955295Z","time spent":"495.218668ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:31:29.450696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.890544ms","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-04-02T22:31:29.450739Z","caller":"traceutil/trace.go:171","msg":"trace[1547141385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"341.985306ms","start":"2026-04-02T22:31:29.108739Z","end":"2026-04-02T22:31:29.450724Z","steps":["trace[1547141385] 'agreement among raft nodes before linearized reading' (duration: 341.803792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:29.450782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:29.10871Z","time spent":"342.063187ms","remote":"127.0.0.1:51216","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-04-02T22:31:33.105226Z","caller":"traceutil/trace.go:171","msg":"trace[1861606759] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"271.354482ms","start":"2026-04-02T22:31:32.833846Z","end":"2026-04-02T22:31:33.105201Z","steps":["trace[1861606759] 'process raft request' (duration: 270.866071ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:33.479202Z","caller":"traceutil/trace.go:171","msg":"trace[1878322514] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"323.824981ms","start":"2026-04-02T22:31:33.155343Z","end":"2026-04-02T22:31:33.479168Z","steps":["trace[1878322514] 'process raft request' (duration: 323.557445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:33.479408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:33.155317Z","time spent":"324.007185ms","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:31:34.123516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.133105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:31:34.123666Z","caller":"traceutil/trace.go:171","msg":"trace[1554666831] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"576.190151ms","start":"2026-04-02T22:31:33.547456Z","end":"2026-04-02T22:31:34.123646Z","steps":["trace[1554666831] 'read index received' (duration: 63.686799ms)","trace[1554666831] 'applied index is now lower than readState.Index' (duration: 512.501632ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:34.123767Z","caller":"traceutil/trace.go:171","msg":"trace[509607398] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"605.003072ms","start":"2026-04-02T22:31:33.518751Z","end":"2026-04-02T22:31:34.123754Z","steps":["trace[509607398] 'process raft request' (duration: 92.378776ms)","trace[509607398] 'compare' (duration: 511.797788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:34.123817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:33.51871Z","time spent":"605.082854ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:31:34.12573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:34.125807Z","caller":"traceutil/trace.go:171","msg":"trace[1904106843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"273.617823ms","start":"2026-04-02T22:31:33.852171Z","end":"2026-04-02T22:31:34.125789Z","steps":["trace[1904106843] 'agreement among raft nodes before linearized reading' (duration: 272.390165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:34.125859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.389521ms","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-04-02T22:31:34.125915Z","caller":"traceutil/trace.go:171","msg":"trace[2101973758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"578.478483ms","start":"2026-04-02T22:31:33.54742Z","end":"2026-04-02T22:31:34.125898Z","steps":["trace[2101973758] 'agreement among raft nodes before linearized reading' (duration: 577.164363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:34.125968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:33.5474Z","time spent":"578.542244ms","remote":"127.0.0.1:51216","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-04-02T22:31:34.126184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.211293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fk7wf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T22:31:34.126216Z","caller":"traceutil/trace.go:171","msg":"trace[1428425377] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fk7wf; range_end:; response_count:1; response_revision:585; }","duration":"497.271934ms","start":"2026-04-02T22:31:33.628938Z","end":"2026-04-02T22:31:34.12621Z","steps":["trace[1428425377] 'agreement among raft nodes before linearized reading' (duration: 497.181872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:34.126236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:33.62892Z","time spent":"497.312045ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-fk7wf\" "} {"level":"warn","ts":"2026-04-02T22:31:34.12634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.846079ms","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-04-02T22:31:34.126453Z","caller":"traceutil/trace.go:171","msg":"trace[706182165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"538.994712ms","start":"2026-04-02T22:31:33.587418Z","end":"2026-04-02T22:31:34.126412Z","steps":["trace[706182165] 'agreement among raft nodes before linearized reading' (duration: 537.12911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:34.126493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:33.587391Z","time spent":"539.094285ms","remote":"127.0.0.1:51216","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-04-02T22:31:34.704168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040056799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:31:34.97109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.542591Z","time spent":"428.469651ms","remote":"127.0.0.1:35096","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T22:31:34.971488Z","caller":"traceutil/trace.go:171","msg":"trace[1785673047] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"842.801989ms","start":"2026-04-02T22:31:34.128659Z","end":"2026-04-02T22:31:34.971461Z","steps":["trace[1785673047] 'process raft request' (duration: 826.204989ms)","trace[1785673047] 'compare' (duration: 16.1563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:34.971646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.128635Z","time spent":"842.906661ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:31:35.157125Z","caller":"traceutil/trace.go:171","msg":"trace[1963679838] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.024222239s","start":"2026-04-02T22:31:34.132878Z","end":"2026-04-02T22:31:35.157101Z","steps":["trace[1963679838] 'process raft request' (duration: 996.147433ms)","trace[1963679838] 'compare' (duration: 27.871831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:35.157257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.132866Z","time spent":"1.02432212s","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:31:35.157316Z","caller":"traceutil/trace.go:171","msg":"trace[1620981739] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.024324511s","start":"2026-04-02T22:31:34.132928Z","end":"2026-04-02T22:31:35.157253Z","steps":["trace[1620981739] 'process raft request' (duration: 1.024121876s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:35.157473Z","caller":"traceutil/trace.go:171","msg":"trace[961176324] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.023304497s","start":"2026-04-02T22:31:34.134137Z","end":"2026-04-02T22:31:35.157442Z","steps":["trace[961176324] 'process raft request' (duration: 1.023061762s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:35.15751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.132915Z","time spent":"1.024494044s","remote":"127.0.0.1:51216","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-04-02T22:31:35.157437Z","caller":"traceutil/trace.go:171","msg":"trace[729178163] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.019181346s","start":"2026-04-02T22:31:34.138233Z","end":"2026-04-02T22:31:35.157414Z","steps":["trace[729178163] 'process raft request' (duration: 1.019095735s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:35.159206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.138223Z","time spent":"1.020441635s","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:31:35.157557Z","caller":"traceutil/trace.go:171","msg":"trace[1214455556] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.024538275s","start":"2026-04-02T22:31:34.133009Z","end":"2026-04-02T22:31:35.157547Z","steps":["trace[1214455556] 'process raft request' (duration: 1.024114236s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:35.159729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.132969Z","time spent":"1.026585531s","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:31:35.157543Z","caller":"traceutil/trace.go:171","msg":"trace[495155644] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.022297285s","start":"2026-04-02T22:31:34.135208Z","end":"2026-04-02T22:31:35.157505Z","steps":["trace[495155644] 'process raft request' (duration: 1.022082911s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:35.160102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.13519Z","time spent":"1.024806671s","remote":"127.0.0.1:51216","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-04-02T22:31:35.157602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.134124Z","time spent":"1.02340272s","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:31:35.16136Z","caller":"traceutil/trace.go:171","msg":"trace[869978996] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:608; }","duration":"958.249499ms","start":"2026-04-02T22:31:34.203089Z","end":"2026-04-02T22:31:35.161339Z","steps":["trace[869978996] 'read index received' (duration: 751.786562ms)","trace[869978996] 'applied index is now lower than readState.Index' (duration: 206.461477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:35.161811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.718761ms","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-04-02T22:31:35.161877Z","caller":"traceutil/trace.go:171","msg":"trace[1996592255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"958.819133ms","start":"2026-04-02T22:31:34.20304Z","end":"2026-04-02T22:31:35.161859Z","steps":["trace[1996592255] 'agreement among raft nodes before linearized reading' (duration: 958.69754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:35.161928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.203019Z","time spent":"958.898244ms","remote":"127.0.0.1:51216","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-04-02T22:31:35.16254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.151986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:35.162569Z","caller":"traceutil/trace.go:171","msg":"trace[1957373037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"309.626515ms","start":"2026-04-02T22:31:34.852936Z","end":"2026-04-02T22:31:35.162562Z","steps":["trace[1957373037] 'agreement among raft nodes before linearized reading' (duration: 309.159525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:35.162589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:34.852918Z","time spent":"309.665357ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:31:36.78537Z","caller":"traceutil/trace.go:171","msg":"trace[1693401524] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"113.706972ms","start":"2026-04-02T22:31:36.671633Z","end":"2026-04-02T22:31:36.78534Z","steps":["trace[1693401524] 'process raft request' (duration: 113.502998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:37.149823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.293959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:31:37.150157Z","caller":"traceutil/trace.go:171","msg":"trace[216644255] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"475.011788ms","start":"2026-04-02T22:31:36.6751Z","end":"2026-04-02T22:31:37.150112Z","steps":["trace[216644255] 'process raft request' (duration: 191.30792ms)","trace[216644255] 'compare' (duration: 282.90456ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:37.150253Z","caller":"traceutil/trace.go:171","msg":"trace[1832638913] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"459.957953ms","start":"2026-04-02T22:31:36.690273Z","end":"2026-04-02T22:31:37.150231Z","steps":["trace[1832638913] 'process raft request' (duration: 459.667296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:37.150327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:36.690252Z","time spent":"460.045495ms","remote":"127.0.0.1:50996","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-04-02T22:31:37.150322Z","caller":"traceutil/trace.go:171","msg":"trace[150878299] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"298.565548ms","start":"2026-04-02T22:31:36.851731Z","end":"2026-04-02T22:31:37.150296Z","steps":["trace[150878299] 'process raft request' (duration: 298.501507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:37.150389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:36.675077Z","time spent":"475.172031ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:31:37.150374Z","caller":"traceutil/trace.go:171","msg":"trace[1087602556] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"298.784923ms","start":"2026-04-02T22:31:36.851554Z","end":"2026-04-02T22:31:37.150339Z","steps":["trace[1087602556] 'read index received' (duration: 14.85656ms)","trace[1087602556] 'applied index is now lower than readState.Index' (duration: 283.926393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:37.150562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.986428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:37.150598Z","caller":"traceutil/trace.go:171","msg":"trace[561835775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"299.0699ms","start":"2026-04-02T22:31:36.851517Z","end":"2026-04-02T22:31:37.150587Z","steps":["trace[561835775] 'agreement among raft nodes before linearized reading' (duration: 298.951258ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:37.153766Z","caller":"traceutil/trace.go:171","msg":"trace[176050924] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"261.618137ms","start":"2026-04-02T22:31:36.892134Z","end":"2026-04-02T22:31:37.153752Z","steps":["trace[176050924] 'process raft request' (duration: 261.515084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:50.884531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.317042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9bqw7\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-02T22:31:50.884628Z","caller":"traceutil/trace.go:171","msg":"trace[468604] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9bqw7; range_end:; response_count:1; response_revision:742; }","duration":"161.522316ms","start":"2026-04-02T22:31:50.723084Z","end":"2026-04-02T22:31:50.884606Z","steps":["trace[468604] 'range keys from in-memory index tree' (duration: 161.129678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:53.121935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040057186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:31:53.359464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696742774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:31:53.359539Z","caller":"traceutil/trace.go:171","msg":"trace[1523472830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"1.696854337s","start":"2026-04-02T22:31:51.662666Z","end":"2026-04-02T22:31:53.359521Z","steps":["trace[1523472830] 'range keys from in-memory index tree' (duration: 1.696602751s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:53.359569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:51.662641Z","time spent":"1.696920668s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:31:53.359716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40416098s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:31:53.359811Z","caller":"traceutil/trace.go:171","msg":"trace[1248868355] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.651151899s","start":"2026-04-02T22:31:51.70863Z","end":"2026-04-02T22:31:53.359781Z","steps":["trace[1248868355] 'process raft request' (duration: 246.845596ms)","trace[1248868355] 'compare' (duration: 1.403623787s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:31:53.360122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:51.708601Z","time spent":"1.651470457s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:31:53.507241Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.525586832s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:31:53.50767Z","caller":"traceutil/trace.go:171","msg":"trace[159993861] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"886.741537ms","start":"2026-04-02T22:31:52.620892Z","end":"2026-04-02T22:31:53.507634Z","steps":["trace[159993861] 'read index received' (duration: 886.554362ms)","trace[159993861] 'applied index is now lower than readState.Index' (duration: 186.025µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:53.507726Z","caller":"traceutil/trace.go:171","msg":"trace[1375334462] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.526205846s","start":"2026-04-02T22:31:51.981493Z","end":"2026-04-02T22:31:53.507699Z","steps":["trace[1375334462] 'process raft request' (duration: 1.525931769s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:53.507879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:51.981463Z","time spent":"1.526319109s","remote":"127.0.0.1:51188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:31:53.50792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.012733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T22:31:53.508077Z","caller":"traceutil/trace.go:171","msg":"trace[2032681389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"887.206037ms","start":"2026-04-02T22:31:52.620853Z","end":"2026-04-02T22:31:53.508059Z","steps":["trace[2032681389] 'agreement among raft nodes before linearized reading' (duration: 886.912191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:53.508133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:52.620832Z","time spent":"887.286899ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:31:53.525731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.730344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:53.525901Z","caller":"traceutil/trace.go:171","msg":"trace[1763136750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"673.97662ms","start":"2026-04-02T22:31:52.851903Z","end":"2026-04-02T22:31:53.52588Z","steps":["trace[1763136750] 'agreement among raft nodes before linearized reading' (duration: 673.688833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:53.525992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:31:52.851879Z","time spent":"674.099612ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:31:53.52627Z","caller":"traceutil/trace.go:171","msg":"trace[1516494193] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"162.150792ms","start":"2026-04-02T22:31:53.363764Z","end":"2026-04-02T22:31:53.525915Z","steps":["trace[1516494193] 'process raft request' (duration: 161.62228ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:57.921236Z","caller":"traceutil/trace.go:171","msg":"trace[564114805] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"155.796468ms","start":"2026-04-02T22:31:57.765415Z","end":"2026-04-02T22:31:57.921211Z","steps":["trace[564114805] 'process raft request' (duration: 155.313887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:58.093211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.296127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:31:58.093311Z","caller":"traceutil/trace.go:171","msg":"trace[1929967658] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"167.496829ms","start":"2026-04-02T22:31:57.925797Z","end":"2026-04-02T22:31:58.093293Z","steps":["trace[1929967658] 'process raft request' (duration: 36.056189ms)","trace[1929967658] 'compare' (duration: 131.086782ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:58.128153Z","caller":"traceutil/trace.go:171","msg":"trace[2022098317] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"202.330241ms","start":"2026-04-02T22:31:57.925794Z","end":"2026-04-02T22:31:58.128124Z","steps":["trace[2022098317] 'process raft request' (duration: 202.119727ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:58.128412Z","caller":"traceutil/trace.go:171","msg":"trace[1229107918] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"201.674627ms","start":"2026-04-02T22:31:57.92671Z","end":"2026-04-02T22:31:58.128384Z","steps":["trace[1229107918] 'process raft request' (duration: 201.35282ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:31:58.592281Z","caller":"traceutil/trace.go:171","msg":"trace[1020470046] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"117.083078ms","start":"2026-04-02T22:31:58.475173Z","end":"2026-04-02T22:31:58.592256Z","steps":["trace[1020470046] 'read index received' (duration: 116.73035ms)","trace[1020470046] 'applied index is now lower than readState.Index' (duration: 351.458µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:31:58.592331Z","caller":"traceutil/trace.go:171","msg":"trace[1864241622] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"137.641761ms","start":"2026-04-02T22:31:58.45467Z","end":"2026-04-02T22:31:58.592311Z","steps":["trace[1864241622] 'process raft request' (duration: 137.306362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:31:58.592468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.274642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:31:58.592567Z","caller":"traceutil/trace.go:171","msg":"trace[682117663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:814; }","duration":"117.415516ms","start":"2026-04-02T22:31:58.475129Z","end":"2026-04-02T22:31:58.592544Z","steps":["trace[682117663] 'agreement among raft nodes before linearized reading' (duration: 117.230082ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:13.834667Z","caller":"traceutil/trace.go:171","msg":"trace[683113444] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"127.180506ms","start":"2026-04-02T22:32:13.707461Z","end":"2026-04-02T22:32:13.834642Z","steps":["trace[683113444] 'process raft request' (duration: 105.11868ms)","trace[683113444] 'compare' (duration: 21.948423ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:28.467375Z","caller":"traceutil/trace.go:171","msg":"trace[1765614950] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"193.100215ms","start":"2026-04-02T22:32:28.274255Z","end":"2026-04-02T22:32:28.467356Z","steps":["trace[1765614950] 'process raft request' (duration: 192.994283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:29.719438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040057652,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:32:29.748994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.373927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:32:29.749202Z","caller":"traceutil/trace.go:171","msg":"trace[828550994] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"530.06787ms","start":"2026-04-02T22:32:29.219121Z","end":"2026-04-02T22:32:29.749189Z","steps":["trace[828550994] 'read index received' (duration: 526.319435ms)","trace[828550994] 'applied index is now lower than readState.Index' (duration: 3.747615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:29.749296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.188543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:32:29.749317Z","caller":"traceutil/trace.go:171","msg":"trace[900968642] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:971; }","duration":"530.251204ms","start":"2026-04-02T22:32:29.219057Z","end":"2026-04-02T22:32:29.749308Z","steps":["trace[900968642] 'agreement among raft nodes before linearized reading' (duration: 530.167212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:29.749341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.21903Z","time spent":"530.304955ms","remote":"127.0.0.1:51420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:32:29.751044Z","caller":"traceutil/trace.go:171","msg":"trace[2121065614] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"571.11048ms","start":"2026-04-02T22:32:29.179914Z","end":"2026-04-02T22:32:29.751024Z","steps":["trace[2121065614] 'process raft request' (duration: 569.174127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:29.751102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.179881Z","time spent":"571.186522ms","remote":"127.0.0.1:51216","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-04-02T22:32:29.751157Z","caller":"traceutil/trace.go:171","msg":"trace[1684433199] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"656.956957ms","start":"2026-04-02T22:32:29.094148Z","end":"2026-04-02T22:32:29.751105Z","steps":["trace[1684433199] 'process raft request' (duration: 25.36245ms)","trace[1684433199] 'compare' (duration: 629.213643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:29.751389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.094128Z","time spent":"657.1297ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:32:30.352602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040057659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:32:30.853043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040057659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:32:31.342995Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.527756195s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:32:31.370109Z","caller":"traceutil/trace.go:171","msg":"trace[1993446816] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"1.554973846s","start":"2026-04-02T22:32:29.815097Z","end":"2026-04-02T22:32:31.370071Z","steps":["trace[1993446816] 'process raft request' (duration: 1.528273277s)","trace[1993446816] 'compare' (duration: 26.201898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:31.37026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.815076Z","time spent":"1.555103688s","remote":"127.0.0.1:51216","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-04-02T22:32:31.432127Z","caller":"traceutil/trace.go:171","msg":"trace[1246349657] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"1.580092449s","start":"2026-04-02T22:32:29.852004Z","end":"2026-04-02T22:32:31.432096Z","steps":["trace[1246349657] 'read index received' (duration: 1.491272886s)","trace[1246349657] 'applied index is now lower than readState.Index' (duration: 88.818513ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:31.432242Z","caller":"traceutil/trace.go:171","msg":"trace[675895032] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"960.368547ms","start":"2026-04-02T22:32:30.471821Z","end":"2026-04-02T22:32:31.43219Z","steps":["trace[675895032] 'process raft request' (duration: 960.136502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.432366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:30.471804Z","time spent":"960.494721ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:32:31.432352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580347515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:32:31.432413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.862825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T22:32:31.432408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561620175s","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-04-02T22:32:31.432419Z","caller":"traceutil/trace.go:171","msg":"trace[348138684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"1.580457408s","start":"2026-04-02T22:32:29.851942Z","end":"2026-04-02T22:32:31.432399Z","steps":["trace[348138684] 'agreement among raft nodes before linearized reading' (duration: 1.580310945s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:31.43244Z","caller":"traceutil/trace.go:171","msg":"trace[385903806] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:973; }","duration":"828.891585ms","start":"2026-04-02T22:32:30.60354Z","end":"2026-04-02T22:32:31.432432Z","steps":["trace[385903806] 'agreement among raft nodes before linearized reading' (duration: 828.856955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.432467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:30.603499Z","time spent":"828.961387ms","remote":"127.0.0.1:51334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:32:31.432463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.851913Z","time spent":"1.580539629s","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:32:31.432456Z","caller":"traceutil/trace.go:171","msg":"trace[1775637417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.561670236s","start":"2026-04-02T22:32:29.870771Z","end":"2026-04-02T22:32:31.432441Z","steps":["trace[1775637417] 'agreement among raft nodes before linearized reading' (duration: 1.561507402s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.432513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.870709Z","time spent":"1.561793448s","remote":"127.0.0.1:51216","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-04-02T22:32:31.432563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518604909s","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-04-02T22:32:31.43263Z","caller":"traceutil/trace.go:171","msg":"trace[919162359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.518758843s","start":"2026-04-02T22:32:29.913854Z","end":"2026-04-02T22:32:31.432613Z","steps":["trace[919162359] 'agreement among raft nodes before linearized reading' (duration: 1.518572038s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.432365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145523814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:32:31.43267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.913833Z","time spent":"1.518828265s","remote":"127.0.0.1:51216","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":"info","ts":"2026-04-02T22:32:31.432684Z","caller":"traceutil/trace.go:171","msg":"trace[883649766] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:973; }","duration":"1.145896924s","start":"2026-04-02T22:32:30.286774Z","end":"2026-04-02T22:32:31.432671Z","steps":["trace[883649766] 'agreement among raft nodes before linearized reading' (duration: 1.145517774s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.432735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45132595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T22:32:31.432767Z","caller":"traceutil/trace.go:171","msg":"trace[600659758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.451386441s","start":"2026-04-02T22:32:29.981371Z","end":"2026-04-02T22:32:31.432758Z","steps":["trace[600659758] 'agreement among raft nodes before linearized reading' (duration: 1.451247408s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.432791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.981348Z","time spent":"1.451437172s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:32:31.432737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:30.286754Z","time spent":"1.145967975s","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:32:31.432846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526473536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:32:31.432978Z","caller":"traceutil/trace.go:171","msg":"trace[1502548813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"1.526564997s","start":"2026-04-02T22:32:29.906321Z","end":"2026-04-02T22:32:31.432886Z","steps":["trace[1502548813] 'agreement among raft nodes before linearized reading' (duration: 1.526413404s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:31.433053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:29.9063Z","time spent":"1.526726282s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:32:34.461595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.61763ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T22:32:37.679367Z","caller":"traceutil/trace.go:171","msg":"trace[171295675] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"113.330813ms","start":"2026-04-02T22:32:37.566017Z","end":"2026-04-02T22:32:37.679348Z","steps":["trace[171295675] 'process raft request' (duration: 103.325769ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:37.679529Z","caller":"traceutil/trace.go:171","msg":"trace[1029044534] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1033; }","duration":"109.485988ms","start":"2026-04-02T22:32:37.570016Z","end":"2026-04-02T22:32:37.679502Z","steps":["trace[1029044534] 'read index received' (duration: 34.630488ms)","trace[1029044534] 'applied index is now lower than readState.Index' (duration: 74.85375ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:37.679553Z","caller":"traceutil/trace.go:171","msg":"trace[1447434200] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"112.591787ms","start":"2026-04-02T22:32:37.566934Z","end":"2026-04-02T22:32:37.679526Z","steps":["trace[1447434200] 'process raft request' (duration: 112.380993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:37.679738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.688693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T22:32:37.679769Z","caller":"traceutil/trace.go:171","msg":"trace[2127751467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"109.773495ms","start":"2026-04-02T22:32:37.569987Z","end":"2026-04-02T22:32:37.679761Z","steps":["trace[2127751467] 'agreement among raft nodes before linearized reading' (duration: 109.59295ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:43.549456Z","caller":"traceutil/trace.go:171","msg":"trace[59283117] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"108.991367ms","start":"2026-04-02T22:32:43.440438Z","end":"2026-04-02T22:32:43.549429Z","steps":["trace[59283117] 'process raft request' (duration: 108.850253ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:43.945501Z","caller":"traceutil/trace.go:171","msg":"trace[814088891] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"301.18117ms","start":"2026-04-02T22:32:43.6443Z","end":"2026-04-02T22:32:43.945481Z","steps":["trace[814088891] 'process raft request' (duration: 266.242086ms)","trace[814088891] 'compare' (duration: 34.620657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:43.945631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:43.644289Z","time spent":"301.276402ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:32:43.945777Z","caller":"traceutil/trace.go:171","msg":"trace[1119109088] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"191.405806ms","start":"2026-04-02T22:32:43.753881Z","end":"2026-04-02T22:32:43.945287Z","steps":["trace[1119109088] 'read index received' (duration: 156.639596ms)","trace[1119109088] 'applied index is now lower than readState.Index' (duration: 34.76512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:43.945842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.950378ms","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-04-02T22:32:43.945923Z","caller":"traceutil/trace.go:171","msg":"trace[252499824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"192.048561ms","start":"2026-04-02T22:32:43.753844Z","end":"2026-04-02T22:32:43.945893Z","steps":["trace[252499824] 'agreement among raft nodes before linearized reading' (duration: 191.815746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:43.946097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.404776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T22:32:43.946088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.247649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T22:32:43.946153Z","caller":"traceutil/trace.go:171","msg":"trace[1519378939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"124.381972ms","start":"2026-04-02T22:32:43.821756Z","end":"2026-04-02T22:32:43.946138Z","steps":["trace[1519378939] 'agreement among raft nodes before linearized reading' (duration: 124.178568ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:43.946143Z","caller":"traceutil/trace.go:171","msg":"trace[655158230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"187.474937ms","start":"2026-04-02T22:32:43.758655Z","end":"2026-04-02T22:32:43.94613Z","steps":["trace[655158230] 'agreement among raft nodes before linearized reading' (duration: 187.298033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:44.854609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.030533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T22:32:45.764024Z","caller":"traceutil/trace.go:171","msg":"trace[334343689] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"154.540479ms","start":"2026-04-02T22:32:45.609467Z","end":"2026-04-02T22:32:45.764007Z","steps":["trace[334343689] 'process raft request' (duration: 154.427847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:52.353475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040057828,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:32:52.504144Z","caller":"traceutil/trace.go:171","msg":"trace[1687129631] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"892.969905ms","start":"2026-04-02T22:32:51.611146Z","end":"2026-04-02T22:32:52.504116Z","steps":["trace[1687129631] 'process raft request' (duration: 892.796921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:52.504498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.611128Z","time spent":"893.137508ms","remote":"127.0.0.1:51216","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-04-02T22:32:53.129329Z","caller":"traceutil/trace.go:171","msg":"trace[907080689] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1080; }","duration":"1.276749481s","start":"2026-04-02T22:32:51.852553Z","end":"2026-04-02T22:32:53.129302Z","steps":["trace[907080689] 'read index received' (duration: 651.426783ms)","trace[907080689] 'applied index is now lower than readState.Index' (duration: 625.321598ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:53.129438Z","caller":"traceutil/trace.go:171","msg":"trace[1556886233] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"1.292993945s","start":"2026-04-02T22:32:51.836423Z","end":"2026-04-02T22:32:53.129416Z","steps":["trace[1556886233] 'process raft request' (duration: 1.217333867s)","trace[1556886233] 'compare' (duration: 75.310839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:53.129515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276955895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:32:53.129602Z","caller":"traceutil/trace.go:171","msg":"trace[1587969190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"1.277097688s","start":"2026-04-02T22:32:51.852491Z","end":"2026-04-02T22:32:53.129589Z","steps":["trace[1587969190] 'agreement among raft nodes before linearized reading' (duration: 1.276976795s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:53.129598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.836401Z","time spent":"1.293082267s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:32:53.129637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.852441Z","time spent":"1.2771879s","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:32:53.129701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155236893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:32:53.129781Z","caller":"traceutil/trace.go:171","msg":"trace[440619129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"1.155364276s","start":"2026-04-02T22:32:51.974396Z","end":"2026-04-02T22:32:53.129761Z","steps":["trace[440619129] 'agreement among raft nodes before linearized reading' (duration: 1.15510764s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:53.129832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.974376Z","time spent":"1.155443557s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:32:53.130059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141124936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T22:32:53.130059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13951917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-02T22:32:53.130346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145594385s","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-04-02T22:32:53.13023Z","caller":"traceutil/trace.go:171","msg":"trace[929385341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"1.139687774s","start":"2026-04-02T22:32:51.990526Z","end":"2026-04-02T22:32:53.130214Z","steps":["trace[929385341] 'agreement among raft nodes before linearized reading' (duration: 1.139417848s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:53.130505Z","caller":"traceutil/trace.go:171","msg":"trace[336221229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"1.14581062s","start":"2026-04-02T22:32:51.98465Z","end":"2026-04-02T22:32:53.130461Z","steps":["trace[336221229] 'agreement among raft nodes before linearized reading' (duration: 1.14534282s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:53.130101Z","caller":"traceutil/trace.go:171","msg":"trace[945208159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"1.141198848s","start":"2026-04-02T22:32:51.988889Z","end":"2026-04-02T22:32:53.130087Z","steps":["trace[945208159] 'agreement among raft nodes before linearized reading' (duration: 1.141059705s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:53.130551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.990475Z","time spent":"1.140026341s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:32:53.13059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.984624Z","time spent":"1.145950454s","remote":"127.0.0.1:51216","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-04-02T22:32:53.130587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:51.98887Z","time spent":"1.141699909s","remote":"127.0.0.1:51216","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":"info","ts":"2026-04-02T22:32:53.328243Z","caller":"traceutil/trace.go:171","msg":"trace[833211979] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1082; }","duration":"188.38823ms","start":"2026-04-02T22:32:53.139837Z","end":"2026-04-02T22:32:53.328225Z","steps":["trace[833211979] 'read index received' (duration: 14.047136ms)","trace[833211979] 'applied index is now lower than readState.Index' (duration: 174.340294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:53.328379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.532873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:32:53.328399Z","caller":"traceutil/trace.go:171","msg":"trace[1383705815] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"191.971999ms","start":"2026-04-02T22:32:53.136394Z","end":"2026-04-02T22:32:53.328366Z","steps":["trace[1383705815] 'process raft request' (duration: 105.821485ms)","trace[1383705815] 'compare' (duration: 85.656162ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:53.328405Z","caller":"traceutil/trace.go:171","msg":"trace[449765897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"188.602864ms","start":"2026-04-02T22:32:53.139794Z","end":"2026-04-02T22:32:53.328397Z","steps":["trace[449765897] 'agreement among raft nodes before linearized reading' (duration: 188.513882ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:53.32866Z","caller":"traceutil/trace.go:171","msg":"trace[441208750] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"192.01289ms","start":"2026-04-02T22:32:53.136585Z","end":"2026-04-02T22:32:53.328598Z","steps":["trace[441208750] 'process raft request' (duration: 191.55524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:55.351831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040057842,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:32:55.398524Z","caller":"traceutil/trace.go:171","msg":"trace[1731292468] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"885.756664ms","start":"2026-04-02T22:32:54.512736Z","end":"2026-04-02T22:32:55.398493Z","steps":["trace[1731292468] 'process raft request' (duration: 885.551908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:55.398771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:54.512703Z","time spent":"885.901467ms","remote":"127.0.0.1:51216","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-04-02T22:32:56.420864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.142614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:32:56.421047Z","caller":"traceutil/trace.go:171","msg":"trace[741631884] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1087; }","duration":"1.569319888s","start":"2026-04-02T22:32:54.85171Z","end":"2026-04-02T22:32:56.42103Z","steps":["trace[741631884] 'read index received' (duration: 546.597279ms)","trace[741631884] 'applied index is now lower than readState.Index' (duration: 1.022721629s)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:56.421251Z","caller":"traceutil/trace.go:171","msg":"trace[2015235408] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"1.869677869s","start":"2026-04-02T22:32:54.551561Z","end":"2026-04-02T22:32:56.421239Z","steps":["trace[2015235408] 'process raft request' (duration: 1.697067454s)","trace[2015235408] 'compare' (duration: 171.849368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:56.421324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:54.551536Z","time spent":"1.869741521s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:32:56.421619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088244699s","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-04-02T22:32:56.421624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08827483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-02T22:32:56.42162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263746588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:32:56.421648Z","caller":"traceutil/trace.go:171","msg":"trace[2069706359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"1.08831755s","start":"2026-04-02T22:32:55.333321Z","end":"2026-04-02T22:32:56.421638Z","steps":["trace[2069706359] 'agreement among raft nodes before linearized reading' (duration: 1.088211738s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:56.421661Z","caller":"traceutil/trace.go:171","msg":"trace[843080987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"1.088356432s","start":"2026-04-02T22:32:55.333294Z","end":"2026-04-02T22:32:56.421651Z","steps":["trace[843080987] 'agreement among raft nodes before linearized reading' (duration: 1.088232709s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:56.421684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.333298Z","time spent":"1.088379002s","remote":"127.0.0.1:51216","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-04-02T22:32:56.42169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.333271Z","time spent":"1.088412292s","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T22:32:56.421695Z","caller":"traceutil/trace.go:171","msg":"trace[907224736] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.286699114s","start":"2026-04-02T22:32:55.134976Z","end":"2026-04-02T22:32:56.421675Z","steps":["trace[907224736] 'process raft request' (duration: 1.285980718s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:56.421771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263074203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T22:32:56.421767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570052785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:32:56.4218Z","caller":"traceutil/trace.go:171","msg":"trace[318769774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"1.263122594s","start":"2026-04-02T22:32:55.158669Z","end":"2026-04-02T22:32:56.421792Z","steps":["trace[318769774] 'agreement among raft nodes before linearized reading' (duration: 1.263038792s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:56.421825Z","caller":"traceutil/trace.go:171","msg":"trace[1419435536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"1.570154796s","start":"2026-04-02T22:32:54.851657Z","end":"2026-04-02T22:32:56.421812Z","steps":["trace[1419435536] 'agreement among raft nodes before linearized reading' (duration: 1.570037493s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:56.421613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084648708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:32:56.421846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.158658Z","time spent":"1.263176986s","remote":"127.0.0.1:51216","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-04-02T22:32:56.421856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.186968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T22:32:56.421862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:54.851638Z","time spent":"1.570217768s","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:32:56.421889Z","caller":"traceutil/trace.go:171","msg":"trace[913310467] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1046; }","duration":"467.247469ms","start":"2026-04-02T22:32:55.954631Z","end":"2026-04-02T22:32:56.421879Z","steps":["trace[913310467] 'agreement among raft nodes before linearized reading' (duration: 467.191377ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:56.42188Z","caller":"traceutil/trace.go:171","msg":"trace[2101577727] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1046; }","duration":"1.084954504s","start":"2026-04-02T22:32:55.336906Z","end":"2026-04-02T22:32:56.42186Z","steps":["trace[2101577727] 'agreement among raft nodes before linearized reading' (duration: 1.084613917s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:56.421796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.134946Z","time spent":"1.286790206s","remote":"127.0.0.1:51216","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-04-02T22:32:56.421929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.954616Z","time spent":"467.30475ms","remote":"127.0.0.1:38498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:32:56.421941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.336891Z","time spent":"1.085033666s","remote":"127.0.0.1:35234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:32:56.421685Z","caller":"traceutil/trace.go:171","msg":"trace[216006675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"1.26384718s","start":"2026-04-02T22:32:55.157821Z","end":"2026-04-02T22:32:56.421669Z","steps":["trace[216006675] 'agreement among raft nodes before linearized reading' (duration: 1.263671776s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:56.422079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:55.157805Z","time spent":"1.264262289s","remote":"127.0.0.1:51216","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-04-02T22:32:56.621765Z","caller":"traceutil/trace.go:171","msg":"trace[280258444] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"195.201091ms","start":"2026-04-02T22:32:56.426541Z","end":"2026-04-02T22:32:56.621743Z","steps":["trace[280258444] 'process raft request' (duration: 194.888374ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:56.622005Z","caller":"traceutil/trace.go:171","msg":"trace[1484507693] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1090; }","duration":"190.21309ms","start":"2026-04-02T22:32:56.43177Z","end":"2026-04-02T22:32:56.621984Z","steps":["trace[1484507693] 'read index received' (duration: 61.894671ms)","trace[1484507693] 'applied index is now lower than readState.Index' (duration: 128.317269ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:56.622024Z","caller":"traceutil/trace.go:171","msg":"trace[1115416462] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"195.310553ms","start":"2026-04-02T22:32:56.426682Z","end":"2026-04-02T22:32:56.621992Z","steps":["trace[1115416462] 'process raft request' (duration: 195.018057ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:56.622106Z","caller":"traceutil/trace.go:171","msg":"trace[2105588838] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"194.888614ms","start":"2026-04-02T22:32:56.427205Z","end":"2026-04-02T22:32:56.622093Z","steps":["trace[2105588838] 'process raft request' (duration: 194.716011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:56.622236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.451945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:32:56.622289Z","caller":"traceutil/trace.go:171","msg":"trace[576578058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"190.534167ms","start":"2026-04-02T22:32:56.431732Z","end":"2026-04-02T22:32:56.622266Z","steps":["trace[576578058] 'agreement among raft nodes before linearized reading' (duration: 190.449665ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:57.936794Z","caller":"traceutil/trace.go:171","msg":"trace[714928509] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"175.97101ms","start":"2026-04-02T22:32:57.760801Z","end":"2026-04-02T22:32:57.936772Z","steps":["trace[714928509] 'read index received' (duration: 175.732085ms)","trace[714928509] 'applied index is now lower than readState.Index' (duration: 237.885µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:32:57.937023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.210065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:32:57.937045Z","caller":"traceutil/trace.go:171","msg":"trace[41520934] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"529.543557ms","start":"2026-04-02T22:32:57.407456Z","end":"2026-04-02T22:32:57.937Z","steps":["trace[41520934] 'process raft request' (duration: 529.163289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:57.937343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:57.40741Z","time spent":"529.845704ms","remote":"127.0.0.1:51216","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-04-02T22:32:57.937523Z","caller":"traceutil/trace.go:171","msg":"trace[2076805624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"176.759488ms","start":"2026-04-02T22:32:57.760749Z","end":"2026-04-02T22:32:57.937509Z","steps":["trace[2076805624] 'agreement among raft nodes before linearized reading' (duration: 176.172605ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:58.727586Z","caller":"traceutil/trace.go:171","msg":"trace[1991964096] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"236.729764ms","start":"2026-04-02T22:32:58.490827Z","end":"2026-04-02T22:32:58.727557Z","steps":["trace[1991964096] 'read index received' (duration: 236.598271ms)","trace[1991964096] 'applied index is now lower than readState.Index' (duration: 130.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:32:58.727662Z","caller":"traceutil/trace.go:171","msg":"trace[1330717082] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"299.863632ms","start":"2026-04-02T22:32:58.427762Z","end":"2026-04-02T22:32:58.727626Z","steps":["trace[1330717082] 'process raft request' (duration: 299.617786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:58.728039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.097028ms","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-04-02T22:32:58.728078Z","caller":"traceutil/trace.go:171","msg":"trace[117454358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"231.178229ms","start":"2026-04-02T22:32:58.496886Z","end":"2026-04-02T22:32:58.728065Z","steps":["trace[117454358] 'agreement among raft nodes before linearized reading' (duration: 230.891333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:58.727789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.920299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T22:32:58.727878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.423797ms","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-04-02T22:32:58.72917Z","caller":"traceutil/trace.go:171","msg":"trace[2054580827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"102.753326ms","start":"2026-04-02T22:32:58.626398Z","end":"2026-04-02T22:32:58.729151Z","steps":["trace[2054580827] 'agreement among raft nodes before linearized reading' (duration: 101.387036ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:58.729169Z","caller":"traceutil/trace.go:171","msg":"trace[1536686272] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1052; }","duration":"237.482981ms","start":"2026-04-02T22:32:58.490784Z","end":"2026-04-02T22:32:58.728267Z","steps":["trace[1536686272] 'agreement among raft nodes before linearized reading' (duration: 236.893118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:58.727947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.337295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T22:32:58.729306Z","caller":"traceutil/trace.go:171","msg":"trace[1057886001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"102.732897ms","start":"2026-04-02T22:32:58.626568Z","end":"2026-04-02T22:32:58.729301Z","steps":["trace[1057886001] 'agreement among raft nodes before linearized reading' (duration: 101.233042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:58.727999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.468528ms","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-04-02T22:32:58.729398Z","caller":"traceutil/trace.go:171","msg":"trace[1145762266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"102.935821ms","start":"2026-04-02T22:32:58.626446Z","end":"2026-04-02T22:32:58.729381Z","steps":["trace[1145762266] 'agreement among raft nodes before linearized reading' (duration: 101.343225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:32:58.729605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:32:58.427743Z","time spent":"300.338602ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:32:58.858507Z","caller":"traceutil/trace.go:171","msg":"trace[562458615] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"124.882993ms","start":"2026-04-02T22:32:58.73361Z","end":"2026-04-02T22:32:58.858493Z","steps":["trace[562458615] 'process raft request' (duration: 124.856863ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:58.858565Z","caller":"traceutil/trace.go:171","msg":"trace[489645556] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"124.914244ms","start":"2026-04-02T22:32:58.733612Z","end":"2026-04-02T22:32:58.858526Z","steps":["trace[489645556] 'process raft request' (duration: 124.771681ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:58.942155Z","caller":"traceutil/trace.go:171","msg":"trace[1150167851] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"208.510901ms","start":"2026-04-02T22:32:58.733622Z","end":"2026-04-02T22:32:58.942133Z","steps":["trace[1150167851] 'process raft request' (duration: 208.316106ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:32:58.942438Z","caller":"traceutil/trace.go:171","msg":"trace[1638066671] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"208.752386ms","start":"2026-04-02T22:32:58.733668Z","end":"2026-04-02T22:32:58.94242Z","steps":["trace[1638066671] 'process raft request' (duration: 208.373477ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:02.999723Z","caller":"traceutil/trace.go:171","msg":"trace[325259492] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"125.967751ms","start":"2026-04-02T22:33:02.87373Z","end":"2026-04-02T22:33:02.999698Z","steps":["trace[325259492] 'process raft request' (duration: 125.812978ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:03.039531Z","caller":"traceutil/trace.go:171","msg":"trace[1642705890] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"164.636558ms","start":"2026-04-02T22:33:02.874104Z","end":"2026-04-02T22:33:03.03874Z","steps":["trace[1642705890] 'process raft request' (duration: 164.28552ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:04.890879Z","caller":"traceutil/trace.go:171","msg":"trace[884205201] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"121.91666ms","start":"2026-04-02T22:33:04.768936Z","end":"2026-04-02T22:33:04.890853Z","steps":["trace[884205201] 'process raft request' (duration: 121.780907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:12.493088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.449138ms","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-04-02T22:33:12.493162Z","caller":"traceutil/trace.go:171","msg":"trace[1681658196] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1150; }","duration":"270.581061ms","start":"2026-04-02T22:33:12.222565Z","end":"2026-04-02T22:33:12.493146Z","steps":["trace[1681658196] 'count revisions from in-memory index tree' (duration: 270.352466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:14.237457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.364059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T22:33:14.237545Z","caller":"traceutil/trace.go:171","msg":"trace[953769628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1155; }","duration":"130.487611ms","start":"2026-04-02T22:33:14.107036Z","end":"2026-04-02T22:33:14.237523Z","steps":["trace[953769628] 'range keys from in-memory index tree' (duration: 130.188165ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:14.969281Z","caller":"traceutil/trace.go:171","msg":"trace[1079683052] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"728.15808ms","start":"2026-04-02T22:33:14.241098Z","end":"2026-04-02T22:33:14.969256Z","steps":["trace[1079683052] 'process raft request' (duration: 728.025617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:14.969436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:14.241071Z","time spent":"728.285042ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:14.984526Z","caller":"traceutil/trace.go:171","msg":"trace[1725002224] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"133.426357ms","start":"2026-04-02T22:33:14.851086Z","end":"2026-04-02T22:33:14.984512Z","steps":["trace[1725002224] 'read index received' (duration: 118.368701ms)","trace[1725002224] 'applied index is now lower than readState.Index' (duration: 15.056666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:33:14.984672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.56314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:14.984776Z","caller":"traceutil/trace.go:171","msg":"trace[1857418310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"133.713475ms","start":"2026-04-02T22:33:14.851036Z","end":"2026-04-02T22:33:14.98475Z","steps":["trace[1857418310] 'agreement among raft nodes before linearized reading' (duration: 133.532061ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:29.512939Z","caller":"traceutil/trace.go:171","msg":"trace[953557620] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"139.784001ms","start":"2026-04-02T22:33:29.373134Z","end":"2026-04-02T22:33:29.512918Z","steps":["trace[953557620] 'process raft request' (duration: 104.246655ms)","trace[953557620] 'compare' (duration: 35.387642ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:29.513099Z","caller":"traceutil/trace.go:171","msg":"trace[400929426] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"138.983342ms","start":"2026-04-02T22:33:29.37409Z","end":"2026-04-02T22:33:29.513074Z","steps":["trace[400929426] 'process raft request' (duration: 138.792628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:35.873392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.255311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-02T22:33:35.873442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.985296ms","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-04-02T22:33:35.873471Z","caller":"traceutil/trace.go:171","msg":"trace[1253445505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"323.375133ms","start":"2026-04-02T22:33:35.550078Z","end":"2026-04-02T22:33:35.873453Z","steps":["trace[1253445505] 'range keys from in-memory index tree' (duration: 323.041436ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:35.873505Z","caller":"traceutil/trace.go:171","msg":"trace[2025101516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"578.096629ms","start":"2026-04-02T22:33:35.295393Z","end":"2026-04-02T22:33:35.873489Z","steps":["trace[2025101516] 'range keys from in-memory index tree' (duration: 577.849482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:35.873525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:35.550064Z","time spent":"323.446135ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:33:35.87354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:35.295375Z","time spent":"578.15639ms","remote":"127.0.0.1:51216","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-04-02T22:33:35.873543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.859592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:33:35.873622Z","caller":"traceutil/trace.go:171","msg":"trace[22528675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"465.001945ms","start":"2026-04-02T22:33:35.408597Z","end":"2026-04-02T22:33:35.873599Z","steps":["trace[22528675] 'range keys from in-memory index tree' (duration: 464.661768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:35.873674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:35.408576Z","time spent":"465.085927ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:33:35.873695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.018087ms","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-04-02T22:33:35.87374Z","caller":"traceutil/trace.go:171","msg":"trace[1761629119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"299.083429ms","start":"2026-04-02T22:33:35.574643Z","end":"2026-04-02T22:33:35.873727Z","steps":["trace[1761629119] 'range keys from in-memory index tree' (duration: 298.917465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:37.353357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040058333,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:33:37.456494Z","caller":"traceutil/trace.go:171","msg":"trace[400635041] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"629.258845ms","start":"2026-04-02T22:33:36.827107Z","end":"2026-04-02T22:33:37.456365Z","steps":["trace[400635041] 'process raft request' (duration: 629.05631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:37.456642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:36.827088Z","time spent":"629.475019ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:37.501322Z","caller":"traceutil/trace.go:171","msg":"trace[731168623] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1323; }","duration":"648.835753ms","start":"2026-04-02T22:33:36.852463Z","end":"2026-04-02T22:33:37.501299Z","steps":["trace[731168623] 'read index received' (duration: 603.800074ms)","trace[731168623] 'applied index is now lower than readState.Index' (duration: 45.034649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:33:37.501715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.17927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:37.501784Z","caller":"traceutil/trace.go:171","msg":"trace[1387199115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"649.322533ms","start":"2026-04-02T22:33:36.852446Z","end":"2026-04-02T22:33:37.501768Z","steps":["trace[1387199115] 'agreement among raft nodes before linearized reading' (duration: 649.123689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:37.501821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:36.852394Z","time spent":"649.419295ms","remote":"127.0.0.1:50922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:33:37.501816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.893676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T22:33:37.501863Z","caller":"traceutil/trace.go:171","msg":"trace[436853745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"336.990397ms","start":"2026-04-02T22:33:37.16486Z","end":"2026-04-02T22:33:37.50185Z","steps":["trace[436853745] 'agreement among raft nodes before linearized reading' (duration: 336.822273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:37.501908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:37.16484Z","time spent":"337.059399ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T22:33:37.502023Z","caller":"traceutil/trace.go:171","msg":"trace[8896217] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"442.488462ms","start":"2026-04-02T22:33:37.05952Z","end":"2026-04-02T22:33:37.502008Z","steps":["trace[8896217] 'process raft request' (duration: 441.586292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:37.502106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:37.05947Z","time spent":"442.587324ms","remote":"127.0.0.1:51216","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-04-02T22:33:37.502181Z","caller":"traceutil/trace.go:171","msg":"trace[288054029] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"327.782751ms","start":"2026-04-02T22:33:37.17439Z","end":"2026-04-02T22:33:37.502173Z","steps":["trace[288054029] 'process raft request' (duration: 326.858661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:37.50222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:37.174365Z","time spent":"327.835493ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:38.310107Z","caller":"traceutil/trace.go:171","msg":"trace[966432645] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"172.200857ms","start":"2026-04-02T22:33:38.137882Z","end":"2026-04-02T22:33:38.310083Z","steps":["trace[966432645] 'process raft request' (duration: 172.042263ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:45.419709Z","caller":"traceutil/trace.go:171","msg":"trace[2037546984] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"429.588412ms","start":"2026-04-02T22:33:44.990099Z","end":"2026-04-02T22:33:45.419687Z","steps":["trace[2037546984] 'process raft request' (duration: 429.399217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:45.419827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:44.990072Z","time spent":"429.698424ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:33:45.419842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.131382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-02T22:33:45.419704Z","caller":"traceutil/trace.go:171","msg":"trace[106496052] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"328.966798ms","start":"2026-04-02T22:33:45.090702Z","end":"2026-04-02T22:33:45.419669Z","steps":["trace[106496052] 'read index received' (duration: 328.769313ms)","trace[106496052] 'applied index is now lower than readState.Index' (duration: 196.225µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:45.41989Z","caller":"traceutil/trace.go:171","msg":"trace[1841315213] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1323; }","duration":"329.223494ms","start":"2026-04-02T22:33:45.090655Z","end":"2026-04-02T22:33:45.419878Z","steps":["trace[1841315213] 'agreement among raft nodes before linearized reading' (duration: 329.07999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:45.419977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.090628Z","time spent":"329.314886ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-02T22:33:46.110294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102141040058445,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:33:46.354681Z","caller":"traceutil/trace.go:171","msg":"trace[952199778] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"930.640574ms","start":"2026-04-02T22:33:45.42402Z","end":"2026-04-02T22:33:46.35466Z","steps":["trace[952199778] 'process raft request' (duration: 929.12596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.354864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.423999Z","time spent":"930.792177ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:33:46.356453Z","caller":"traceutil/trace.go:171","msg":"trace[1777762407] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"746.221514ms","start":"2026-04-02T22:33:45.610205Z","end":"2026-04-02T22:33:46.356426Z","steps":["trace[1777762407] 'read index received' (duration: 742.9198ms)","trace[1777762407] 'applied index is now lower than readState.Index' (duration: 3.300584ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:33:46.356743Z","caller":"traceutil/trace.go:171","msg":"trace[750858583] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"823.103016ms","start":"2026-04-02T22:33:45.533626Z","end":"2026-04-02T22:33:46.356729Z","steps":["trace[750858583] 'process raft request' (duration: 822.675537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.356851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.533593Z","time spent":"823.185577ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:33:46.357106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.898748ms","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-04-02T22:33:46.357143Z","caller":"traceutil/trace.go:171","msg":"trace[1360529172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1325; }","duration":"746.96956ms","start":"2026-04-02T22:33:45.610164Z","end":"2026-04-02T22:33:46.357134Z","steps":["trace[1360529172] 'agreement among raft nodes before linearized reading' (duration: 746.852218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.357178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.610145Z","time spent":"747.026852ms","remote":"127.0.0.1:51216","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-04-02T22:33:46.357312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.361456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:33:46.357347Z","caller":"traceutil/trace.go:171","msg":"trace[1174334239] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1325; }","duration":"558.429608ms","start":"2026-04-02T22:33:45.798907Z","end":"2026-04-02T22:33:46.357336Z","steps":["trace[1174334239] 'agreement among raft nodes before linearized reading' (duration: 558.382427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.35738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.798888Z","time spent":"558.484328ms","remote":"127.0.0.1:51442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:33:46.357698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.77115ms","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":"warn","ts":"2026-04-02T22:33:46.357738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.921967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:33:46.357812Z","caller":"traceutil/trace.go:171","msg":"trace[1564755217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"504.025809ms","start":"2026-04-02T22:33:45.85377Z","end":"2026-04-02T22:33:46.357796Z","steps":["trace[1564755217] 'agreement among raft nodes before linearized reading' (duration: 503.916876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.35784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.853755Z","time spent":"504.07876ms","remote":"127.0.0.1:50938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:33:46.357826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.91ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:33:46.357933Z","caller":"traceutil/trace.go:171","msg":"trace[568712470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"410.043483ms","start":"2026-04-02T22:33:45.947876Z","end":"2026-04-02T22:33:46.357919Z","steps":["trace[568712470] 'agreement among raft nodes before linearized reading' (duration: 409.660825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.35798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:33:45.947854Z","time spent":"410.114374ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:33:46.357761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.257333ms","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-04-02T22:33:46.359029Z","caller":"traceutil/trace.go:171","msg":"trace[820227402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"250.537612ms","start":"2026-04-02T22:33:46.108463Z","end":"2026-04-02T22:33:46.359Z","steps":["trace[820227402] 'agreement among raft nodes before linearized reading' (duration: 249.193912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.357736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.157403ms","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-04-02T22:33:46.359099Z","caller":"traceutil/trace.go:171","msg":"trace[1566090668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1325; }","duration":"247.559575ms","start":"2026-04-02T22:33:46.111531Z","end":"2026-04-02T22:33:46.35909Z","steps":["trace[1566090668] 'agreement among raft nodes before linearized reading' (duration: 246.102972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:33:46.357729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.748222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T22:33:46.359178Z","caller":"traceutil/trace.go:171","msg":"trace[472593736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"271.238815ms","start":"2026-04-02T22:33:46.087931Z","end":"2026-04-02T22:33:46.35917Z","steps":["trace[472593736] 'agreement among raft nodes before linearized reading' (duration: 269.604498ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:33:46.35776Z","caller":"traceutil/trace.go:171","msg":"trace[1031359683] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1325; }","duration":"239.882583ms","start":"2026-04-02T22:33:46.117862Z","end":"2026-04-02T22:33:46.357744Z","steps":["trace[1031359683] 'agreement among raft nodes before linearized reading' (duration: 239.720599ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:16.688822Z","caller":"traceutil/trace.go:171","msg":"trace[501554908] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1526; }","duration":"113.251024ms","start":"2026-04-02T22:34:16.575542Z","end":"2026-04-02T22:34:16.688793Z","steps":["trace[501554908] 'read index received' (duration: 72.973486ms)","trace[501554908] 'applied index is now lower than readState.Index' (duration: 40.276128ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:34:16.689146Z","caller":"traceutil/trace.go:171","msg":"trace[1002417983] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"117.014358ms","start":"2026-04-02T22:34:16.572117Z","end":"2026-04-02T22:34:16.689131Z","steps":["trace[1002417983] 'process raft request' (duration: 116.522138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:16.689422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.865008ms","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-04-02T22:34:16.689465Z","caller":"traceutil/trace.go:171","msg":"trace[1707795493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1466; }","duration":"113.93709ms","start":"2026-04-02T22:34:16.575516Z","end":"2026-04-02T22:34:16.689453Z","steps":["trace[1707795493] 'agreement among raft nodes before linearized reading' (duration: 113.796036ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:16.864461Z","caller":"traceutil/trace.go:171","msg":"trace[396663053] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"170.023259ms","start":"2026-04-02T22:34:16.694412Z","end":"2026-04-02T22:34:16.864435Z","steps":["trace[396663053] 'process raft request' (duration: 169.903636ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:16.902478Z","caller":"traceutil/trace.go:171","msg":"trace[2069692827] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"206.725388ms","start":"2026-04-02T22:34:16.695732Z","end":"2026-04-02T22:34:16.902457Z","steps":["trace[2069692827] 'process raft request' (duration: 206.548534ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:16.902668Z","caller":"traceutil/trace.go:171","msg":"trace[2120068211] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"206.184945ms","start":"2026-04-02T22:34:16.696476Z","end":"2026-04-02T22:34:16.902661Z","steps":["trace[2120068211] 'process raft request' (duration: 205.95275ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:16.902823Z","caller":"traceutil/trace.go:171","msg":"trace[149525463] linearizableReadLoop","detail":"{readStateIndex:1532; appliedIndex:1529; }","duration":"194.124996ms","start":"2026-04-02T22:34:16.708682Z","end":"2026-04-02T22:34:16.902807Z","steps":["trace[149525463] 'read index received' (duration: 155.839913ms)","trace[149525463] 'applied index is now lower than readState.Index' (duration: 38.284363ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:34:16.902966Z","caller":"traceutil/trace.go:171","msg":"trace[1885520758] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"205.48367ms","start":"2026-04-02T22:34:16.697474Z","end":"2026-04-02T22:34:16.902958Z","steps":["trace[1885520758] 'process raft request' (duration: 205.152132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:34:16.902974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.267169ms","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-04-02T22:34:16.903041Z","caller":"traceutil/trace.go:171","msg":"trace[940107803] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1470; }","duration":"194.380122ms","start":"2026-04-02T22:34:16.708642Z","end":"2026-04-02T22:34:16.903023Z","steps":["trace[940107803] 'agreement among raft nodes before linearized reading' (duration: 194.256049ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:31.54089Z","caller":"traceutil/trace.go:171","msg":"trace[1136200417] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"104.376586ms","start":"2026-04-02T22:34:31.43649Z","end":"2026-04-02T22:34:31.540867Z","steps":["trace[1136200417] 'process raft request' (duration: 104.233233ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:34:36.709663Z","caller":"traceutil/trace.go:171","msg":"trace[2100318606] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"116.936265ms","start":"2026-04-02T22:34:36.592683Z","end":"2026-04-02T22:34:36.709619Z","steps":["trace[2100318606] 'process raft request' (duration: 61.503901ms)","trace[2100318606] 'compare' (duration: 55.198149ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:34:58.899684Z","caller":"traceutil/trace.go:171","msg":"trace[592197168] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"112.439437ms","start":"2026-04-02T22:34:58.787211Z","end":"2026-04-02T22:34:58.89965Z","steps":["trace[592197168] 'process raft request' (duration: 112.116389ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:35:03.095419Z","caller":"traceutil/trace.go:171","msg":"trace[1326673224] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"123.207256ms","start":"2026-04-02T22:35:02.972169Z","end":"2026-04-02T22:35:03.095377Z","steps":["trace[1326673224] 'process raft request' (duration: 79.85285ms)","trace[1326673224] 'compare' (duration: 42.971758ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:36:51.761219Z","caller":"traceutil/trace.go:171","msg":"trace[389844975] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"139.106311ms","start":"2026-04-02T22:36:51.622063Z","end":"2026-04-02T22:36:51.761169Z","steps":["trace[389844975] 'process raft request' (duration: 138.415995ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:00.05257Z","caller":"traceutil/trace.go:171","msg":"trace[988586614] linearizableReadLoop","detail":"{readStateIndex:2338; appliedIndex:2336; }","duration":"169.250497ms","start":"2026-04-02T22:36:59.883289Z","end":"2026-04-02T22:37:00.05254Z","steps":["trace[988586614] 'read index received' (duration: 94.530371ms)","trace[988586614] 'applied index is now lower than readState.Index' (duration: 74.718756ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:37:00.052625Z","caller":"traceutil/trace.go:171","msg":"trace[1333043212] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"178.637719ms","start":"2026-04-02T22:36:59.873957Z","end":"2026-04-02T22:37:00.052595Z","steps":["trace[1333043212] 'process raft request' (duration: 103.759219ms)","trace[1333043212] 'compare' (duration: 74.43227ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:37:00.052728Z","caller":"traceutil/trace.go:171","msg":"trace[575971421] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"177.493622ms","start":"2026-04-02T22:36:59.875218Z","end":"2026-04-02T22:37:00.052712Z","steps":["trace[575971421] 'process raft request' (duration: 177.250017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.053302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.015414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T22:37:00.053342Z","caller":"traceutil/trace.go:171","msg":"trace[1115516630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2242; }","duration":"170.100677ms","start":"2026-04-02T22:36:59.88323Z","end":"2026-04-02T22:37:00.05333Z","steps":["trace[1115516630] 'agreement among raft nodes before linearized reading' (duration: 170.011904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.053378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.84174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-02T22:37:00.053379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.939108ms","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:531"} {"level":"info","ts":"2026-04-02T22:37:00.053447Z","caller":"traceutil/trace.go:171","msg":"trace[482548451] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2242; }","duration":"110.03372ms","start":"2026-04-02T22:36:59.943398Z","end":"2026-04-02T22:37:00.053432Z","steps":["trace[482548451] 'agreement among raft nodes before linearized reading' (duration: 109.837735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.053423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.19729ms","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-04-02T22:37:00.053488Z","caller":"traceutil/trace.go:171","msg":"trace[2052668466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2242; }","duration":"115.952323ms","start":"2026-04-02T22:36:59.937471Z","end":"2026-04-02T22:37:00.053424Z","steps":["trace[2052668466] 'agreement among raft nodes before linearized reading' (duration: 115.782538ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:00.053498Z","caller":"traceutil/trace.go:171","msg":"trace[1614560116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2242; }","duration":"129.324843ms","start":"2026-04-02T22:36:59.924159Z","end":"2026-04-02T22:37:00.053484Z","steps":["trace[1614560116] 'agreement among raft nodes before linearized reading' (duration: 129.148928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.053926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.571426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-02T22:37:00.053966Z","caller":"traceutil/trace.go:171","msg":"trace[1554687837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2242; }","duration":"169.631347ms","start":"2026-04-02T22:36:59.884325Z","end":"2026-04-02T22:37:00.053956Z","steps":["trace[1554687837] 'agreement among raft nodes before linearized reading' (duration: 168.645324ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:00.37442Z","caller":"traceutil/trace.go:171","msg":"trace[1015136579] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"317.31576ms","start":"2026-04-02T22:37:00.057078Z","end":"2026-04-02T22:37:00.374394Z","steps":["trace[1015136579] 'process raft request' (duration: 316.835569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.37455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:00.057061Z","time spent":"317.417772ms","remote":"127.0.0.1:51216","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-04-02T22:37:00.81653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.562915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:37:00.816807Z","caller":"traceutil/trace.go:171","msg":"trace[841135530] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2339; }","duration":"181.747718ms","start":"2026-04-02T22:37:00.635043Z","end":"2026-04-02T22:37:00.81679Z","steps":["trace[841135530] 'read index received' (duration: 31.801µs)","trace[841135530] 'applied index is now lower than readState.Index' (duration: 181.714867ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:37:00.816898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.84992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:37:00.816931Z","caller":"traceutil/trace.go:171","msg":"trace[1036644606] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2247; }","duration":"181.905601ms","start":"2026-04-02T22:37:00.635017Z","end":"2026-04-02T22:37:00.816922Z","steps":["trace[1036644606] 'agreement among raft nodes before linearized reading' (duration: 181.829369ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:00.816958Z","caller":"traceutil/trace.go:171","msg":"trace[165674816] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"759.705385ms","start":"2026-04-02T22:37:00.057232Z","end":"2026-04-02T22:37:00.816938Z","steps":["trace[165674816] 'process raft request' (duration: 759.395928ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:37:00.816788Z","caller":"traceutil/trace.go:171","msg":"trace[488990147] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"759.585163ms","start":"2026-04-02T22:37:00.057166Z","end":"2026-04-02T22:37:00.816751Z","steps":["trace[488990147] 'process raft request' (duration: 560.714891ms)","trace[488990147] 'compare' (duration: 198.3295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:37:00.817122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:00.057227Z","time spent":"759.803017ms","remote":"127.0.0.1:51216","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-04-02T22:37:00.817135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:00.057144Z","time spent":"759.93338ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:37:00.817129Z","caller":"traceutil/trace.go:171","msg":"trace[959579590] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"759.264886ms","start":"2026-04-02T22:37:00.057842Z","end":"2026-04-02T22:37:00.817107Z","steps":["trace[959579590] 'process raft request' (duration: 758.904528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.81725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:00.057833Z","time spent":"759.347877ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:37:00.817419Z","caller":"traceutil/trace.go:171","msg":"trace[1931318985] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"760.039262ms","start":"2026-04-02T22:37:00.057365Z","end":"2026-04-02T22:37:00.817404Z","steps":["trace[1931318985] 'process raft request' (duration: 759.345887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:00.817487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:00.05736Z","time spent":"760.099153ms","remote":"127.0.0.1:51216","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-04-02T22:37:00.983881Z","caller":"traceutil/trace.go:171","msg":"trace[1075166236] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"112.779822ms","start":"2026-04-02T22:37:00.871073Z","end":"2026-04-02T22:37:00.983853Z","steps":["trace[1075166236] 'process raft request' (duration: 69.765136ms)","trace[1075166236] 'compare' (duration: 42.826742ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:37:01.777501Z","caller":"traceutil/trace.go:171","msg":"trace[1430832456] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"312.000081ms","start":"2026-04-02T22:37:01.465471Z","end":"2026-04-02T22:37:01.777471Z","steps":["trace[1430832456] 'process raft request' (duration: 311.835217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:37:01.777735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:37:01.465446Z","time spent":"312.190774ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}