{"level":"warn","ts":"2026-04-02T08:44:52.148042Z","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-02T08:44:52.148144Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-02T08:44:52.148224Z","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-02T08:44:52.148241Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-02T08:44:52.148263Z","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-02T08:44:52.149564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-04-02T08:44:52.149655Z","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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-02T08:44:52.156144Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.178174ms"} {"level":"info","ts":"2026-04-02T08:44:52.165545Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-04-02T08:44:52.165755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T08:44:52.165793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-04-02T08:44:52.165835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T08:44:52.16585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-04-02T08:44:52.165951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-04-02T08:44:52.174386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T08:44:52.176081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T08:44:52.179373Z","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-02T08:44:52.182318Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T08:44:52.182619Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T08:44:52.182753Z","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-02T08:44:52.182921Z","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-02T08:44:52.182951Z","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-02T08:44:52.189306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-04-02T08:44:52.189829Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-02T08:44:52.191099Z","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-02T08:44:52.191185Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-02T08:44:52.191237Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-02T08:44:52.191554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T08:44:52.191601Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T08:44:52.466544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T08:44:52.466598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T08:44:52.466644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-04-02T08:44:52.466673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-04-02T08:44:52.46671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-02T08:44:52.466739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-04-02T08:44:52.466765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-02T08:44:52.468209Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T08:44:52.468268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:44:52.468416Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:52.468448Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:44:52.468687Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T08:44:52.468836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T08:44:52.473562Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-04-02T08:44:52.474333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T08:44:52.474926Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:52.475085Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:52.475145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:55.018721Z","caller":"traceutil/trace.go:171","msg":"trace[1204625752] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"255.654277ms","start":"2026-04-02T08:44:54.763046Z","end":"2026-04-02T08:44:55.018701Z","steps":["trace[1204625752] 'read index received' (duration: 255.485912ms)","trace[1204625752] 'applied index is now lower than readState.Index' (duration: 167.745µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:44:55.018994Z","caller":"traceutil/trace.go:171","msg":"trace[1568222704] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"256.813511ms","start":"2026-04-02T08:44:54.762116Z","end":"2026-04-02T08:44:55.01893Z","steps":["trace[1568222704] 'process raft request' (duration: 256.466271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:55.019091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.520424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T08:44:55.019112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.20334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T08:44:55.019219Z","caller":"traceutil/trace.go:171","msg":"trace[2075286768] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:70; }","duration":"136.357655ms","start":"2026-04-02T08:44:54.882833Z","end":"2026-04-02T08:44:55.01919Z","steps":["trace[2075286768] 'agreement among raft nodes before linearized reading' (duration: 136.108978ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:44:55.019148Z","caller":"traceutil/trace.go:171","msg":"trace[941581906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"143.645237ms","start":"2026-04-02T08:44:54.875489Z","end":"2026-04-02T08:44:55.019135Z","steps":["trace[941581906] 'agreement among raft nodes before linearized reading' (duration: 143.479273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:55.019402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.214214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T08:44:55.019516Z","caller":"traceutil/trace.go:171","msg":"trace[842436823] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:70; }","duration":"256.44796ms","start":"2026-04-02T08:44:54.763042Z","end":"2026-04-02T08:44:55.01949Z","steps":["trace[842436823] 'agreement among raft nodes before linearized reading' (duration: 256.105021ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:01.18261Z","caller":"traceutil/trace.go:171","msg":"trace[407530204] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"112.212247ms","start":"2026-04-02T08:45:01.070375Z","end":"2026-04-02T08:45:01.182588Z","steps":["trace[407530204] 'read index received' (duration: 111.822757ms)","trace[407530204] 'applied index is now lower than readState.Index' (duration: 388.62µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:01.182675Z","caller":"traceutil/trace.go:171","msg":"trace[1917344599] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"118.081068ms","start":"2026-04-02T08:45:01.064563Z","end":"2026-04-02T08:45:01.182644Z","steps":["trace[1917344599] 'process raft request' (duration: 117.713948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:01.182719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.3216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:01.182749Z","caller":"traceutil/trace.go:171","msg":"trace[83971932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:335; }","duration":"112.392152ms","start":"2026-04-02T08:45:01.070349Z","end":"2026-04-02T08:45:01.182741Z","steps":["trace[83971932] 'agreement among raft nodes before linearized reading' (duration: 112.323481ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:06.583932Z","caller":"traceutil/trace.go:171","msg":"trace[686255117] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"141.546486ms","start":"2026-04-02T08:45:06.442359Z","end":"2026-04-02T08:45:06.583906Z","steps":["trace[686255117] 'process raft request' (duration: 141.400052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:10.761352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111266,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T08:45:10.922985Z","caller":"traceutil/trace.go:171","msg":"trace[1760428480] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"662.354261ms","start":"2026-04-02T08:45:10.260599Z","end":"2026-04-02T08:45:10.922953Z","steps":["trace[1760428480] 'read index received' (duration: 662.173646ms)","trace[1760428480] 'applied index is now lower than readState.Index' (duration: 179.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:10.923118Z","caller":"traceutil/trace.go:171","msg":"trace[476518765] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"826.393253ms","start":"2026-04-02T08:45:10.096616Z","end":"2026-04-02T08:45:10.923009Z","steps":["trace[476518765] 'process raft request' (duration: 826.140047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:10.923223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.569162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T08:45:10.923221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.597737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T08:45:10.923291Z","caller":"traceutil/trace.go:171","msg":"trace[1045356536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"490.689704ms","start":"2026-04-02T08:45:10.432583Z","end":"2026-04-02T08:45:10.923273Z","steps":["trace[1045356536] 'agreement among raft nodes before linearized reading' (duration: 490.562681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:10.923289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.902118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:10.923308Z","caller":"traceutil/trace.go:171","msg":"trace[1354185150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"662.73054ms","start":"2026-04-02T08:45:10.260556Z","end":"2026-04-02T08:45:10.923286Z","steps":["trace[1354185150] 'agreement among raft nodes before linearized reading' (duration: 662.565896ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:10.923355Z","caller":"traceutil/trace.go:171","msg":"trace[1567427281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"109.01906ms","start":"2026-04-02T08:45:10.81432Z","end":"2026-04-02T08:45:10.923339Z","steps":["trace[1567427281] 'agreement among raft nodes before linearized reading' (duration: 108.885197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:10.923348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:10.432564Z","time spent":"490.771626ms","remote":"127.0.0.1:48670","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-02T08:45:10.923364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:10.260533Z","time spent":"662.817433ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:45:10.924767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:10.096591Z","time spent":"826.596908ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:11.407434Z","caller":"traceutil/trace.go:171","msg":"trace[426097306] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"479.512168ms","start":"2026-04-02T08:45:10.927897Z","end":"2026-04-02T08:45:11.407409Z","steps":["trace[426097306] 'process raft request' (duration: 472.429029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:11.407571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:10.927884Z","time spent":"479.61605ms","remote":"127.0.0.1:48670","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-02T08:45:11.410565Z","caller":"traceutil/trace.go:171","msg":"trace[511484278] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"481.65891ms","start":"2026-04-02T08:45:10.928877Z","end":"2026-04-02T08:45:11.410536Z","steps":["trace[511484278] 'process raft request' (duration: 481.379513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:11.410736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:10.92887Z","time spent":"481.768992ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:11.41752Z","caller":"traceutil/trace.go:171","msg":"trace[848381864] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:425; }","duration":"190.920573ms","start":"2026-04-02T08:45:11.226577Z","end":"2026-04-02T08:45:11.417498Z","steps":["trace[848381864] 'read index received' (duration: 173.762342ms)","trace[848381864] 'applied index is now lower than readState.Index' (duration: 17.157031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:11.41765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.052528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-04-02T08:45:11.417733Z","caller":"traceutil/trace.go:171","msg":"trace[1210030907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"191.18603ms","start":"2026-04-02T08:45:11.226537Z","end":"2026-04-02T08:45:11.417723Z","steps":["trace[1210030907] 'agreement among raft nodes before linearized reading' (duration: 191.048717ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:11.534451Z","caller":"traceutil/trace.go:171","msg":"trace[596853682] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"113.551222ms","start":"2026-04-02T08:45:11.42058Z","end":"2026-04-02T08:45:11.534131Z","steps":["trace[596853682] 'process raft request' (duration: 113.347677ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:17.164771Z","caller":"traceutil/trace.go:171","msg":"trace[1344980142] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"168.775421ms","start":"2026-04-02T08:45:16.995973Z","end":"2026-04-02T08:45:17.164748Z","steps":["trace[1344980142] 'read index received' (duration: 168.626797ms)","trace[1344980142] 'applied index is now lower than readState.Index' (duration: 147.504µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:17.164955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.958015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"info","ts":"2026-04-02T08:45:17.16499Z","caller":"traceutil/trace.go:171","msg":"trace[1997962186] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:429; }","duration":"169.037107ms","start":"2026-04-02T08:45:16.995941Z","end":"2026-04-02T08:45:17.164978Z","steps":["trace[1997962186] 'agreement among raft nodes before linearized reading' (duration: 168.899554ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:17.165002Z","caller":"traceutil/trace.go:171","msg":"trace[52815217] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"216.628176ms","start":"2026-04-02T08:45:16.948329Z","end":"2026-04-02T08:45:17.164958Z","steps":["trace[52815217] 'process raft request' (duration: 216.195886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:17.613595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.970026ms","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-02T08:45:17.613679Z","caller":"traceutil/trace.go:171","msg":"trace[827719763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"182.105209ms","start":"2026-04-02T08:45:17.431556Z","end":"2026-04-02T08:45:17.613661Z","steps":["trace[827719763] 'range keys from in-memory index tree' (duration: 181.865373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:17.613746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.175907ms","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-02T08:45:17.613872Z","caller":"traceutil/trace.go:171","msg":"trace[542380110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"181.30418ms","start":"2026-04-02T08:45:17.432522Z","end":"2026-04-02T08:45:17.613826Z","steps":["trace[542380110] 'range keys from in-memory index tree' (duration: 180.973832ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:23.44587Z","caller":"traceutil/trace.go:171","msg":"trace[1317350036] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"250.366365ms","start":"2026-04-02T08:45:23.195487Z","end":"2026-04-02T08:45:23.445854Z","steps":["trace[1317350036] 'process raft request' (duration: 250.259863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:24.293774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.518593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T08:45:24.294242Z","caller":"traceutil/trace.go:171","msg":"trace[1278152530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"646.039155ms","start":"2026-04-02T08:45:23.648175Z","end":"2026-04-02T08:45:24.294215Z","steps":["trace[1278152530] 'range keys from in-memory index tree' (duration: 645.36017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:24.2943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:23.648159Z","time spent":"646.127077ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:45:24.293987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.593564ms","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-02T08:45:24.294437Z","caller":"traceutil/trace.go:171","msg":"trace[1796301662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"646.082456ms","start":"2026-04-02T08:45:23.648283Z","end":"2026-04-02T08:45:24.294366Z","steps":["trace[1796301662] 'range keys from in-memory index tree' (duration: 645.436582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:24.294614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:23.648253Z","time spent":"646.295031ms","remote":"127.0.0.1:48670","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-02T08:45:24.294081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.742341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:24.294841Z","caller":"traceutil/trace.go:171","msg":"trace[746320553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"480.577301ms","start":"2026-04-02T08:45:23.814251Z","end":"2026-04-02T08:45:24.294829Z","steps":["trace[746320553] 'range keys from in-memory index tree' (duration: 479.609249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:24.294899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:23.814235Z","time spent":"480.632333ms","remote":"127.0.0.1:48394","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-02T08:45:24.294139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.781831ms","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-02T08:45:24.295108Z","caller":"traceutil/trace.go:171","msg":"trace[1305605359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"647.792484ms","start":"2026-04-02T08:45:23.647304Z","end":"2026-04-02T08:45:24.295096Z","steps":["trace[1305605359] 'range keys from in-memory index tree' (duration: 646.662469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:24.295169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:23.64669Z","time spent":"648.446619ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T08:45:24.836603Z","caller":"traceutil/trace.go:171","msg":"trace[1512630146] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"536.20079ms","start":"2026-04-02T08:45:24.300376Z","end":"2026-04-02T08:45:24.836577Z","steps":["trace[1512630146] 'process raft request' (duration: 536.110798ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:24.836877Z","caller":"traceutil/trace.go:171","msg":"trace[1061760389] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"536.679402ms","start":"2026-04-02T08:45:24.300179Z","end":"2026-04-02T08:45:24.836858Z","steps":["trace[1061760389] 'process raft request' (duration: 525.042717ms)","trace[1061760389] 'compare' (duration: 10.591161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:24.836969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:24.300143Z","time spent":"536.771244ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:24.837672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:24.300357Z","time spent":"536.324873ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:28.009541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:28.509711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:28.525442Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.680207648s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:45:28.526057Z","caller":"traceutil/trace.go:171","msg":"trace[517199179] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.680922014s","start":"2026-04-02T08:45:26.845095Z","end":"2026-04-02T08:45:28.526017Z","steps":["trace[517199179] 'process raft request' (duration: 1.680678459s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:28.526298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:26.845066Z","time spent":"1.681098857s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:28.595688Z","caller":"traceutil/trace.go:171","msg":"trace[1980209122] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:497; }","duration":"1.08629196s","start":"2026-04-02T08:45:27.50936Z","end":"2026-04-02T08:45:28.595652Z","steps":["trace[1980209122] 'read index received' (duration: 1.01640942s)","trace[1980209122] 'applied index is now lower than readState.Index' (duration: 69.88146ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:28.595756Z","caller":"traceutil/trace.go:171","msg":"trace[189567146] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.749918834s","start":"2026-04-02T08:45:26.845818Z","end":"2026-04-02T08:45:28.595736Z","steps":["trace[189567146] 'process raft request' (duration: 1.749595567s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:28.595882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086485584s","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-02T08:45:28.595928Z","caller":"traceutil/trace.go:171","msg":"trace[1208952601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"1.086603287s","start":"2026-04-02T08:45:27.509313Z","end":"2026-04-02T08:45:28.595917Z","steps":["trace[1208952601] 'agreement among raft nodes before linearized reading' (duration: 1.086477484s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:28.595971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:27.509294Z","time spent":"1.086669168s","remote":"127.0.0.1:48670","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-02T08:45:28.596048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:26.845789Z","time spent":"1.750105668s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:29.10087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.825212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:45:29.101153Z","caller":"traceutil/trace.go:171","msg":"trace[714753628] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"500.268047ms","start":"2026-04-02T08:45:28.60086Z","end":"2026-04-02T08:45:29.101128Z","steps":["trace[714753628] 'process raft request' (duration: 500.150434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:29.101267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:28.600817Z","time spent":"500.40254ms","remote":"127.0.0.1:48670","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-02T08:45:29.101179Z","caller":"traceutil/trace.go:171","msg":"trace[1014641314] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"521.415752ms","start":"2026-04-02T08:45:28.57967Z","end":"2026-04-02T08:45:29.101086Z","steps":["trace[1014641314] 'process raft request' (duration: 72.124209ms)","trace[1014641314] 'compare' (duration: 448.509335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:29.101459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:28.579622Z","time spent":"521.727169ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:29.101496Z","caller":"traceutil/trace.go:171","msg":"trace[1854865478] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"505.685476ms","start":"2026-04-02T08:45:28.595799Z","end":"2026-04-02T08:45:29.101485Z","steps":["trace[1854865478] 'read index received' (duration: 56.066386ms)","trace[1854865478] 'applied index is now lower than readState.Index' (duration: 449.61803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:29.101779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.590638ms","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-02T08:45:29.101826Z","caller":"traceutil/trace.go:171","msg":"trace[346948206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"756.662629ms","start":"2026-04-02T08:45:28.345153Z","end":"2026-04-02T08:45:29.101816Z","steps":["trace[346948206] 'agreement among raft nodes before linearized reading' (duration: 756.522086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:29.101867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:28.345137Z","time spent":"756.717201ms","remote":"127.0.0.1:48670","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-02T08:45:29.101953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.395956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:29.101999Z","caller":"traceutil/trace.go:171","msg":"trace[1571093064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"289.483637ms","start":"2026-04-02T08:45:28.8125Z","end":"2026-04-02T08:45:29.101983Z","steps":["trace[1571093064] 'agreement among raft nodes before linearized reading' (duration: 289.394256ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:29.362483Z","caller":"traceutil/trace.go:171","msg":"trace[1942939492] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"253.553201ms","start":"2026-04-02T08:45:29.108887Z","end":"2026-04-02T08:45:29.36244Z","steps":["trace[1942939492] 'process raft request' (duration: 154.308389ms)","trace[1942939492] 'compare' (duration: 99.058888ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:30.675293Z","caller":"traceutil/trace.go:171","msg":"trace[436671254] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"140.964406ms","start":"2026-04-02T08:45:30.534293Z","end":"2026-04-02T08:45:30.675258Z","steps":["trace[436671254] 'process raft request' (duration: 140.718701ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:30.927715Z","caller":"traceutil/trace.go:171","msg":"trace[1033744062] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"112.73921ms","start":"2026-04-02T08:45:30.81494Z","end":"2026-04-02T08:45:30.92768Z","steps":["trace[1033744062] 'read index received' (duration: 109.529101ms)","trace[1033744062] 'applied index is now lower than readState.Index' (duration: 3.208639ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:30.927823Z","caller":"traceutil/trace.go:171","msg":"trace[242599662] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"248.660637ms","start":"2026-04-02T08:45:30.679125Z","end":"2026-04-02T08:45:30.927785Z","steps":["trace[242599662] 'process raft request' (duration: 245.297664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:30.92789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.908975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:30.927933Z","caller":"traceutil/trace.go:171","msg":"trace[291214594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"112.985917ms","start":"2026-04-02T08:45:30.814935Z","end":"2026-04-02T08:45:30.927921Z","steps":["trace[291214594] 'agreement among raft nodes before linearized reading' (duration: 112.878184ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.362269Z","caller":"traceutil/trace.go:171","msg":"trace[1821472309] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"103.546735ms","start":"2026-04-02T08:45:33.258685Z","end":"2026-04-02T08:45:33.362232Z","steps":["trace[1821472309] 'process raft request' (duration: 92.146812ms)","trace[1821472309] 'compare' (duration: 11.161068ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:33.370942Z","caller":"traceutil/trace.go:171","msg":"trace[31238854] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"111.330831ms","start":"2026-04-02T08:45:33.259591Z","end":"2026-04-02T08:45:33.370922Z","steps":["trace[31238854] 'process raft request' (duration: 111.070265ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.371332Z","caller":"traceutil/trace.go:171","msg":"trace[1749085333] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"104.75244ms","start":"2026-04-02T08:45:33.26656Z","end":"2026-04-02T08:45:33.371313Z","steps":["trace[1749085333] 'process raft request' (duration: 104.578006ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.371385Z","caller":"traceutil/trace.go:171","msg":"trace[2077514274] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"111.632177ms","start":"2026-04-02T08:45:33.259741Z","end":"2026-04-02T08:45:33.371373Z","steps":["trace[2077514274] 'process raft request' (duration: 111.316171ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.371512Z","caller":"traceutil/trace.go:171","msg":"trace[1079891009] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"103.79002ms","start":"2026-04-02T08:45:33.267714Z","end":"2026-04-02T08:45:33.371504Z","steps":["trace[1079891009] 'process raft request' (duration: 103.545865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.232183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:35.406898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.00050528s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:45:35.430217Z","caller":"traceutil/trace.go:171","msg":"trace[1932410924] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"2.029607974s","start":"2026-04-02T08:45:33.400571Z","end":"2026-04-02T08:45:35.430179Z","steps":["trace[1932410924] 'process raft request' (duration: 2.006530359s)","trace[1932410924] 'compare' (duration: 22.098884ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:35.430276Z","caller":"traceutil/trace.go:171","msg":"trace[1569589108] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"2.029271937s","start":"2026-04-02T08:45:33.400905Z","end":"2026-04-02T08:45:35.430177Z","steps":["trace[1569589108] 'process raft request' (duration: 2.028643044s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:35.430375Z","caller":"traceutil/trace.go:171","msg":"trace[1933029810] transaction","detail":"{read_only:false; number_of_response:0; response_revision:547; }","duration":"2.029370678s","start":"2026-04-02T08:45:33.400977Z","end":"2026-04-02T08:45:35.430347Z","steps":["trace[1933029810] 'process raft request' (duration: 2.028682394s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:35.430447Z","caller":"traceutil/trace.go:171","msg":"trace[804739483] transaction","detail":"{read_only:false; number_of_response:0; response_revision:547; }","duration":"2.02943544s","start":"2026-04-02T08:45:33.400999Z","end":"2026-04-02T08:45:35.430435Z","steps":["trace[804739483] 'process raft request' (duration: 2.028683754s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.430504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.400968Z","time spent":"2.02947773s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:45:35.43051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.400993Z","time spent":"2.029482901s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:35.430396Z","caller":"traceutil/trace.go:171","msg":"trace[1551917284] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"2.029245116s","start":"2026-04-02T08:45:33.401035Z","end":"2026-04-02T08:45:35.43028Z","steps":["trace[1551917284] 'process raft request' (duration: 2.028674754s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:35.430623Z","caller":"traceutil/trace.go:171","msg":"trace[1928940589] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"2.019503949s","start":"2026-04-02T08:45:33.41111Z","end":"2026-04-02T08:45:35.430614Z","steps":["trace[1928940589] 'process raft request' (duration: 2.018659991s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.430623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.400896Z","time spent":"2.029542592s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:35.430686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.411091Z","time spent":"2.019553859s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:35.43038Z","caller":"traceutil/trace.go:171","msg":"trace[1556468818] transaction","detail":"{read_only:false; number_of_response:0; response_revision:546; }","duration":"2.029513561s","start":"2026-04-02T08:45:33.400845Z","end":"2026-04-02T08:45:35.430358Z","steps":["trace[1556468818] 'process raft request' (duration: 2.028636433s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.430853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.400824Z","time spent":"2.030006891s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:35.430899Z","caller":"traceutil/trace.go:171","msg":"trace[2110853813] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"2.018547647s","start":"2026-04-02T08:45:33.41234Z","end":"2026-04-02T08:45:35.430888Z","steps":["trace[2110853813] 'process raft request' (duration: 2.017526156s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.430958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.412314Z","time spent":"2.018612139s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:35.431028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.401027Z","time spent":"2.029628803s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:35.431047Z","caller":"traceutil/trace.go:171","msg":"trace[938665625] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"2.007370199s","start":"2026-04-02T08:45:33.423663Z","end":"2026-04-02T08:45:35.431033Z","steps":["trace[938665625] 'process raft request' (duration: 2.006307807s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.431326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.423637Z","time spent":"2.007571894s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:35.430374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.40042Z","time spent":"2.02987424s","remote":"127.0.0.1:48670","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-02T08:45:35.431447Z","caller":"traceutil/trace.go:171","msg":"trace[968640681] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:565; }","duration":"699.963973ms","start":"2026-04-02T08:45:34.731458Z","end":"2026-04-02T08:45:35.431422Z","steps":["trace[968640681] 'read index received' (duration: 675.660253ms)","trace[968640681] 'applied index is now lower than readState.Index' (duration: 24.3024ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:35.431511Z","caller":"traceutil/trace.go:171","msg":"trace[1309288104] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.928841247s","start":"2026-04-02T08:45:33.502655Z","end":"2026-04-02T08:45:35.431496Z","steps":["trace[1309288104] 'process raft request' (duration: 1.928609372s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.431579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.502626Z","time spent":"1.928918509s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:35.431696Z","caller":"traceutil/trace.go:171","msg":"trace[1547924117] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.003929427s","start":"2026-04-02T08:45:33.427739Z","end":"2026-04-02T08:45:35.431668Z","steps":["trace[1547924117] 'process raft request' (duration: 2.002353934s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:35.431752Z","caller":"traceutil/trace.go:171","msg":"trace[2079009499] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"117.266364ms","start":"2026-04-02T08:45:35.314473Z","end":"2026-04-02T08:45:35.431739Z","steps":["trace[2079009499] 'process raft request' (duration: 116.865695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.431864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:33.427721Z","time spent":"2.004039289s","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:35.431889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.431754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T08:45:35.431922Z","caller":"traceutil/trace.go:171","msg":"trace[364614655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"700.466684ms","start":"2026-04-02T08:45:34.731446Z","end":"2026-04-02T08:45:35.431913Z","steps":["trace[364614655] 'agreement among raft nodes before linearized reading' (duration: 700.357312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.431943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:34.731387Z","time spent":"700.550036ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:45:35.432077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.040963ms","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-02T08:45:35.432094Z","caller":"traceutil/trace.go:171","msg":"trace[572399438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"299.095584ms","start":"2026-04-02T08:45:35.132993Z","end":"2026-04-02T08:45:35.432088Z","steps":["trace[572399438] 'agreement among raft nodes before linearized reading' (duration: 299.043433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.432195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.442212ms","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-02T08:45:35.432211Z","caller":"traceutil/trace.go:171","msg":"trace[228531460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"490.491723ms","start":"2026-04-02T08:45:34.941713Z","end":"2026-04-02T08:45:35.432205Z","steps":["trace[228531460] 'agreement among raft nodes before linearized reading' (duration: 490.427192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.432227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:34.941693Z","time spent":"490.529694ms","remote":"127.0.0.1:48670","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-02T08:45:35.432317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.431149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:35.432339Z","caller":"traceutil/trace.go:171","msg":"trace[793250949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"617.48167ms","start":"2026-04-02T08:45:34.814851Z","end":"2026-04-02T08:45:35.432332Z","steps":["trace[793250949] 'agreement among raft nodes before linearized reading' (duration: 617.445709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:35.432356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:34.814807Z","time spent":"617.543901ms","remote":"127.0.0.1:48394","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-02T08:45:42.46088Z","caller":"traceutil/trace.go:171","msg":"trace[1650263143] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"318.508524ms","start":"2026-04-02T08:45:42.142344Z","end":"2026-04-02T08:45:42.460853Z","steps":["trace[1650263143] 'process raft request' (duration: 318.323641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:42.461217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:42.142325Z","time spent":"318.665888ms","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:44.000114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:44.501298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:44.835595Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.951813114s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:45:44.836635Z","caller":"traceutil/trace.go:171","msg":"trace[429107457] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"1.337085865s","start":"2026-04-02T08:45:43.499523Z","end":"2026-04-02T08:45:44.836609Z","steps":["trace[429107457] 'read index received' (duration: 1.336280349s)","trace[429107457] 'applied index is now lower than readState.Index' (duration: 804.416µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:44.836761Z","caller":"traceutil/trace.go:171","msg":"trace[230981222] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.669176977s","start":"2026-04-02T08:45:42.167575Z","end":"2026-04-02T08:45:44.836752Z","steps":["trace[230981222] 'process raft request' (duration: 2.668478613s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:44.836885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:42.167554Z","time spent":"2.669237057s","remote":"127.0.0.1:48484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:44.83699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250546448s","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-02T08:45:44.837256Z","caller":"traceutil/trace.go:171","msg":"trace[1633747417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.250877744s","start":"2026-04-02T08:45:43.586362Z","end":"2026-04-02T08:45:44.83724Z","steps":["trace[1633747417] 'agreement among raft nodes before linearized reading' (duration: 1.250511557s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:44.837519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:43.586355Z","time spent":"1.251121619s","remote":"127.0.0.1:48670","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-02T08:45:44.836911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257729121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-02T08:45:44.837053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337533003s","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-02T08:45:44.837837Z","caller":"traceutil/trace.go:171","msg":"trace[1111768872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"1.25868715s","start":"2026-04-02T08:45:43.579126Z","end":"2026-04-02T08:45:44.837813Z","steps":["trace[1111768872] 'agreement among raft nodes before linearized reading' (duration: 1.2576744s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:44.837907Z","caller":"traceutil/trace.go:171","msg":"trace[495896172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"1.338408741s","start":"2026-04-02T08:45:43.499467Z","end":"2026-04-02T08:45:44.837876Z","steps":["trace[495896172] 'agreement among raft nodes before linearized reading' (duration: 1.337472333s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:44.837983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:43.499445Z","time spent":"1.338516943s","remote":"127.0.0.1:48670","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-02T08:45:44.837986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:43.579111Z","time spent":"1.258855123s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:45:44.837082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251166301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T08:45:44.83712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021507639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:44.838079Z","caller":"traceutil/trace.go:171","msg":"trace[673259972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.252193561s","start":"2026-04-02T08:45:43.585861Z","end":"2026-04-02T08:45:44.838054Z","steps":["trace[673259972] 'agreement among raft nodes before linearized reading' (duration: 1.251057679s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:44.838148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:43.585853Z","time spent":"1.252274912s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:45:44.838149Z","caller":"traceutil/trace.go:171","msg":"trace[1529538197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.022762473s","start":"2026-04-02T08:45:43.815337Z","end":"2026-04-02T08:45:44.8381Z","steps":["trace[1529538197] 'agreement among raft nodes before linearized reading' (duration: 1.021517029s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:44.83826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:43.815316Z","time spent":"1.022928167s","remote":"127.0.0.1:48394","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-02T08:45:45.035954Z","caller":"traceutil/trace.go:171","msg":"trace[1782385054] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"192.130586ms","start":"2026-04-02T08:45:44.843808Z","end":"2026-04-02T08:45:45.035938Z","steps":["trace[1782385054] 'process raft request' (duration: 192.029984ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.035935Z","caller":"traceutil/trace.go:171","msg":"trace[1100155829] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:665; }","duration":"188.158086ms","start":"2026-04-02T08:45:44.847753Z","end":"2026-04-02T08:45:45.035911Z","steps":["trace[1100155829] 'read index received' (duration: 31.848271ms)","trace[1100155829] 'applied index is now lower than readState.Index' (duration: 156.308595ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:45.036064Z","caller":"traceutil/trace.go:171","msg":"trace[2061218426] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"192.432642ms","start":"2026-04-02T08:45:44.843574Z","end":"2026-04-02T08:45:45.036007Z","steps":["trace[2061218426] 'process raft request' (duration: 192.115786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:45.036122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.280689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:45.036167Z","caller":"traceutil/trace.go:171","msg":"trace[969190246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"188.444992ms","start":"2026-04-02T08:45:44.847711Z","end":"2026-04-02T08:45:45.036156Z","steps":["trace[969190246] 'agreement among raft nodes before linearized reading' (duration: 188.271479ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.035969Z","caller":"traceutil/trace.go:171","msg":"trace[279392413] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"192.028974ms","start":"2026-04-02T08:45:44.843923Z","end":"2026-04-02T08:45:45.035952Z","steps":["trace[279392413] 'process raft request' (duration: 191.954353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:46.283443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:46.784204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:47.284959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276111712,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:47.323332Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.287690839s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T08:45:48.815428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000377488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T08:45:48.815524Z","caller":"traceutil/trace.go:171","msg":"trace[270028731] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000526741s","start":"2026-04-02T08:45:46.814975Z","end":"2026-04-02T08:45:48.815502Z","steps":["trace[270028731] 'agreement among raft nodes before linearized reading' (duration: 2.000406849s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:48.815604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:46.814957Z","time spent":"2.000628873s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 08:45:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T08:45:49.226395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902754361s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:45:49.226683Z","caller":"traceutil/trace.go:171","msg":"trace[2064026596] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"3.44350067s","start":"2026-04-02T08:45:45.783148Z","end":"2026-04-02T08:45:49.226649Z","steps":["trace[2064026596] 'read index received' (duration: 1.540386662s)","trace[2064026596] 'applied index is now lower than readState.Index' (duration: 1.903113028s)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:49.226838Z","caller":"traceutil/trace.go:171","msg":"trace[713437563] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"4.325096878s","start":"2026-04-02T08:45:44.901726Z","end":"2026-04-02T08:45:49.226822Z","steps":["trace[713437563] 'process raft request' (duration: 2.421794306s)","trace[713437563] 'compare' (duration: 1.902590468s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:49.226917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:44.901703Z","time spent":"4.325169189s","remote":"127.0.0.1:48640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:49.227331Z","caller":"traceutil/trace.go:171","msg":"trace[165946760] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"3.727351654s","start":"2026-04-02T08:45:45.499966Z","end":"2026-04-02T08:45:49.227318Z","steps":["trace[165946760] 'process raft request' (duration: 3.726567929s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:49.22741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:45.499944Z","time spent":"3.727418505s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:49.227665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.444495059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-02T08:45:49.227757Z","caller":"traceutil/trace.go:171","msg":"trace[681400099] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:645; }","duration":"3.444638752s","start":"2026-04-02T08:45:45.783094Z","end":"2026-04-02T08:45:49.227733Z","steps":["trace[681400099] 'agreement among raft nodes before linearized reading' (duration: 3.444399707s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:49.227816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:45.783069Z","time spent":"3.444730244s","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"warn","ts":"2026-04-02T08:45:49.228109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.345664769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T08:45:49.228158Z","caller":"traceutil/trace.go:171","msg":"trace[628189279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"2.34573786s","start":"2026-04-02T08:45:46.882393Z","end":"2026-04-02T08:45:49.228131Z","steps":["trace[628189279] 'agreement among raft nodes before linearized reading' (duration: 2.345635888s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:49.228191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:46.882368Z","time spent":"2.345814871s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:45:49.228377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.188263801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T08:45:49.228369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.068811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:45:49.228378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18718604s","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-02T08:45:49.22841Z","caller":"traceutil/trace.go:171","msg":"trace[119787161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"2.188335992s","start":"2026-04-02T08:45:47.040062Z","end":"2026-04-02T08:45:49.228398Z","steps":["trace[119787161] 'agreement among raft nodes before linearized reading' (duration: 2.188245291s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:49.228423Z","caller":"traceutil/trace.go:171","msg":"trace[1101059032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"412.160143ms","start":"2026-04-02T08:45:48.816246Z","end":"2026-04-02T08:45:49.228406Z","steps":["trace[1101059032] 'agreement among raft nodes before linearized reading' (duration: 412.073011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:49.228442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:47.040055Z","time spent":"2.188378813s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:45:49.228442Z","caller":"traceutil/trace.go:171","msg":"trace[1793348391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"2.187307932s","start":"2026-04-02T08:45:47.041118Z","end":"2026-04-02T08:45:49.228426Z","steps":["trace[1793348391] 'agreement among raft nodes before linearized reading' (duration: 2.187118317s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:49.228466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:48.816229Z","time spent":"412.228734ms","remote":"127.0.0.1:48400","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-02T08:45:49.228486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:47.041093Z","time spent":"2.187383703s","remote":"127.0.0.1:48670","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-02T08:45:49.228727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.188787891s","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-02T08:45:49.22885Z","caller":"traceutil/trace.go:171","msg":"trace[342961808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"2.188990775s","start":"2026-04-02T08:45:47.039831Z","end":"2026-04-02T08:45:49.228822Z","steps":["trace[342961808] 'agreement among raft nodes before linearized reading' (duration: 2.188499916s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:49.228925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:47.039812Z","time spent":"2.189100397s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T08:45:50.902392Z","caller":"traceutil/trace.go:171","msg":"trace[1123137544] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"730.717699ms","start":"2026-04-02T08:45:50.171651Z","end":"2026-04-02T08:45:50.902369Z","steps":["trace[1123137544] 'process raft request' (duration: 730.565316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:50.902544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:50.171634Z","time spent":"730.832761ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:51.239124Z","caller":"traceutil/trace.go:171","msg":"trace[1073939393] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"426.165807ms","start":"2026-04-02T08:45:50.812937Z","end":"2026-04-02T08:45:51.239103Z","steps":["trace[1073939393] 'read index received' (duration: 89.201937ms)","trace[1073939393] 'applied index is now lower than readState.Index' (duration: 336.963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:51.239261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.311889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:51.239293Z","caller":"traceutil/trace.go:171","msg":"trace[852121736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"426.38605ms","start":"2026-04-02T08:45:50.812896Z","end":"2026-04-02T08:45:51.239282Z","steps":["trace[852121736] 'agreement among raft nodes before linearized reading' (duration: 426.296409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:51.239325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:50.81288Z","time spent":"426.437832ms","remote":"127.0.0.1:48394","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-02T08:45:51.239251Z","caller":"traceutil/trace.go:171","msg":"trace[1681200102] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.060576482s","start":"2026-04-02T08:45:50.178634Z","end":"2026-04-02T08:45:51.239211Z","steps":["trace[1681200102] 'process raft request' (duration: 1.060308497s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:51.23956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:50.178627Z","time spent":"1.060844728s","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17224,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:51.780488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.79462ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T08:45:51.780639Z","caller":"traceutil/trace.go:171","msg":"trace[1240025967] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"509.125454ms","start":"2026-04-02T08:45:51.27149Z","end":"2026-04-02T08:45:51.780615Z","steps":["trace[1240025967] 'read index received' (duration: 176.13361ms)","trace[1240025967] 'applied index is now lower than readState.Index' (duration: 332.989854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:51.780938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.42863ms","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-02T08:45:51.780983Z","caller":"traceutil/trace.go:171","msg":"trace[1761348816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"509.525312ms","start":"2026-04-02T08:45:51.271445Z","end":"2026-04-02T08:45:51.78097Z","steps":["trace[1761348816] 'agreement among raft nodes before linearized reading' (duration: 509.293588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:51.781028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:51.271424Z","time spent":"509.594543ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:45:51.78112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.521312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-02T08:45:51.781132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.492781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T08:45:51.781205Z","caller":"traceutil/trace.go:171","msg":"trace[287095258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"509.667954ms","start":"2026-04-02T08:45:51.271516Z","end":"2026-04-02T08:45:51.781184Z","steps":["trace[287095258] 'agreement among raft nodes before linearized reading' (duration: 509.356398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:51.781255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:51.271497Z","time spent":"509.749976ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T08:45:51.781296Z","caller":"traceutil/trace.go:171","msg":"trace[1037945419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"509.659194ms","start":"2026-04-02T08:45:51.271565Z","end":"2026-04-02T08:45:51.781224Z","steps":["trace[1037945419] 'agreement among raft nodes before linearized reading' (duration: 509.289287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:51.781358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:51.271557Z","time spent":"509.787577ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:45:51.78182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.249115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T08:45:51.782136Z","caller":"traceutil/trace.go:171","msg":"trace[1213639615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"510.605791ms","start":"2026-04-02T08:45:51.271516Z","end":"2026-04-02T08:45:51.782122Z","steps":["trace[1213639615] 'agreement among raft nodes before linearized reading' (duration: 509.317667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:51.782175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:51.271489Z","time spent":"510.674643ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T08:45:51.893497Z","caller":"traceutil/trace.go:171","msg":"trace[2031096392] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"105.789053ms","start":"2026-04-02T08:45:51.787684Z","end":"2026-04-02T08:45:51.893474Z","steps":["trace[2031096392] 'process raft request' (duration: 105.63935ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:52.086736Z","caller":"traceutil/trace.go:171","msg":"trace[139768] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"298.960157ms","start":"2026-04-02T08:45:51.787739Z","end":"2026-04-02T08:45:52.086699Z","steps":["trace[139768] 'process raft request' (duration: 226.763002ms)","trace[139768] 'compare' (duration: 71.92223ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:52.087169Z","caller":"traceutil/trace.go:171","msg":"trace[255391634] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"299.213601ms","start":"2026-04-02T08:45:51.787923Z","end":"2026-04-02T08:45:52.087136Z","steps":["trace[255391634] 'process raft request' (duration: 298.719662ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:52.087431Z","caller":"traceutil/trace.go:171","msg":"trace[1029875161] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:687; }","duration":"273.899461ms","start":"2026-04-02T08:45:51.813519Z","end":"2026-04-02T08:45:52.087418Z","steps":["trace[1029875161] 'read index received' (duration: 79.809089ms)","trace[1029875161] 'applied index is now lower than readState.Index' (duration: 194.089212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:52.087556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.032633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:52.0876Z","caller":"traceutil/trace.go:171","msg":"trace[2059931643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"274.151815ms","start":"2026-04-02T08:45:51.813437Z","end":"2026-04-02T08:45:52.087588Z","steps":["trace[2059931643] 'agreement among raft nodes before linearized reading' (duration: 274.034573ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:52.087834Z","caller":"traceutil/trace.go:171","msg":"trace[392819231] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"299.939695ms","start":"2026-04-02T08:45:51.787831Z","end":"2026-04-02T08:45:52.087771Z","steps":["trace[392819231] 'process raft request' (duration: 299.075259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:52.08812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:51.787758Z","time spent":"300.134369ms","remote":"127.0.0.1:48670","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-02T08:45:55.394588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.965018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gx5cp\" ","response":"range_response_count:1 size:17516"} {"level":"info","ts":"2026-04-02T08:45:55.394688Z","caller":"traceutil/trace.go:171","msg":"trace[822810787] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gx5cp; range_end:; response_count:1; response_revision:673; }","duration":"194.10529ms","start":"2026-04-02T08:45:55.20056Z","end":"2026-04-02T08:45:55.394665Z","steps":["trace[822810787] 'range keys from in-memory index tree' (duration: 193.753504ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.008307Z","caller":"traceutil/trace.go:171","msg":"trace[993408410] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"126.514552ms","start":"2026-04-02T08:45:55.881752Z","end":"2026-04-02T08:45:56.008267Z","steps":["trace[993408410] 'process raft request' (duration: 65.869794ms)","trace[993408410] 'compare' (duration: 60.302642ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:56.26814Z","caller":"traceutil/trace.go:171","msg":"trace[2089610531] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"112.010551ms","start":"2026-04-02T08:45:56.156107Z","end":"2026-04-02T08:45:56.268118Z","steps":["trace[2089610531] 'process raft request' (duration: 111.893968ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.268271Z","caller":"traceutil/trace.go:171","msg":"trace[1211260060] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"112.235537ms","start":"2026-04-02T08:45:56.155909Z","end":"2026-04-02T08:45:56.268144Z","steps":["trace[1211260060] 'process raft request' (duration: 100.268934ms)","trace[1211260060] 'compare' (duration: 11.705617ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:56.268617Z","caller":"traceutil/trace.go:171","msg":"trace[304626131] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"110.29887ms","start":"2026-04-02T08:45:56.158284Z","end":"2026-04-02T08:45:56.268582Z","steps":["trace[304626131] 'process raft request' (duration: 109.78584ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.413095Z","caller":"traceutil/trace.go:171","msg":"trace[1018663396] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"104.613914ms","start":"2026-04-02T08:45:56.308453Z","end":"2026-04-02T08:45:56.413067Z","steps":["trace[1018663396] 'process raft request' (duration: 103.84077ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.475418Z","caller":"traceutil/trace.go:171","msg":"trace[1298353638] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"161.081888ms","start":"2026-04-02T08:45:56.314313Z","end":"2026-04-02T08:45:56.475395Z","steps":["trace[1298353638] 'process raft request' (duration: 160.936616ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.612008Z","caller":"traceutil/trace.go:171","msg":"trace[1374940704] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"158.646894ms","start":"2026-04-02T08:45:56.453336Z","end":"2026-04-02T08:45:56.611983Z","steps":["trace[1374940704] 'process raft request' (duration: 158.529981ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.646571Z","caller":"traceutil/trace.go:171","msg":"trace[1934243448] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"162.851943ms","start":"2026-04-02T08:45:56.483698Z","end":"2026-04-02T08:45:56.646549Z","steps":["trace[1934243448] 'process raft request' (duration: 162.585117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.008146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.572815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:45:57.008301Z","caller":"traceutil/trace.go:171","msg":"trace[1214706383] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"192.862567ms","start":"2026-04-02T08:45:56.815416Z","end":"2026-04-02T08:45:57.008278Z","steps":["trace[1214706383] 'read index received' (duration: 89.19025ms)","trace[1214706383] 'applied index is now lower than readState.Index' (duration: 103.670657ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.00838Z","caller":"traceutil/trace.go:171","msg":"trace[15134921] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"221.953195ms","start":"2026-04-02T08:45:56.786361Z","end":"2026-04-02T08:45:57.008314Z","steps":["trace[15134921] 'process raft request' (duration: 118.104445ms)","trace[15134921] 'compare' (duration: 103.371142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:57.008565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.145971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:57.008638Z","caller":"traceutil/trace.go:171","msg":"trace[1927341018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"193.270524ms","start":"2026-04-02T08:45:56.815355Z","end":"2026-04-02T08:45:57.008626Z","steps":["trace[1927341018] 'agreement among raft nodes before linearized reading' (duration: 193.06003ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.013383Z","caller":"traceutil/trace.go:171","msg":"trace[61081881] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"156.034114ms","start":"2026-04-02T08:45:56.857327Z","end":"2026-04-02T08:45:57.013361Z","steps":["trace[61081881] 'process raft request' (duration: 155.737038ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.166876Z","caller":"traceutil/trace.go:171","msg":"trace[292460643] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"136.944999ms","start":"2026-04-02T08:45:57.029906Z","end":"2026-04-02T08:45:57.166851Z","steps":["trace[292460643] 'process raft request' (duration: 136.755915ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.328344Z","caller":"traceutil/trace.go:171","msg":"trace[1643744268] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"276.968644ms","start":"2026-04-02T08:45:57.051316Z","end":"2026-04-02T08:45:57.328285Z","steps":["trace[1643744268] 'process raft request' (duration: 201.316763ms)","trace[1643744268] 'compare' (duration: 75.342306ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.328432Z","caller":"traceutil/trace.go:171","msg":"trace[1326735880] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"102.862862ms","start":"2026-04-02T08:45:57.225559Z","end":"2026-04-02T08:45:57.328422Z","steps":["trace[1326735880] 'read index received' (duration: 27.094119ms)","trace[1326735880] 'applied index is now lower than readState.Index' (duration: 75.767703ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.328341Z","caller":"traceutil/trace.go:171","msg":"trace[143758121] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"141.170386ms","start":"2026-04-02T08:45:57.187152Z","end":"2026-04-02T08:45:57.328323Z","steps":["trace[143758121] 'process raft request' (duration: 141.024153ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.328507Z","caller":"traceutil/trace.go:171","msg":"trace[819132766] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"102.537566ms","start":"2026-04-02T08:45:57.225962Z","end":"2026-04-02T08:45:57.3285Z","steps":["trace[819132766] 'process raft request' (duration: 102.309442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.328883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.310671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gx5cp\" ","response":"range_response_count:1 size:17782"} {"level":"info","ts":"2026-04-02T08:45:57.328929Z","caller":"traceutil/trace.go:171","msg":"trace[2085727247] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gx5cp; range_end:; response_count:1; response_revision:716; }","duration":"103.431323ms","start":"2026-04-02T08:45:57.225487Z","end":"2026-04-02T08:45:57.328918Z","steps":["trace[2085727247] 'agreement among raft nodes before linearized reading' (duration: 103.216709ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.77187Z","caller":"traceutil/trace.go:171","msg":"trace[1689213739] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"435.213365ms","start":"2026-04-02T08:45:57.336635Z","end":"2026-04-02T08:45:57.771849Z","steps":["trace[1689213739] 'process raft request' (duration: 435.101523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.771997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.336611Z","time spent":"435.320896ms","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:58.019671Z","caller":"traceutil/trace.go:171","msg":"trace[1365913193] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"568.80837ms","start":"2026-04-02T08:45:57.450823Z","end":"2026-04-02T08:45:58.019631Z","steps":["trace[1365913193] 'read index received' (duration: 320.889281ms)","trace[1365913193] 'applied index is now lower than readState.Index' (duration: 247.918079ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:58.019777Z","caller":"traceutil/trace.go:171","msg":"trace[1173972720] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"672.790033ms","start":"2026-04-02T08:45:57.346977Z","end":"2026-04-02T08:45:58.019767Z","steps":["trace[1173972720] 'process raft request' (duration: 672.455557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:58.019885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.346927Z","time spent":"672.878985ms","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18044,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:58.019981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.131257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:58.020048Z","caller":"traceutil/trace.go:171","msg":"trace[2056084109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:718; }","duration":"569.265289ms","start":"2026-04-02T08:45:57.450766Z","end":"2026-04-02T08:45:58.020031Z","steps":["trace[2056084109] 'agreement among raft nodes before linearized reading' (duration: 569.103076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:58.020084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.450751Z","time spent":"569.32765ms","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-02T08:45:58.051826Z","caller":"traceutil/trace.go:171","msg":"trace[931850741] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"274.476443ms","start":"2026-04-02T08:45:57.777326Z","end":"2026-04-02T08:45:58.051802Z","steps":["trace[931850741] 'process raft request' (duration: 274.238219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:58.051988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.414736ms","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-02T08:45:58.052109Z","caller":"traceutil/trace.go:171","msg":"trace[927975207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:719; }","duration":"390.572288ms","start":"2026-04-02T08:45:57.661499Z","end":"2026-04-02T08:45:58.052071Z","steps":["trace[927975207] 'agreement among raft nodes before linearized reading' (duration: 390.277273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:58.052078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.876977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-04-02T08:45:58.052167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.66148Z","time spent":"390.676971ms","remote":"127.0.0.1:48670","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-02T08:45:58.052196Z","caller":"traceutil/trace.go:171","msg":"trace[1150696444] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"402.001169ms","start":"2026-04-02T08:45:57.65017Z","end":"2026-04-02T08:45:58.052171Z","steps":["trace[1150696444] 'agreement among raft nodes before linearized reading' (duration: 401.752704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:58.052318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.650148Z","time spent":"402.0906ms","remote":"127.0.0.1:48446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-02T08:45:58.052358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.727227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-02T08:45:58.052404Z","caller":"traceutil/trace.go:171","msg":"trace[835559139] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"200.835409ms","start":"2026-04-02T08:45:57.851555Z","end":"2026-04-02T08:45:58.052391Z","steps":["trace[835559139] 'agreement among raft nodes before linearized reading' (duration: 200.597625ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:58.472876Z","caller":"traceutil/trace.go:171","msg":"trace[1502960997] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"120.163098ms","start":"2026-04-02T08:45:58.352687Z","end":"2026-04-02T08:45:58.47285Z","steps":["trace[1502960997] 'process raft request' (duration: 120.007914ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:58.606234Z","caller":"traceutil/trace.go:171","msg":"trace[1592350606] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"253.196082ms","start":"2026-04-02T08:45:58.353003Z","end":"2026-04-02T08:45:58.606199Z","steps":["trace[1592350606] 'process raft request' (duration: 223.648672ms)","trace[1592350606] 'compare' (duration: 29.267185ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:58.606366Z","caller":"traceutil/trace.go:171","msg":"trace[671500205] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"150.564165ms","start":"2026-04-02T08:45:58.455776Z","end":"2026-04-02T08:45:58.60634Z","steps":["trace[671500205] 'process raft request' (duration: 150.330361ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:59.951423Z","caller":"traceutil/trace.go:171","msg":"trace[43783800] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"390.243771ms","start":"2026-04-02T08:45:59.561159Z","end":"2026-04-02T08:45:59.951403Z","steps":["trace[43783800] 'process raft request' (duration: 390.128409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:59.951659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:59.561124Z","time spent":"390.474326ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:59.975616Z","caller":"traceutil/trace.go:171","msg":"trace[1956467642] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"351.451036ms","start":"2026-04-02T08:45:59.62413Z","end":"2026-04-02T08:45:59.975581Z","steps":["trace[1956467642] 'process raft request' (duration: 351.136231ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:59.975732Z","caller":"traceutil/trace.go:171","msg":"trace[1915766493] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:768; }","duration":"262.251072ms","start":"2026-04-02T08:45:59.713425Z","end":"2026-04-02T08:45:59.975676Z","steps":["trace[1915766493] 'read index received' (duration: 237.978ms)","trace[1915766493] 'applied index is now lower than readState.Index' (duration: 24.271702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:59.975767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:59.624103Z","time spent":"351.588998ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4735,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:59.975839Z","caller":"traceutil/trace.go:171","msg":"trace[1776351454] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"282.641224ms","start":"2026-04-02T08:45:59.693179Z","end":"2026-04-02T08:45:59.97582Z","steps":["trace[1776351454] 'process raft request' (duration: 282.43204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:59.975907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.475397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:59.975962Z","caller":"traceutil/trace.go:171","msg":"trace[245734959] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:741; }","duration":"262.577068ms","start":"2026-04-02T08:45:59.713371Z","end":"2026-04-02T08:45:59.975948Z","steps":["trace[245734959] 'agreement among raft nodes before linearized reading' (duration: 262.440436ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:59.97602Z","caller":"traceutil/trace.go:171","msg":"trace[292008331] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"289.393826ms","start":"2026-04-02T08:45:59.686614Z","end":"2026-04-02T08:45:59.976008Z","steps":["trace[292008331] 'process raft request' (duration: 288.874287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:59.976176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.419458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T08:45:59.976225Z","caller":"traceutil/trace.go:171","msg":"trace[1169474450] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:741; }","duration":"155.51206ms","start":"2026-04-02T08:45:59.820699Z","end":"2026-04-02T08:45:59.976211Z","steps":["trace[1169474450] 'agreement among raft nodes before linearized reading' (duration: 155.437008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:59.976406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.286828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-02T08:45:59.976445Z","caller":"traceutil/trace.go:171","msg":"trace[1915268730] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:741; }","duration":"126.327689ms","start":"2026-04-02T08:45:59.850105Z","end":"2026-04-02T08:45:59.976433Z","steps":["trace[1915268730] 'agreement among raft nodes before linearized reading' (duration: 126.154576ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:00.394454Z","caller":"traceutil/trace.go:171","msg":"trace[612691414] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"122.209481ms","start":"2026-04-02T08:46:00.272218Z","end":"2026-04-02T08:46:00.394428Z","steps":["trace[612691414] 'read index received' (duration: 13.24911ms)","trace[612691414] 'applied index is now lower than readState.Index' (duration: 108.959381ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:00.394658Z","caller":"traceutil/trace.go:171","msg":"trace[1144248405] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"125.504342ms","start":"2026-04-02T08:46:00.269139Z","end":"2026-04-02T08:46:00.394643Z","steps":["trace[1144248405] 'process raft request' (duration: 124.542244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:00.39485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.612569ms","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-02T08:46:00.395057Z","caller":"traceutil/trace.go:171","msg":"trace[64619713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"122.861974ms","start":"2026-04-02T08:46:00.272185Z","end":"2026-04-02T08:46:00.395047Z","steps":["trace[64619713] 'agreement among raft nodes before linearized reading' (duration: 122.513038ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:00.621839Z","caller":"traceutil/trace.go:171","msg":"trace[1399918318] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"121.237585ms","start":"2026-04-02T08:46:00.500567Z","end":"2026-04-02T08:46:00.621805Z","steps":["trace[1399918318] 'read index received' (duration: 49.006117ms)","trace[1399918318] 'applied index is now lower than readState.Index' (duration: 72.230048ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:00.621979Z","caller":"traceutil/trace.go:171","msg":"trace[1878280688] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"141.013343ms","start":"2026-04-02T08:46:00.480904Z","end":"2026-04-02T08:46:00.621918Z","steps":["trace[1878280688] 'process raft request' (duration: 68.753625ms)","trace[1878280688] 'compare' (duration: 71.960733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:00.622338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.746584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-02T08:46:00.622454Z","caller":"traceutil/trace.go:171","msg":"trace[1183226244] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:749; }","duration":"121.925467ms","start":"2026-04-02T08:46:00.500491Z","end":"2026-04-02T08:46:00.622417Z","steps":["trace[1183226244] 'agreement among raft nodes before linearized reading' (duration: 121.477429ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:00.834524Z","caller":"traceutil/trace.go:171","msg":"trace[1119928433] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"205.980179ms","start":"2026-04-02T08:46:00.628513Z","end":"2026-04-02T08:46:00.834494Z","steps":["trace[1119928433] 'process raft request' (duration: 197.112878ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:01.738829Z","caller":"traceutil/trace.go:171","msg":"trace[1435265420] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"113.33641ms","start":"2026-04-02T08:46:01.625435Z","end":"2026-04-02T08:46:01.738771Z","steps":["trace[1435265420] 'process raft request' (duration: 113.068405ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:01.780004Z","caller":"traceutil/trace.go:171","msg":"trace[79860133] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"131.338256ms","start":"2026-04-02T08:46:01.648642Z","end":"2026-04-02T08:46:01.779981Z","steps":["trace[79860133] 'read index received' (duration: 89.776717ms)","trace[79860133] 'applied index is now lower than readState.Index' (duration: 41.560549ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:01.780138Z","caller":"traceutil/trace.go:171","msg":"trace[1796069845] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"151.124471ms","start":"2026-04-02T08:46:01.629001Z","end":"2026-04-02T08:46:01.780126Z","steps":["trace[1796069845] 'process raft request' (duration: 150.841356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:01.780455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.785134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:46:01.780456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.647841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:01.780503Z","caller":"traceutil/trace.go:171","msg":"trace[643109887] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:756; }","duration":"131.875045ms","start":"2026-04-02T08:46:01.648617Z","end":"2026-04-02T08:46:01.780492Z","steps":["trace[643109887] 'agreement among raft nodes before linearized reading' (duration: 131.764293ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:01.780616Z","caller":"traceutil/trace.go:171","msg":"trace[1215002046] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:756; }","duration":"131.795603ms","start":"2026-04-02T08:46:01.648735Z","end":"2026-04-02T08:46:01.78053Z","steps":["trace[1215002046] 'agreement among raft nodes before linearized reading' (duration: 131.61893ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:01.923948Z","caller":"traceutil/trace.go:171","msg":"trace[2059875477] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"107.047157ms","start":"2026-04-02T08:46:01.81688Z","end":"2026-04-02T08:46:01.923927Z","steps":["trace[2059875477] 'read index received' (duration: 106.944405ms)","trace[2059875477] 'applied index is now lower than readState.Index' (duration: 101.962µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:01.924197Z","caller":"traceutil/trace.go:171","msg":"trace[1299839868] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"134.315598ms","start":"2026-04-02T08:46:01.78985Z","end":"2026-04-02T08:46:01.924166Z","steps":["trace[1299839868] 'process raft request' (duration: 133.957781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:01.924214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.316303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:01.92472Z","caller":"traceutil/trace.go:171","msg":"trace[1439992208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"107.834962ms","start":"2026-04-02T08:46:01.816843Z","end":"2026-04-02T08:46:01.924678Z","steps":["trace[1439992208] 'agreement among raft nodes before linearized reading' (duration: 107.306453ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:02.087784Z","caller":"traceutil/trace.go:171","msg":"trace[1778787349] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"163.746471ms","start":"2026-04-02T08:46:01.924003Z","end":"2026-04-02T08:46:02.087749Z","steps":["trace[1778787349] 'read index received' (duration: 113.399809ms)","trace[1778787349] 'applied index is now lower than readState.Index' (duration: 50.340512ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:02.087878Z","caller":"traceutil/trace.go:171","msg":"trace[2105715833] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"198.537047ms","start":"2026-04-02T08:46:01.889305Z","end":"2026-04-02T08:46:02.087842Z","steps":["trace[2105715833] 'process raft request' (duration: 148.156585ms)","trace[2105715833] 'compare' (duration: 50.134958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:02.088199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.569215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:46:02.08823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.038496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:02.088322Z","caller":"traceutil/trace.go:171","msg":"trace[714947796] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:758; }","duration":"270.745828ms","start":"2026-04-02T08:46:01.817555Z","end":"2026-04-02T08:46:02.088301Z","steps":["trace[714947796] 'agreement among raft nodes before linearized reading' (duration: 270.358871ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:02.08834Z","caller":"traceutil/trace.go:171","msg":"trace[446010380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:758; }","duration":"149.220449ms","start":"2026-04-02T08:46:01.939096Z","end":"2026-04-02T08:46:02.088317Z","steps":["trace[446010380] 'agreement among raft nodes before linearized reading' (duration: 148.996445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:02.088336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.283763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:02.08842Z","caller":"traceutil/trace.go:171","msg":"trace[1252344087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:758; }","duration":"148.409584ms","start":"2026-04-02T08:46:01.939991Z","end":"2026-04-02T08:46:02.0884Z","steps":["trace[1252344087] 'agreement among raft nodes before linearized reading' (duration: 148.302922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:02.088511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.452913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:02.08854Z","caller":"traceutil/trace.go:171","msg":"trace[516069507] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:758; }","duration":"149.508714ms","start":"2026-04-02T08:46:01.939024Z","end":"2026-04-02T08:46:02.088532Z","steps":["trace[516069507] 'agreement among raft nodes before linearized reading' (duration: 149.458873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:02.088801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.982147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:02.088927Z","caller":"traceutil/trace.go:171","msg":"trace[219579081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"163.109889ms","start":"2026-04-02T08:46:01.925804Z","end":"2026-04-02T08:46:02.088914Z","steps":["trace[219579081] 'agreement among raft nodes before linearized reading' (duration: 162.243084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:02.088921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.323248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:02.088977Z","caller":"traceutil/trace.go:171","msg":"trace[1857561363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:758; }","duration":"271.414609ms","start":"2026-04-02T08:46:01.817552Z","end":"2026-04-02T08:46:02.088967Z","steps":["trace[1857561363] 'agreement among raft nodes before linearized reading' (duration: 270.491583ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:04.02758Z","caller":"traceutil/trace.go:171","msg":"trace[687651068] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"135.469192ms","start":"2026-04-02T08:46:03.89208Z","end":"2026-04-02T08:46:04.027549Z","steps":["trace[687651068] 'process raft request' (duration: 135.260588ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:04.056243Z","caller":"traceutil/trace.go:171","msg":"trace[1475204183] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"124.079338ms","start":"2026-04-02T08:46:03.932113Z","end":"2026-04-02T08:46:04.056193Z","steps":["trace[1475204183] 'process raft request' (duration: 123.935555ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:04.517495Z","caller":"traceutil/trace.go:171","msg":"trace[468736861] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"211.032912ms","start":"2026-04-02T08:46:04.306431Z","end":"2026-04-02T08:46:04.517464Z","steps":["trace[468736861] 'read index received' (duration: 193.815417ms)","trace[468736861] 'applied index is now lower than readState.Index' (duration: 17.216025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:04.517657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.206675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:04.517699Z","caller":"traceutil/trace.go:171","msg":"trace[985157301] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:770; }","duration":"211.324727ms","start":"2026-04-02T08:46:04.306366Z","end":"2026-04-02T08:46:04.517691Z","steps":["trace[985157301] 'agreement among raft nodes before linearized reading' (duration: 211.232896ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:06.24772Z","caller":"traceutil/trace.go:171","msg":"trace[1098526340] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"102.075903ms","start":"2026-04-02T08:46:06.145622Z","end":"2026-04-02T08:46:06.247698Z","steps":["trace[1098526340] 'process raft request' (duration: 101.743597ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:10.595196Z","caller":"traceutil/trace.go:171","msg":"trace[1097192232] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"324.781434ms","start":"2026-04-02T08:46:10.270395Z","end":"2026-04-02T08:46:10.595177Z","steps":["trace[1097192232] 'read index received' (duration: 323.9456ms)","trace[1097192232] 'applied index is now lower than readState.Index' (duration: 835.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:10.595402Z","caller":"traceutil/trace.go:171","msg":"trace[1202973915] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"378.769454ms","start":"2026-04-02T08:46:10.216459Z","end":"2026-04-02T08:46:10.595228Z","steps":["trace[1202973915] 'process raft request' (duration: 378.554321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:10.595455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.654096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-04-02T08:46:10.595678Z","caller":"traceutil/trace.go:171","msg":"trace[106681140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"323.92222ms","start":"2026-04-02T08:46:10.27174Z","end":"2026-04-02T08:46:10.595662Z","steps":["trace[106681140] 'agreement among raft nodes before linearized reading' (duration: 323.556363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:10.595471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.043989ms","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-02T08:46:10.595781Z","caller":"traceutil/trace.go:171","msg":"trace[109133439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"325.406724ms","start":"2026-04-02T08:46:10.270362Z","end":"2026-04-02T08:46:10.595768Z","steps":["trace[109133439] 'agreement among raft nodes before linearized reading' (duration: 324.905976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:10.595755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:10.271722Z","time spent":"324.019291ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:46:10.595821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:10.270347Z","time spent":"325.462826ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T08:46:10.596042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:10.216436Z","time spent":"379.100469ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:46:15.122915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276112155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:15.62405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276112155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:16.125096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276112155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:16.626158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276112155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:16.815056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00035796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T08:46:16.81517Z","caller":"traceutil/trace.go:171","msg":"trace[151651686] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000491802s","start":"2026-04-02T08:46:14.814652Z","end":"2026-04-02T08:46:16.815144Z","steps":["trace[151651686] 'agreement among raft nodes before linearized reading' (duration: 2.000351779s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:16.815378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.814595Z","time spent":"2.000764516s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 08:46:16 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T08:46:16.898085Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.563722133s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:46:16.898614Z","caller":"traceutil/trace.go:171","msg":"trace[1666606599] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"2.276322944s","start":"2026-04-02T08:46:14.622263Z","end":"2026-04-02T08:46:16.898586Z","steps":["trace[1666606599] 'read index received' (duration: 2.275921197s)","trace[1666606599] 'applied index is now lower than readState.Index' (duration: 400.587µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:16.898695Z","caller":"traceutil/trace.go:171","msg":"trace[1422101981] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"2.564449345s","start":"2026-04-02T08:46:14.334228Z","end":"2026-04-02T08:46:16.898677Z","steps":["trace[1422101981] 'process raft request' (duration: 2.564050869s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:16.89886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.334203Z","time spent":"2.564526517s","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:46:16.898925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.276649309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-02T08:46:16.898924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.273363993s","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-02T08:46:16.898955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928476511s","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-02T08:46:16.898992Z","caller":"traceutil/trace.go:171","msg":"trace[672885167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"2.276746911s","start":"2026-04-02T08:46:14.622223Z","end":"2026-04-02T08:46:16.89897Z","steps":["trace[672885167] 'agreement among raft nodes before linearized reading' (duration: 2.276490307s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:16.898999Z","caller":"traceutil/trace.go:171","msg":"trace[580835470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"1.928572882s","start":"2026-04-02T08:46:14.970414Z","end":"2026-04-02T08:46:16.898987Z","steps":["trace[580835470] 'agreement among raft nodes before linearized reading' (duration: 1.928398239s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:16.899045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113925416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-04-02T08:46:16.899052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.970398Z","time spent":"1.928645523s","remote":"127.0.0.1:48670","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-02T08:46:16.899117Z","caller":"traceutil/trace.go:171","msg":"trace[1734141754] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:832; }","duration":"1.113959887s","start":"2026-04-02T08:46:15.785109Z","end":"2026-04-02T08:46:16.899069Z","steps":["trace[1734141754] 'agreement among raft nodes before linearized reading' (duration: 1.113837525s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:16.89906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.622203Z","time spent":"2.276837842s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:46:16.899146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:15.785063Z","time spent":"1.114074799s","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.6\" "} {"level":"warn","ts":"2026-04-02T08:46:16.89915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.203122505s","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-02T08:46:16.899Z","caller":"traceutil/trace.go:171","msg":"trace[1181665147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:832; }","duration":"2.273470594s","start":"2026-04-02T08:46:14.625512Z","end":"2026-04-02T08:46:16.898983Z","steps":["trace[1181665147] 'agreement among raft nodes before linearized reading' (duration: 2.273260221s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:16.899191Z","caller":"traceutil/trace.go:171","msg":"trace[285198826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:832; }","duration":"2.203193776s","start":"2026-04-02T08:46:14.695985Z","end":"2026-04-02T08:46:16.899178Z","steps":["trace[285198826] 'agreement among raft nodes before linearized reading' (duration: 2.203044684s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:16.899233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.695966Z","time spent":"2.203256487s","remote":"127.0.0.1:48670","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-02T08:46:16.899224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.625498Z","time spent":"2.273709559s","remote":"127.0.0.1:48670","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-02T08:46:16.899224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124283002s","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-02T08:46:16.899312Z","caller":"traceutil/trace.go:171","msg":"trace[1745828625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"2.124400064s","start":"2026-04-02T08:46:14.774883Z","end":"2026-04-02T08:46:16.899283Z","steps":["trace[1745828625] 'agreement among raft nodes before linearized reading' (duration: 2.124074239s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:16.899364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:14.77487Z","time spent":"2.124480385s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:48:03.780989Z","caller":"traceutil/trace.go:171","msg":"trace[1740206763] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"131.585683ms","start":"2026-04-02T08:48:03.649382Z","end":"2026-04-02T08:48:03.780968Z","steps":["trace[1740206763] 'process raft request' (duration: 131.396601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:04.291298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807345276113375,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T08:48:04.39245Z","caller":"traceutil/trace.go:171","msg":"trace[136692174] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"741.536725ms","start":"2026-04-02T08:48:03.650886Z","end":"2026-04-02T08:48:04.392422Z","steps":["trace[136692174] 'process raft request' (duration: 734.92941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:04.392597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:03.650866Z","time spent":"741.662007ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:48:04.393414Z","caller":"traceutil/trace.go:171","msg":"trace[1650866191] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1406; }","duration":"602.526105ms","start":"2026-04-02T08:48:03.79086Z","end":"2026-04-02T08:48:04.393386Z","steps":["trace[1650866191] 'read index received' (duration: 594.966688ms)","trace[1650866191] 'applied index is now lower than readState.Index' (duration: 7.558527ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:04.393758Z","caller":"traceutil/trace.go:171","msg":"trace[1447545338] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"397.075975ms","start":"2026-04-02T08:48:03.996601Z","end":"2026-04-02T08:48:04.393677Z","steps":["trace[1447545338] 'process raft request' (duration: 396.646169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:04.39397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:03.996568Z","time spent":"397.283599ms","remote":"127.0.0.1:48670","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-02T08:48:04.393996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.544706ms","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-02T08:48:04.394056Z","caller":"traceutil/trace.go:171","msg":"trace[1769138506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"599.659798ms","start":"2026-04-02T08:48:03.794377Z","end":"2026-04-02T08:48:04.394036Z","steps":["trace[1769138506] 'agreement among raft nodes before linearized reading' (duration: 599.485896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:04.394112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:03.794353Z","time spent":"599.747459ms","remote":"127.0.0.1:48670","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-02T08:48:04.394326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.527802ms","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-02T08:48:04.394366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.484429ms","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-02T08:48:04.39439Z","caller":"traceutil/trace.go:171","msg":"trace[1089521097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"601.609143ms","start":"2026-04-02T08:48:03.792763Z","end":"2026-04-02T08:48:04.394372Z","steps":["trace[1089521097] 'agreement among raft nodes before linearized reading' (duration: 601.069386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:04.394664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:03.792754Z","time spent":"601.895618ms","remote":"127.0.0.1:48670","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-02T08:48:04.394552Z","caller":"traceutil/trace.go:171","msg":"trace[955089846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"603.764312ms","start":"2026-04-02T08:48:03.790769Z","end":"2026-04-02T08:48:04.394534Z","steps":["trace[955089846] 'agreement among raft nodes before linearized reading' (duration: 602.879751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:04.394827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:03.790755Z","time spent":"604.012595ms","remote":"127.0.0.1:48670","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-02T08:48:11.93868Z","caller":"traceutil/trace.go:171","msg":"trace[668745794] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"110.451089ms","start":"2026-04-02T08:48:11.8282Z","end":"2026-04-02T08:48:11.938651Z","steps":["trace[668745794] 'process raft request' (duration: 110.266107ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:14.8227Z","caller":"traceutil/trace.go:171","msg":"trace[280950140] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"868.319791ms","start":"2026-04-02T08:48:13.954362Z","end":"2026-04-02T08:48:14.822681Z","steps":["trace[280950140] 'process raft request' (duration: 868.19897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:14.822868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:13.954344Z","time spent":"868.436673ms","remote":"127.0.0.1:48670","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-02T08:48:14.901886Z","caller":"traceutil/trace.go:171","msg":"trace[1527774131] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1446; }","duration":"467.913403ms","start":"2026-04-02T08:48:14.433948Z","end":"2026-04-02T08:48:14.901862Z","steps":["trace[1527774131] 'read index received' (duration: 388.645346ms)","trace[1527774131] 'applied index is now lower than readState.Index' (duration: 79.267027ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:14.902029Z","caller":"traceutil/trace.go:171","msg":"trace[819246607] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"471.643771ms","start":"2026-04-02T08:48:14.430372Z","end":"2026-04-02T08:48:14.902016Z","steps":["trace[819246607] 'process raft request' (duration: 430.082718ms)","trace[819246607] 'compare' (duration: 41.296109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:48:14.902295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.997166ms","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-02T08:48:14.902354Z","caller":"traceutil/trace.go:171","msg":"trace[1806580247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"451.082977ms","start":"2026-04-02T08:48:14.451257Z","end":"2026-04-02T08:48:14.90234Z","steps":["trace[1806580247] 'agreement among raft nodes before linearized reading' (duration: 450.922975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:14.902339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.369718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-02T08:48:14.902389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:14.451247Z","time spent":"451.131957ms","remote":"127.0.0.1:48670","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-02T08:48:14.902413Z","caller":"traceutil/trace.go:171","msg":"trace[808496030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1388; }","duration":"468.500329ms","start":"2026-04-02T08:48:14.433898Z","end":"2026-04-02T08:48:14.902398Z","steps":["trace[808496030] 'agreement among raft nodes before linearized reading' (duration: 468.272607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:14.902408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.411892ms","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-02T08:48:14.902449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:14.433882Z","time spent":"468.5607ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T08:48:14.902465Z","caller":"traceutil/trace.go:171","msg":"trace[389758035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1388; }","duration":"455.462473ms","start":"2026-04-02T08:48:14.446986Z","end":"2026-04-02T08:48:14.902449Z","steps":["trace[389758035] 'agreement among raft nodes before linearized reading' (duration: 455.347932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:14.90251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:14.44695Z","time spent":"455.548615ms","remote":"127.0.0.1:48670","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-02T08:48:14.902146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:14.430354Z","time spent":"471.717221ms","remote":"127.0.0.1:48670","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-02T08:48:14.902295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.542067ms","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-02T08:48:14.903067Z","caller":"traceutil/trace.go:171","msg":"trace[1419941053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"464.332046ms","start":"2026-04-02T08:48:14.438714Z","end":"2026-04-02T08:48:14.903046Z","steps":["trace[1419941053] 'agreement among raft nodes before linearized reading' (duration: 463.444325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:14.903378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:14.438704Z","time spent":"464.65463ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:48:15.879837Z","caller":"traceutil/trace.go:171","msg":"trace[1996019619] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1396; }","duration":"127.074287ms","start":"2026-04-02T08:48:15.752738Z","end":"2026-04-02T08:48:15.879812Z","steps":["trace[1996019619] 'process raft request' (duration: 81.856798ms)","trace[1996019619] 'compare' (duration: 45.039267ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:16.087814Z","caller":"traceutil/trace.go:171","msg":"trace[581340790] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"170.877436ms","start":"2026-04-02T08:48:15.916896Z","end":"2026-04-02T08:48:16.087773Z","steps":["trace[581340790] 'process raft request' (duration: 137.834724ms)","trace[581340790] 'compare' (duration: 32.86181ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:17.116544Z","caller":"traceutil/trace.go:171","msg":"trace[370906884] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"286.744274ms","start":"2026-04-02T08:48:16.829768Z","end":"2026-04-02T08:48:17.116512Z","steps":["trace[370906884] 'process raft request' (duration: 286.533002ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:17.291847Z","caller":"traceutil/trace.go:171","msg":"trace[2068261936] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1458; }","duration":"374.229662ms","start":"2026-04-02T08:48:16.917584Z","end":"2026-04-02T08:48:17.291814Z","steps":["trace[2068261936] 'read index received' (duration: 198.75349ms)","trace[2068261936] 'applied index is now lower than readState.Index' (duration: 175.474792ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:17.292006Z","caller":"traceutil/trace.go:171","msg":"trace[2189837] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"383.987846ms","start":"2026-04-02T08:48:16.907997Z","end":"2026-04-02T08:48:17.291985Z","steps":["trace[2189837] 'process raft request' (duration: 382.341155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:17.292127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:16.907964Z","time spent":"384.079307ms","remote":"127.0.0.1:48670","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-02T08:48:17.292147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.339772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T08:48:17.292211Z","caller":"traceutil/trace.go:171","msg":"trace[1694956516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1399; }","duration":"334.444293ms","start":"2026-04-02T08:48:16.957751Z","end":"2026-04-02T08:48:17.292195Z","steps":["trace[1694956516] 'agreement among raft nodes before linearized reading' (duration: 334.27069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:17.292257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:16.957736Z","time spent":"334.512153ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:48:17.292323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.928244ms","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-02T08:48:17.292366Z","caller":"traceutil/trace.go:171","msg":"trace[1700445880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1399; }","duration":"332.989005ms","start":"2026-04-02T08:48:16.959367Z","end":"2026-04-02T08:48:17.292356Z","steps":["trace[1700445880] 'agreement among raft nodes before linearized reading' (duration: 332.884453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:17.292298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.142674ms","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-02T08:48:17.292395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:16.959359Z","time spent":"333.029735ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:48:17.292418Z","caller":"traceutil/trace.go:171","msg":"trace[1912368603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1399; }","duration":"332.309676ms","start":"2026-04-02T08:48:16.960092Z","end":"2026-04-02T08:48:17.292402Z","steps":["trace[1912368603] 'agreement among raft nodes before linearized reading' (duration: 332.067143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:17.292467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:16.960069Z","time spent":"332.383497ms","remote":"127.0.0.1:48670","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-02T08:48:17.292971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.369136ms","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-02T08:48:17.293042Z","caller":"traceutil/trace.go:171","msg":"trace[600362696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1399; }","duration":"375.481297ms","start":"2026-04-02T08:48:16.917547Z","end":"2026-04-02T08:48:17.293028Z","steps":["trace[600362696] 'agreement among raft nodes before linearized reading' (duration: 374.703887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:17.293701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:16.917532Z","time spent":"376.156216ms","remote":"127.0.0.1:48670","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-02T08:48:22.795281Z","caller":"traceutil/trace.go:171","msg":"trace[912996797] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"116.970912ms","start":"2026-04-02T08:48:22.678288Z","end":"2026-04-02T08:48:22.795259Z","steps":["trace[912996797] 'read index received' (duration: 116.742208ms)","trace[912996797] 'applied index is now lower than readState.Index' (duration: 227.854µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:22.795673Z","caller":"traceutil/trace.go:171","msg":"trace[1084265363] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"176.627993ms","start":"2026-04-02T08:48:22.619032Z","end":"2026-04-02T08:48:22.79566Z","steps":["trace[1084265363] 'process raft request' (duration: 176.088354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:22.795804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.48094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-04-02T08:48:22.795898Z","caller":"traceutil/trace.go:171","msg":"trace[1719374605] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1416; }","duration":"117.624112ms","start":"2026-04-02T08:48:22.678247Z","end":"2026-04-02T08:48:22.795871Z","steps":["trace[1719374605] 'agreement among raft nodes before linearized reading' (duration: 117.434999ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:23.489472Z","caller":"traceutil/trace.go:171","msg":"trace[1281147672] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"103.26959ms","start":"2026-04-02T08:48:23.386175Z","end":"2026-04-02T08:48:23.489444Z","steps":["trace[1281147672] 'process raft request' (duration: 101.629699ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:23.489784Z","caller":"traceutil/trace.go:171","msg":"trace[283468928] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"103.584332ms","start":"2026-04-02T08:48:23.38617Z","end":"2026-04-02T08:48:23.489754Z","steps":["trace[283468928] 'process raft request' (duration: 103.208858ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:23.490263Z","caller":"traceutil/trace.go:171","msg":"trace[1053417555] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1480; }","duration":"104.044799ms","start":"2026-04-02T08:48:23.386204Z","end":"2026-04-02T08:48:23.490249Z","steps":["trace[1053417555] 'read index received' (duration: 53.21364ms)","trace[1053417555] 'applied index is now lower than readState.Index' (duration: 50.830029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:48:23.490409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.182625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-02T08:48:23.490463Z","caller":"traceutil/trace.go:171","msg":"trace[1509497732] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"103.626404ms","start":"2026-04-02T08:48:23.386817Z","end":"2026-04-02T08:48:23.490443Z","steps":["trace[1509497732] 'process raft request' (duration: 102.810272ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:23.490473Z","caller":"traceutil/trace.go:171","msg":"trace[1617328730] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1422; }","duration":"104.253758ms","start":"2026-04-02T08:48:23.386199Z","end":"2026-04-02T08:48:23.490453Z","steps":["trace[1617328730] 'agreement among raft nodes before linearized reading' (duration: 104.139814ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:23.6931Z","caller":"traceutil/trace.go:171","msg":"trace[1988921195] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"118.87248ms","start":"2026-04-02T08:48:23.57419Z","end":"2026-04-02T08:48:23.693063Z","steps":["trace[1988921195] 'process raft request' (duration: 73.773256ms)","trace[1988921195] 'compare' (duration: 44.100476ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:31.487592Z","caller":"traceutil/trace.go:171","msg":"trace[1125684778] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"416.343153ms","start":"2026-04-02T08:48:31.07122Z","end":"2026-04-02T08:48:31.487563Z","steps":["trace[1125684778] 'read index received' (duration: 341.576314ms)","trace[1125684778] 'applied index is now lower than readState.Index' (duration: 74.765259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:48:31.48788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.608304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-04-02T08:48:31.488046Z","caller":"traceutil/trace.go:171","msg":"trace[1963565946] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1442; }","duration":"416.818681ms","start":"2026-04-02T08:48:31.071182Z","end":"2026-04-02T08:48:31.488001Z","steps":["trace[1963565946] 'agreement among raft nodes before linearized reading' (duration: 416.49588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.48812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:31.071166Z","time spent":"416.941215ms","remote":"127.0.0.1:48766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":786,"request content":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-02T08:48:31.487927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.908086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-04-02T08:48:31.488412Z","caller":"traceutil/trace.go:171","msg":"trace[823515304] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1443; }","duration":"397.438876ms","start":"2026-04-02T08:48:31.090954Z","end":"2026-04-02T08:48:31.488393Z","steps":["trace[823515304] 'agreement among raft nodes before linearized reading' (duration: 396.890736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.488462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:31.090934Z","time spent":"397.515939ms","remote":"127.0.0.1:48752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":735,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"warn","ts":"2026-04-02T08:48:31.488858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.921661ms","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-02T08:48:31.489294Z","caller":"traceutil/trace.go:171","msg":"trace[398795844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1443; }","duration":"253.463351ms","start":"2026-04-02T08:48:31.235802Z","end":"2026-04-02T08:48:31.489266Z","steps":["trace[398795844] 'agreement among raft nodes before linearized reading' (duration: 252.553948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.795285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.602599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:48:31.795413Z","caller":"traceutil/trace.go:171","msg":"trace[1174546003] linearizableReadLoop","detail":"{readStateIndex:1508; appliedIndex:1507; }","duration":"287.206017ms","start":"2026-04-02T08:48:31.50818Z","end":"2026-04-02T08:48:31.795386Z","steps":["trace[1174546003] 'read index received' (duration: 161.431121ms)","trace[1174546003] 'applied index is now lower than readState.Index' (duration: 125.773796ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:31.795524Z","caller":"traceutil/trace.go:171","msg":"trace[1702845064] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"301.161044ms","start":"2026-04-02T08:48:31.494302Z","end":"2026-04-02T08:48:31.795463Z","steps":["trace[1702845064] 'process raft request' (duration: 175.295926ms)","trace[1702845064] 'compare' (duration: 125.408122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:48:31.795597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.416645ms","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-02T08:48:31.795631Z","caller":"traceutil/trace.go:171","msg":"trace[1942816760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1444; }","duration":"287.504768ms","start":"2026-04-02T08:48:31.508116Z","end":"2026-04-02T08:48:31.79562Z","steps":["trace[1942816760] 'agreement among raft nodes before linearized reading' (duration: 287.374273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.795697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.943455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-02T08:48:31.795734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.921234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"warn","ts":"2026-04-02T08:48:31.795751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:31.494287Z","time spent":"301.344192ms","remote":"127.0.0.1:48670","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-02T08:48:31.795804Z","caller":"traceutil/trace.go:171","msg":"trace[1073298925] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1444; }","duration":"230.046168ms","start":"2026-04-02T08:48:31.565742Z","end":"2026-04-02T08:48:31.795788Z","steps":["trace[1073298925] 'agreement among raft nodes before linearized reading' (duration: 229.887912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.795861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.861512ms","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-02T08:48:31.795916Z","caller":"traceutil/trace.go:171","msg":"trace[350093839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1444; }","duration":"270.931994ms","start":"2026-04-02T08:48:31.524971Z","end":"2026-04-02T08:48:31.795903Z","steps":["trace[350093839] 'agreement among raft nodes before linearized reading' (duration: 270.80973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.795922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.158639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:1 size:739"} {"level":"info","ts":"2026-04-02T08:48:31.795999Z","caller":"traceutil/trace.go:171","msg":"trace[950317198] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:1; response_revision:1444; }","duration":"217.278063ms","start":"2026-04-02T08:48:31.578711Z","end":"2026-04-02T08:48:31.795989Z","steps":["trace[950317198] 'agreement among raft nodes before linearized reading' (duration: 217.17642ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:31.795754Z","caller":"traceutil/trace.go:171","msg":"trace[793749987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1444; }","duration":"271.041339ms","start":"2026-04-02T08:48:31.524697Z","end":"2026-04-02T08:48:31.795738Z","steps":["trace[793749987] 'agreement among raft nodes before linearized reading' (duration: 270.902604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.796096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.321879ms","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-02T08:48:31.796142Z","caller":"traceutil/trace.go:171","msg":"trace[154185980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1444; }","duration":"271.404373ms","start":"2026-04-02T08:48:31.524727Z","end":"2026-04-02T08:48:31.796131Z","steps":["trace[154185980] 'agreement among raft nodes before linearized reading' (duration: 271.294579ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:31.919543Z","caller":"traceutil/trace.go:171","msg":"trace[853299815] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"118.071744ms","start":"2026-04-02T08:48:31.801436Z","end":"2026-04-02T08:48:31.919508Z","steps":["trace[853299815] 'process raft request' (duration: 102.503778ms)","trace[853299815] 'compare' (duration: 15.266115ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:31.919941Z","caller":"traceutil/trace.go:171","msg":"trace[1112179680] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"118.447989ms","start":"2026-04-02T08:48:31.801467Z","end":"2026-04-02T08:48:31.919915Z","steps":["trace[1112179680] 'process raft request' (duration: 117.999732ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:31.920109Z","caller":"traceutil/trace.go:171","msg":"trace[476370724] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1508; }","duration":"107.896915ms","start":"2026-04-02T08:48:31.812183Z","end":"2026-04-02T08:48:31.92008Z","steps":["trace[476370724] 'read index received' (duration: 81.685661ms)","trace[476370724] 'applied index is now lower than readState.Index' (duration: 26.209594ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:31.920219Z","caller":"traceutil/trace.go:171","msg":"trace[393272614] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"118.657226ms","start":"2026-04-02T08:48:31.801513Z","end":"2026-04-02T08:48:31.92017Z","steps":["trace[393272614] 'process raft request' (duration: 118.326984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:31.920376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.096063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:48:31.920424Z","caller":"traceutil/trace.go:171","msg":"trace[1092308216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1448; }","duration":"108.280179ms","start":"2026-04-02T08:48:31.812132Z","end":"2026-04-02T08:48:31.920413Z","steps":["trace[1092308216] 'agreement among raft nodes before linearized reading' (duration: 108.094242ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:33.306982Z","caller":"traceutil/trace.go:171","msg":"trace[655547062] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"125.099019ms","start":"2026-04-02T08:48:33.181833Z","end":"2026-04-02T08:48:33.306932Z","steps":["trace[655547062] 'process raft request' (duration: 124.690934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:33.967144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.344965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-02T08:48:33.967257Z","caller":"traceutil/trace.go:171","msg":"trace[1297938896] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1452; }","duration":"113.486279ms","start":"2026-04-02T08:48:33.853739Z","end":"2026-04-02T08:48:33.967225Z","steps":["trace[1297938896] 'range keys from in-memory index tree' (duration: 113.264771ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:34.158343Z","caller":"traceutil/trace.go:171","msg":"trace[1031809149] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"222.527205ms","start":"2026-04-02T08:48:33.935792Z","end":"2026-04-02T08:48:34.15832Z","steps":["trace[1031809149] 'process raft request' (duration: 222.355289ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:34.204878Z","caller":"traceutil/trace.go:171","msg":"trace[1233135721] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"232.677003ms","start":"2026-04-02T08:48:33.972178Z","end":"2026-04-02T08:48:34.204855Z","steps":["trace[1233135721] 'process raft request' (duration: 197.923737ms)","trace[1233135721] 'compare' (duration: 34.58ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:34.205202Z","caller":"traceutil/trace.go:171","msg":"trace[1661631508] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1516; }","duration":"232.32379ms","start":"2026-04-02T08:48:33.972855Z","end":"2026-04-02T08:48:34.205178Z","steps":["trace[1661631508] 'read index received' (duration: 185.409868ms)","trace[1661631508] 'applied index is now lower than readState.Index' (duration: 46.912952ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:34.205255Z","caller":"traceutil/trace.go:171","msg":"trace[210845824] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"232.802517ms","start":"2026-04-02T08:48:33.972438Z","end":"2026-04-02T08:48:34.205241Z","steps":["trace[210845824] 'process raft request' (duration: 232.380952ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:34.2053Z","caller":"traceutil/trace.go:171","msg":"trace[1136390741] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"231.522341ms","start":"2026-04-02T08:48:33.973751Z","end":"2026-04-02T08:48:34.205273Z","steps":["trace[1136390741] 'process raft request' (duration: 231.286972ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:34.205338Z","caller":"traceutil/trace.go:171","msg":"trace[382239824] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"231.148757ms","start":"2026-04-02T08:48:33.974177Z","end":"2026-04-02T08:48:34.205326Z","steps":["trace[382239824] 'process raft request' (duration: 230.960071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:34.205389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.379528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:1 size:715"} {"level":"info","ts":"2026-04-02T08:48:34.205419Z","caller":"traceutil/trace.go:171","msg":"trace[1396732120] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:1; response_revision:1457; }","duration":"154.476041ms","start":"2026-04-02T08:48:34.050935Z","end":"2026-04-02T08:48:34.205411Z","steps":["trace[1396732120] 'agreement among raft nodes before linearized reading' (duration: 154.398699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:34.205539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.667232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-02T08:48:34.205584Z","caller":"traceutil/trace.go:171","msg":"trace[1619283843] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1457; }","duration":"232.744325ms","start":"2026-04-02T08:48:33.972821Z","end":"2026-04-02T08:48:34.205565Z","steps":["trace[1619283843] 'agreement among raft nodes before linearized reading' (duration: 232.644381ms)"],"step_count":1}