{"level":"warn","ts":"2026-03-05T07:26:02.588701Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T07:26:02.588776Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-03-05T07:26:02.588832Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T07:26:02.588845Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-05T07:26:02.588861Z","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-03-05T07:26:02.589444Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-03-05T07:26:02.589536Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-03-05T07:26:02.593399Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.670045ms"} {"level":"info","ts":"2026-03-05T07:26:02.599589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-03-05T07:26:02.599653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T07:26:02.599676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-03-05T07:26:02.599686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T07:26:02.599697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-03-05T07:26:02.59973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-03-05T07:26:02.608038Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T07:26:02.611253Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T07:26:02.612485Z","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-03-05T07:26:02.614499Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T07:26:02.615377Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T07:26:02.615462Z","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-03-05T07:26:02.615496Z","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-03-05T07:26:02.615504Z","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-03-05T07:26:02.617962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-03-05T07:26:02.618179Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-05T07:26:02.621745Z","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-03-05T07:26:02.621892Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-05T07:26:02.621967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-05T07:26:02.621995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T07:26:02.622019Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T07:26:03.50055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T07:26:03.500631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T07:26:03.500665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-03-05T07:26:03.500687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-03-05T07:26:03.500704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-05T07:26:03.500721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-03-05T07:26:03.500741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-05T07:26:03.501789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:26:03.502788Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T07:26:03.502801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T07:26:03.50286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T07:26:03.503211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T07:26:03.503271Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T07:26:03.504161Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:26:03.504301Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:26:03.504342Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:26:03.506133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-03-05T07:26:03.506829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T07:26:25.226381Z","caller":"traceutil/trace.go:171","msg":"trace[1210993315] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"270.739683ms","start":"2026-03-05T07:26:24.955612Z","end":"2026-03-05T07:26:25.226352Z","steps":["trace[1210993315] 'process raft request' (duration: 270.30956ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:26:25.276395Z","caller":"traceutil/trace.go:171","msg":"trace[2028837711] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:433; }","duration":"115.006695ms","start":"2026-03-05T07:26:25.161363Z","end":"2026-03-05T07:26:25.27637Z","steps":["trace[2028837711] 'read index received' (duration: 64.840829ms)","trace[2028837711] 'applied index is now lower than readState.Index' (duration: 50.164856ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:26:25.276657Z","caller":"traceutil/trace.go:171","msg":"trace[1101074995] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"319.96738ms","start":"2026-03-05T07:26:24.956492Z","end":"2026-03-05T07:26:25.276459Z","steps":["trace[1101074995] 'process raft request' (duration: 319.744283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:25.27665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.883564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-05T07:26:25.276636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.241713ms","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-03-05T07:26:25.276761Z","caller":"traceutil/trace.go:171","msg":"trace[576394391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"112.03655ms","start":"2026-03-05T07:26:25.164708Z","end":"2026-03-05T07:26:25.276745Z","steps":["trace[576394391] 'agreement among raft nodes before linearized reading' (duration: 111.857084ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:26:25.276782Z","caller":"traceutil/trace.go:171","msg":"trace[1681767977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"115.443109ms","start":"2026-03-05T07:26:25.161317Z","end":"2026-03-05T07:26:25.27676Z","steps":["trace[1681767977] 'agreement among raft nodes before linearized reading' (duration: 115.192191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:25.277703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:24.956479Z","time spent":"320.646192ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:26:25.634057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.345993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:26:25.634353Z","caller":"traceutil/trace.go:171","msg":"trace[2029180620] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"353.335445ms","start":"2026-03-05T07:26:25.281Z","end":"2026-03-05T07:26:25.634336Z","steps":["trace[2029180620] 'process raft request' (duration: 353.237442ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:26:25.634381Z","caller":"traceutil/trace.go:171","msg":"trace[1038773518] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"353.584944ms","start":"2026-03-05T07:26:25.280767Z","end":"2026-03-05T07:26:25.634352Z","steps":["trace[1038773518] 'process raft request' (duration: 40.871797ms)","trace[1038773518] 'compare' (duration: 311.135576ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:25.635231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:25.280742Z","time spent":"353.702028ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:26:25.635364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:25.280944Z","time spent":"353.456829ms","remote":"127.0.0.1:51692","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-03-05T07:26:25.782662Z","caller":"traceutil/trace.go:171","msg":"trace[110970656] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"146.874203ms","start":"2026-03-05T07:26:25.635761Z","end":"2026-03-05T07:26:25.782635Z","steps":["trace[110970656] 'process raft request' (duration: 134.704211ms)","trace[110970656] 'compare' (duration: 12.053718ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:32.062804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.670317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:26:32.062917Z","caller":"traceutil/trace.go:171","msg":"trace[2060563467] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"239.840918ms","start":"2026-03-05T07:26:31.823055Z","end":"2026-03-05T07:26:32.062896Z","steps":["trace[2060563467] 'read index received' (duration: 40.451µs)","trace[2060563467] 'applied index is now lower than readState.Index' (duration: 239.799137ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:32.062976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.925651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:26:32.063009Z","caller":"traceutil/trace.go:171","msg":"trace[887884706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"239.983663ms","start":"2026-03-05T07:26:31.823014Z","end":"2026-03-05T07:26:32.062998Z","steps":["trace[887884706] 'agreement among raft nodes before linearized reading' (duration: 239.931591ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:26:32.063059Z","caller":"traceutil/trace.go:171","msg":"trace[1359631085] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"398.255533ms","start":"2026-03-05T07:26:31.664766Z","end":"2026-03-05T07:26:32.063021Z","steps":["trace[1359631085] 'process raft request' (duration: 147.280956ms)","trace[1359631085] 'compare' (duration: 250.43414ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:32.063151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:31.664756Z","time spent":"398.351506ms","remote":"127.0.0.1:51692","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-03-05T07:26:32.731793Z","caller":"traceutil/trace.go:171","msg":"trace[363518753] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"127.422955ms","start":"2026-03-05T07:26:32.604346Z","end":"2026-03-05T07:26:32.731769Z","steps":["trace[363518753] 'process raft request' (duration: 127.288101ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:26:38.885798Z","caller":"traceutil/trace.go:171","msg":"trace[1951568010] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"157.088613ms","start":"2026-03-05T07:26:38.728674Z","end":"2026-03-05T07:26:38.885762Z","steps":["trace[1951568010] 'process raft request' (duration: 156.918947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:39.834431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830252904737626,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T07:26:39.925123Z","caller":"traceutil/trace.go:171","msg":"trace[1910730653] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"591.429499ms","start":"2026-03-05T07:26:39.33367Z","end":"2026-03-05T07:26:39.9251Z","steps":["trace[1910730653] 'read index received' (duration: 591.208882ms)","trace[1910730653] 'applied index is now lower than readState.Index' (duration: 219.747µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:26:39.925229Z","caller":"traceutil/trace.go:171","msg":"trace[1346470071] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"634.6077ms","start":"2026-03-05T07:26:39.290597Z","end":"2026-03-05T07:26:39.925205Z","steps":["trace[1346470071] 'process raft request' (duration: 634.337191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:39.925309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.629665ms","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-03-05T07:26:39.925361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:39.290571Z","time spent":"634.718903ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:26:39.925463Z","caller":"traceutil/trace.go:171","msg":"trace[1975339964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"591.750599ms","start":"2026-03-05T07:26:39.333616Z","end":"2026-03-05T07:26:39.925367Z","steps":["trace[1975339964] 'agreement among raft nodes before linearized reading' (duration: 591.609045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:39.925494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.570357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-05T07:26:39.925511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:39.333588Z","time spent":"591.914444ms","remote":"127.0.0.1:51692","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-03-05T07:26:39.925628Z","caller":"traceutil/trace.go:171","msg":"trace[358425425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"174.735032ms","start":"2026-03-05T07:26:39.750794Z","end":"2026-03-05T07:26:39.925529Z","steps":["trace[358425425] 'agreement among raft nodes before linearized reading' (duration: 174.428012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:40.404548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.098128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:26:40.404708Z","caller":"traceutil/trace.go:171","msg":"trace[997638515] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"310.191226ms","start":"2026-03-05T07:26:40.094499Z","end":"2026-03-05T07:26:40.404691Z","steps":["trace[997638515] 'read index received' (duration: 297.195677ms)","trace[997638515] 'applied index is now lower than readState.Index' (duration: 12.994589ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:40.4048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.30776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-05T07:26:40.404812Z","caller":"traceutil/trace.go:171","msg":"trace[1026951585] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"475.140412ms","start":"2026-03-05T07:26:39.929638Z","end":"2026-03-05T07:26:40.404779Z","steps":["trace[1026951585] 'process raft request' (duration: 81.737854ms)","trace[1026951585] 'compare' (duration: 393.003215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:40.404989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:39.929616Z","time spent":"475.305237ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:26:40.404905Z","caller":"traceutil/trace.go:171","msg":"trace[1241536125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"310.430734ms","start":"2026-03-05T07:26:40.094447Z","end":"2026-03-05T07:26:40.404878Z","steps":["trace[1241536125] 'agreement among raft nodes before linearized reading' (duration: 310.289979ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:26:40.404835Z","caller":"traceutil/trace.go:171","msg":"trace[263269185] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"474.737919ms","start":"2026-03-05T07:26:39.930073Z","end":"2026-03-05T07:26:40.404811Z","steps":["trace[263269185] 'process raft request' (duration: 474.573583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:40.405105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:40.094427Z","time spent":"310.6059ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T07:26:40.405349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:39.930046Z","time spent":"475.035148ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:26:41.928596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.045988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:26:41.929211Z","caller":"traceutil/trace.go:171","msg":"trace[584869438] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"340.682158ms","start":"2026-03-05T07:26:41.588502Z","end":"2026-03-05T07:26:41.929184Z","steps":["trace[584869438] 'read index received' (duration: 53.957279ms)","trace[584869438] 'applied index is now lower than readState.Index' (duration: 286.722229ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:26:41.92921Z","caller":"traceutil/trace.go:171","msg":"trace[1988288547] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"413.34691ms","start":"2026-03-05T07:26:41.515827Z","end":"2026-03-05T07:26:41.929174Z","steps":["trace[1988288547] 'process raft request' (duration: 126.62467ms)","trace[1988288547] 'compare' (duration: 285.943035ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:26:41.929636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:41.515806Z","time spent":"413.697412ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T07:26:41.93296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.443139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-05T07:26:41.933029Z","caller":"traceutil/trace.go:171","msg":"trace[1601232991] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:500; }","duration":"344.538393ms","start":"2026-03-05T07:26:41.588472Z","end":"2026-03-05T07:26:41.933011Z","steps":["trace[1601232991] 'agreement among raft nodes before linearized reading' (duration: 340.798492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:26:41.93307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:26:41.588452Z","time spent":"344.608675ms","remote":"127.0.0.1:51540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-05T07:26:41.933301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.202677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:26:41.933346Z","caller":"traceutil/trace.go:171","msg":"trace[1761508629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"108.282859ms","start":"2026-03-05T07:26:41.825052Z","end":"2026-03-05T07:26:41.933335Z","steps":["trace[1761508629] 'agreement among raft nodes before linearized reading' (duration: 108.214137ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:00.993779Z","caller":"traceutil/trace.go:171","msg":"trace[40485520] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"950.476329ms","start":"2026-03-05T07:27:00.043286Z","end":"2026-03-05T07:27:00.993762Z","steps":["trace[40485520] 'process raft request' (duration: 950.368335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:00.993903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:00.043263Z","time spent":"950.579582ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:27:01.014811Z","caller":"traceutil/trace.go:171","msg":"trace[577750064] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"500.805588ms","start":"2026-03-05T07:27:00.513978Z","end":"2026-03-05T07:27:01.014784Z","steps":["trace[577750064] 'read index received' (duration: 479.790412ms)","trace[577750064] 'applied index is now lower than readState.Index' (duration: 21.014206ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:01.01526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.964033ms","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-03-05T07:27:01.015321Z","caller":"traceutil/trace.go:171","msg":"trace[1397002214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"501.035785ms","start":"2026-03-05T07:27:00.514268Z","end":"2026-03-05T07:27:01.015304Z","steps":["trace[1397002214] 'agreement among raft nodes before linearized reading' (duration: 500.8869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:01.015354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:00.514262Z","time spent":"501.084636ms","remote":"127.0.0.1:51692","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-03-05T07:27:01.015344Z","caller":"traceutil/trace.go:171","msg":"trace[1168302679] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"971.495165ms","start":"2026-03-05T07:27:00.043824Z","end":"2026-03-05T07:27:01.015319Z","steps":["trace[1168302679] 'process raft request' (duration: 970.835134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:01.01551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:00.043814Z","time spent":"971.605528ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:27:01.015562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.352348ms","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-03-05T07:27:01.015599Z","caller":"traceutil/trace.go:171","msg":"trace[1019430471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"498.426691ms","start":"2026-03-05T07:27:00.517162Z","end":"2026-03-05T07:27:01.015589Z","steps":["trace[1019430471] 'agreement among raft nodes before linearized reading' (duration: 498.336048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:01.015638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:00.517139Z","time spent":"498.493133ms","remote":"127.0.0.1:51692","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-03-05T07:27:01.015813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.828661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T07:27:01.015874Z","caller":"traceutil/trace.go:171","msg":"trace[465613179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"501.910574ms","start":"2026-03-05T07:27:00.513946Z","end":"2026-03-05T07:27:01.015856Z","steps":["trace[465613179] 'agreement among raft nodes before linearized reading' (duration: 501.753959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:01.015917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:00.513929Z","time spent":"501.976635ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T07:27:01.839591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.639608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:27:01.839785Z","caller":"traceutil/trace.go:171","msg":"trace[2040233133] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"818.969811ms","start":"2026-03-05T07:27:01.0208Z","end":"2026-03-05T07:27:01.83977Z","steps":["trace[2040233133] 'process raft request' (duration: 818.938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:01.839848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:01.020792Z","time spent":"819.025202ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:27:01.840858Z","caller":"traceutil/trace.go:171","msg":"trace[1552189438] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"822.626888ms","start":"2026-03-05T07:27:01.018187Z","end":"2026-03-05T07:27:01.840813Z","steps":["trace[1552189438] 'process raft request' (duration: 565.680268ms)","trace[1552189438] 'compare' (duration: 255.555246ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:01.84104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:01.018162Z","time spent":"822.777303ms","remote":"127.0.0.1:51692","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-03-05T07:27:01.840084Z","caller":"traceutil/trace.go:171","msg":"trace[2125239724] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"821.251474ms","start":"2026-03-05T07:27:01.018806Z","end":"2026-03-05T07:27:01.840058Z","steps":["trace[2125239724] 'process raft request' (duration: 820.877232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:01.841235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:01.018787Z","time spent":"822.423662ms","remote":"127.0.0.1:51692","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-03-05T07:27:01.985388Z","caller":"traceutil/trace.go:171","msg":"trace[120279017] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"160.640226ms","start":"2026-03-05T07:27:01.824719Z","end":"2026-03-05T07:27:01.985359Z","steps":["trace[120279017] 'read index received' (duration: 148.33904ms)","trace[120279017] 'applied index is now lower than readState.Index' (duration: 12.299596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:01.985538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.792851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:27:01.985628Z","caller":"traceutil/trace.go:171","msg":"trace[271558220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"160.914985ms","start":"2026-03-05T07:27:01.824685Z","end":"2026-03-05T07:27:01.9856Z","steps":["trace[271558220] 'agreement among raft nodes before linearized reading' (duration: 160.776151ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:05.120422Z","caller":"traceutil/trace.go:171","msg":"trace[1727511941] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"103.673991ms","start":"2026-03-05T07:27:05.016716Z","end":"2026-03-05T07:27:05.12039Z","steps":["trace[1727511941] 'process raft request' (duration: 103.439704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:05.727833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830252904738017,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T07:27:05.902468Z","caller":"traceutil/trace.go:171","msg":"trace[326211894] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"675.259559ms","start":"2026-03-05T07:27:05.227179Z","end":"2026-03-05T07:27:05.902438Z","steps":["trace[326211894] 'read index received' (duration: 652.150875ms)","trace[326211894] 'applied index is now lower than readState.Index' (duration: 23.107424ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:27:05.90259Z","caller":"traceutil/trace.go:171","msg":"trace[1219733252] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"778.297501ms","start":"2026-03-05T07:27:05.12428Z","end":"2026-03-05T07:27:05.902577Z","steps":["trace[1219733252] 'process raft request' (duration: 755.049592ms)","trace[1219733252] 'compare' (duration: 22.825215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:05.902689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:05.124262Z","time spent":"778.355132ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:27:05.902808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.58204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:27:05.902896Z","caller":"traceutil/trace.go:171","msg":"trace[753899852] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:686; }","duration":"675.739446ms","start":"2026-03-05T07:27:05.227139Z","end":"2026-03-05T07:27:05.902878Z","steps":["trace[753899852] 'agreement among raft nodes before linearized reading' (duration: 675.593451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:05.902931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:05.227125Z","time spent":"675.798147ms","remote":"127.0.0.1:51698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-05T07:27:06.089327Z","caller":"traceutil/trace.go:171","msg":"trace[2087496018] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"182.975536ms","start":"2026-03-05T07:27:05.906327Z","end":"2026-03-05T07:27:06.089302Z","steps":["trace[2087496018] 'process raft request' (duration: 139.66561ms)","trace[2087496018] 'compare' (duration: 43.204893ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:27:06.145894Z","caller":"traceutil/trace.go:171","msg":"trace[1889711331] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"238.884747ms","start":"2026-03-05T07:27:05.906998Z","end":"2026-03-05T07:27:06.145883Z","steps":["trace[1889711331] 'process raft request' (duration: 238.759093ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:06.146031Z","caller":"traceutil/trace.go:171","msg":"trace[87064981] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:712; }","duration":"198.146715ms","start":"2026-03-05T07:27:05.947859Z","end":"2026-03-05T07:27:06.146006Z","steps":["trace[87064981] 'read index received' (duration: 98.202205ms)","trace[87064981] 'applied index is now lower than readState.Index' (duration: 99.94249ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:06.146143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.276699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T07:27:06.146121Z","caller":"traceutil/trace.go:171","msg":"trace[21869318] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"237.729201ms","start":"2026-03-05T07:27:05.908331Z","end":"2026-03-05T07:27:06.14606Z","steps":["trace[21869318] 'process raft request' (duration: 237.529124ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:06.146172Z","caller":"traceutil/trace.go:171","msg":"trace[1204100133] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:689; }","duration":"198.346161ms","start":"2026-03-05T07:27:05.947818Z","end":"2026-03-05T07:27:06.146165Z","steps":["trace[1204100133] 'agreement among raft nodes before linearized reading' (duration: 198.263369ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:22.330777Z","caller":"traceutil/trace.go:171","msg":"trace[399609896] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"121.541687ms","start":"2026-03-05T07:27:22.209212Z","end":"2026-03-05T07:27:22.330753Z","steps":["trace[399609896] 'read index received' (duration: 121.31014ms)","trace[399609896] 'applied index is now lower than readState.Index' (duration: 230.187µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:27:22.330866Z","caller":"traceutil/trace.go:171","msg":"trace[942012380] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"177.09939ms","start":"2026-03-05T07:27:22.153733Z","end":"2026-03-05T07:27:22.330832Z","steps":["trace[942012380] 'process raft request' (duration: 176.876573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:22.331008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.779665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-05T07:27:22.331049Z","caller":"traceutil/trace.go:171","msg":"trace[759626612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"121.866028ms","start":"2026-03-05T07:27:22.209172Z","end":"2026-03-05T07:27:22.331038Z","steps":["trace[759626612] 'agreement among raft nodes before linearized reading' (duration: 121.737524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:22.3311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.123044ms","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-03-05T07:27:22.331192Z","caller":"traceutil/trace.go:171","msg":"trace[2094923033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"116.257069ms","start":"2026-03-05T07:27:22.214912Z","end":"2026-03-05T07:27:22.331169Z","steps":["trace[2094923033] 'agreement among raft nodes before linearized reading' (duration: 116.005411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:22.817576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.348612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:27:22.817767Z","caller":"traceutil/trace.go:171","msg":"trace[33275187] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"482.260415ms","start":"2026-03-05T07:27:22.335487Z","end":"2026-03-05T07:27:22.817747Z","steps":["trace[33275187] 'process raft request' (duration: 482.195833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:22.817845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:22.335459Z","time spent":"482.351968ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:27:22.817832Z","caller":"traceutil/trace.go:171","msg":"trace[505125196] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"482.562365ms","start":"2026-03-05T07:27:22.335235Z","end":"2026-03-05T07:27:22.817798Z","steps":["trace[505125196] 'process raft request' (duration: 229.909514ms)","trace[505125196] 'compare' (duration: 252.218777ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:22.817986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:22.335208Z","time spent":"482.693249ms","remote":"127.0.0.1:51692","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-03-05T07:27:22.817935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.950017ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:27:22.818329Z","caller":"traceutil/trace.go:171","msg":"trace[447625944] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:740; }","duration":"235.771683ms","start":"2026-03-05T07:27:22.582532Z","end":"2026-03-05T07:27:22.818304Z","steps":["trace[447625944] 'range keys from in-memory index tree' (duration: 234.478681ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:24.293763Z","caller":"traceutil/trace.go:171","msg":"trace[1858593627] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"318.608916ms","start":"2026-03-05T07:27:23.975124Z","end":"2026-03-05T07:27:24.293733Z","steps":["trace[1858593627] 'process raft request' (duration: 318.368688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:24.293918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:23.975106Z","time spent":"318.729579ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:27:24.510178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.27547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-05T07:27:24.510265Z","caller":"traceutil/trace.go:171","msg":"trace[1844322001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"174.381283ms","start":"2026-03-05T07:27:24.335864Z","end":"2026-03-05T07:27:24.510245Z","steps":["trace[1844322001] 'range keys from in-memory index tree' (duration: 174.012922ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:34.476202Z","caller":"traceutil/trace.go:171","msg":"trace[1118958236] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"135.032168ms","start":"2026-03-05T07:27:34.341148Z","end":"2026-03-05T07:27:34.47618Z","steps":["trace[1118958236] 'process raft request' (duration: 134.858732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:36.980362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830252904738363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T07:27:37.119058Z","caller":"traceutil/trace.go:171","msg":"trace[932683269] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"639.356228ms","start":"2026-03-05T07:27:36.479667Z","end":"2026-03-05T07:27:37.119023Z","steps":["trace[932683269] 'read index received' (duration: 638.968975ms)","trace[932683269] 'applied index is now lower than readState.Index' (duration: 385.243µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:37.119243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:36.445511Z","time spent":"673.724523ms","remote":"127.0.0.1:51456","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-05T07:27:37.119834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.151303ms","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-03-05T07:27:37.119919Z","caller":"traceutil/trace.go:171","msg":"trace[683474392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:827; }","duration":"640.297488ms","start":"2026-03-05T07:27:36.4796Z","end":"2026-03-05T07:27:37.119898Z","steps":["trace[683474392] 'agreement among raft nodes before linearized reading' (duration: 640.06287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:37.119965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:36.479551Z","time spent":"640.406051ms","remote":"127.0.0.1:51692","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-03-05T07:27:37.120023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.827936ms","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-03-05T07:27:37.120117Z","caller":"traceutil/trace.go:171","msg":"trace[943369500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:827; }","duration":"536.986731ms","start":"2026-03-05T07:27:36.583106Z","end":"2026-03-05T07:27:37.120093Z","steps":["trace[943369500] 'agreement among raft nodes before linearized reading' (duration: 536.670311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:37.120176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:36.583079Z","time spent":"537.085024ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T07:27:37.120323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.777349ms","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-03-05T07:27:37.120356Z","caller":"traceutil/trace.go:171","msg":"trace[1686254477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:827; }","duration":"249.845871ms","start":"2026-03-05T07:27:36.870501Z","end":"2026-03-05T07:27:37.120347Z","steps":["trace[1686254477] 'agreement among raft nodes before linearized reading' (duration: 249.770799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:37.120435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.715237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T07:27:37.120453Z","caller":"traceutil/trace.go:171","msg":"trace[1506807112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:827; }","duration":"249.789159ms","start":"2026-03-05T07:27:36.870659Z","end":"2026-03-05T07:27:37.120448Z","steps":["trace[1506807112] 'agreement among raft nodes before linearized reading' (duration: 249.740168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:37.120506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.165867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:27:37.120535Z","caller":"traceutil/trace.go:171","msg":"trace[1869525842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"295.235069ms","start":"2026-03-05T07:27:36.825282Z","end":"2026-03-05T07:27:37.120517Z","steps":["trace[1869525842] 'agreement among raft nodes before linearized reading' (duration: 295.211189ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:37.312176Z","caller":"traceutil/trace.go:171","msg":"trace[1321738743] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"190.311012ms","start":"2026-03-05T07:27:37.121828Z","end":"2026-03-05T07:27:37.312139Z","steps":["trace[1321738743] 'process raft request' (duration: 189.155945ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:37.321962Z","caller":"traceutil/trace.go:171","msg":"trace[207251539] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"192.830122ms","start":"2026-03-05T07:27:37.129114Z","end":"2026-03-05T07:27:37.321944Z","steps":["trace[207251539] 'process raft request' (duration: 192.7753ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:37.321998Z","caller":"traceutil/trace.go:171","msg":"trace[320762308] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"194.658751ms","start":"2026-03-05T07:27:37.127305Z","end":"2026-03-05T07:27:37.321964Z","steps":["trace[320762308] 'process raft request' (duration: 194.525426ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:37.322133Z","caller":"traceutil/trace.go:171","msg":"trace[1113422087] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"193.05518ms","start":"2026-03-05T07:27:37.129068Z","end":"2026-03-05T07:27:37.322123Z","steps":["trace[1113422087] 'process raft request' (duration: 192.791361ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:37.322222Z","caller":"traceutil/trace.go:171","msg":"trace[93053509] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"196.684785ms","start":"2026-03-05T07:27:37.125526Z","end":"2026-03-05T07:27:37.322211Z","steps":["trace[93053509] 'process raft request' (duration: 196.183629ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:53.771401Z","caller":"traceutil/trace.go:171","msg":"trace[1232022238] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"358.433686ms","start":"2026-03-05T07:27:53.412944Z","end":"2026-03-05T07:27:53.771378Z","steps":["trace[1232022238] 'read index received' (duration: 358.213439ms)","trace[1232022238] 'applied index is now lower than readState.Index' (duration: 219.387µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:53.771638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.517568ms","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-03-05T07:27:53.771682Z","caller":"traceutil/trace.go:171","msg":"trace[902513771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"358.619961ms","start":"2026-03-05T07:27:53.413048Z","end":"2026-03-05T07:27:53.771668Z","steps":["trace[902513771] 'agreement among raft nodes before linearized reading' (duration: 358.468147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:53.771664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.690224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-05T07:27:53.771731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.413018Z","time spent":"358.704204ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-05T07:27:53.771748Z","caller":"traceutil/trace.go:171","msg":"trace[1280157533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"358.800117ms","start":"2026-03-05T07:27:53.412926Z","end":"2026-03-05T07:27:53.771727Z","steps":["trace[1280157533] 'agreement among raft nodes before linearized reading' (duration: 358.554579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:53.771794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.41292Z","time spent":"358.865449ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T07:27:53.771771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.300429ms","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-03-05T07:27:53.771829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.145825ms","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-03-05T07:27:53.771871Z","caller":"traceutil/trace.go:171","msg":"trace[1408767279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"336.460905ms","start":"2026-03-05T07:27:53.435391Z","end":"2026-03-05T07:27:53.771851Z","steps":["trace[1408767279] 'agreement among raft nodes before linearized reading' (duration: 336.185366ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:53.771891Z","caller":"traceutil/trace.go:171","msg":"trace[676469228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"352.227688ms","start":"2026-03-05T07:27:53.419647Z","end":"2026-03-05T07:27:53.771875Z","steps":["trace[676469228] 'agreement among raft nodes before linearized reading' (duration: 352.036662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:53.771926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.435371Z","time spent":"336.541947ms","remote":"127.0.0.1:51692","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-03-05T07:27:53.771929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.419621Z","time spent":"352.29881ms","remote":"127.0.0.1:51692","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-03-05T07:27:53.771514Z","caller":"traceutil/trace.go:171","msg":"trace[1113189279] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"359.954164ms","start":"2026-03-05T07:27:53.411493Z","end":"2026-03-05T07:27:53.771447Z","steps":["trace[1113189279] 'process raft request' (duration: 359.737697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:53.772433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.411478Z","time spent":"360.675217ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:27:54.325562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830252904738531,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T07:27:54.465927Z","caller":"traceutil/trace.go:171","msg":"trace[189010483] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"689.499818ms","start":"2026-03-05T07:27:53.776399Z","end":"2026-03-05T07:27:54.465899Z","steps":["trace[189010483] 'process raft request' (duration: 689.323963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:54.466294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.776376Z","time spent":"689.627713ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:27:54.49034Z","caller":"traceutil/trace.go:171","msg":"trace[1336641513] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"713.449372ms","start":"2026-03-05T07:27:53.77687Z","end":"2026-03-05T07:27:54.49032Z","steps":["trace[1336641513] 'process raft request' (duration: 713.36664ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:54.490359Z","caller":"traceutil/trace.go:171","msg":"trace[1667385460] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"713.469252ms","start":"2026-03-05T07:27:53.77685Z","end":"2026-03-05T07:27:54.490319Z","steps":["trace[1667385460] 'process raft request' (duration: 713.341888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:54.491135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.776862Z","time spent":"713.548885ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:27:54.49133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.77684Z","time spent":"714.337251ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:27:54.490951Z","caller":"traceutil/trace.go:171","msg":"trace[1023172198] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"713.599867ms","start":"2026-03-05T07:27:53.776791Z","end":"2026-03-05T07:27:54.490391Z","steps":["trace[1023172198] 'process raft request' (duration: 713.243755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:54.491492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.776769Z","time spent":"714.682351ms","remote":"127.0.0.1:51692","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-03-05T07:27:54.491636Z","caller":"traceutil/trace.go:171","msg":"trace[219084067] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:939; }","duration":"665.557414ms","start":"2026-03-05T07:27:53.824717Z","end":"2026-03-05T07:27:54.490275Z","steps":["trace[219084067] 'read index received' (duration: 641.097814ms)","trace[219084067] 'applied index is now lower than readState.Index' (duration: 24.45856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:54.4919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.178295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:27:54.491956Z","caller":"traceutil/trace.go:171","msg":"trace[882909480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"667.258619ms","start":"2026-03-05T07:27:53.824668Z","end":"2026-03-05T07:27:54.491927Z","steps":["trace[882909480] 'agreement among raft nodes before linearized reading' (duration: 667.188676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:54.491996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:53.824642Z","time spent":"667.346441ms","remote":"127.0.0.1:51418","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-03-05T07:27:56.326134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830252904738539,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T07:27:56.822327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039886277s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T07:27:56.822775Z","caller":"traceutil/trace.go:171","msg":"trace[1976365501] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"996.969937ms","start":"2026-03-05T07:27:55.825776Z","end":"2026-03-05T07:27:56.822746Z","steps":["trace[1976365501] 'read index received' (duration: 996.859934ms)","trace[1976365501] 'applied index is now lower than readState.Index' (duration: 108.933µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:27:56.823017Z","caller":"traceutil/trace.go:171","msg":"trace[939126727] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"1.040676793s","start":"2026-03-05T07:27:55.782328Z","end":"2026-03-05T07:27:56.823005Z","steps":["trace[939126727] 'process raft request' (duration: 1.04027079s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.734794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T07:27:56.82313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:55.782309Z","time spent":"1.040738404s","remote":"127.0.0.1:51692","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-03-05T07:27:56.823154Z","caller":"traceutil/trace.go:171","msg":"trace[770119018] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:907; }","duration":"709.898159ms","start":"2026-03-05T07:27:56.113241Z","end":"2026-03-05T07:27:56.82314Z","steps":["trace[770119018] 'agreement among raft nodes before linearized reading' (duration: 709.794246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:56.113222Z","time spent":"709.967931ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-05T07:27:56.823348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.558763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T07:27:56.823414Z","caller":"traceutil/trace.go:171","msg":"trace[810081577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"302.696767ms","start":"2026-03-05T07:27:56.52069Z","end":"2026-03-05T07:27:56.823387Z","steps":["trace[810081577] 'agreement among raft nodes before linearized reading' (duration: 302.530172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:56.520655Z","time spent":"302.783561ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T07:27:56.823466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.896483ms","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-03-05T07:27:56.823499Z","caller":"traceutil/trace.go:171","msg":"trace[1811199465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"302.934665ms","start":"2026-03-05T07:27:56.520554Z","end":"2026-03-05T07:27:56.823488Z","steps":["trace[1811199465] 'agreement among raft nodes before linearized reading' (duration: 302.818401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.317018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-05T07:27:56.823556Z","caller":"traceutil/trace.go:171","msg":"trace[1712910412] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:907; }","duration":"303.382659ms","start":"2026-03-05T07:27:56.520157Z","end":"2026-03-05T07:27:56.82354Z","steps":["trace[1712910412] 'agreement among raft nodes before linearized reading' (duration: 303.272475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:56.520551Z","time spent":"302.969306ms","remote":"127.0.0.1:51692","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-03-05T07:27:56.823614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.847636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:27:56.82365Z","caller":"traceutil/trace.go:171","msg":"trace[286111391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"997.909528ms","start":"2026-03-05T07:27:55.825725Z","end":"2026-03-05T07:27:56.823635Z","steps":["trace[286111391] 'agreement among raft nodes before linearized reading' (duration: 997.861736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:55.825703Z","time spent":"997.961419ms","remote":"127.0.0.1:51418","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-03-05T07:27:56.823719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.221844ms","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-03-05T07:27:56.823756Z","caller":"traceutil/trace.go:171","msg":"trace[506118555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"303.266995ms","start":"2026-03-05T07:27:56.520479Z","end":"2026-03-05T07:27:56.823746Z","steps":["trace[506118555] 'agreement among raft nodes before linearized reading' (duration: 303.156692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:56.520474Z","time spent":"303.305506ms","remote":"127.0.0.1:51692","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-03-05T07:27:56.823808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.885093ms","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-03-05T07:27:56.823836Z","caller":"traceutil/trace.go:171","msg":"trace[682727715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"302.919844ms","start":"2026-03-05T07:27:56.520909Z","end":"2026-03-05T07:27:56.823829Z","steps":["trace[682727715] 'agreement among raft nodes before linearized reading' (duration: 302.852272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:56.823853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:56.520904Z","time spent":"302.944996ms","remote":"127.0.0.1:51692","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-03-05T07:27:56.82361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:56.520142Z","time spent":"303.456062ms","remote":"127.0.0.1:51456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-03-05T07:27:57.080121Z","caller":"traceutil/trace.go:171","msg":"trace[1781820353] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"100.478445ms","start":"2026-03-05T07:27:56.979623Z","end":"2026-03-05T07:27:57.080102Z","steps":["trace[1781820353] 'process raft request' (duration: 100.30901ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:57.194605Z","caller":"traceutil/trace.go:171","msg":"trace[81317784] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"109.351989ms","start":"2026-03-05T07:27:57.085226Z","end":"2026-03-05T07:27:57.194578Z","steps":["trace[81317784] 'process raft request' (duration: 109.233455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:57.557131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.029223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:27:57.557312Z","caller":"traceutil/trace.go:171","msg":"trace[1959497344] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"355.781591ms","start":"2026-03-05T07:27:57.201513Z","end":"2026-03-05T07:27:57.557295Z","steps":["trace[1959497344] 'read index received' (duration: 19.75782ms)","trace[1959497344] 'applied index is now lower than readState.Index' (duration: 336.022121ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:27:57.557372Z","caller":"traceutil/trace.go:171","msg":"trace[945868476] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"355.971057ms","start":"2026-03-05T07:27:57.201377Z","end":"2026-03-05T07:27:57.557348Z","steps":["trace[945868476] 'process raft request' (duration: 70.662205ms)","trace[945868476] 'compare' (duration: 284.90297ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:57.557476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:57.201356Z","time spent":"356.07154ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T07:27:57.557494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.972577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-05T07:27:57.557528Z","caller":"traceutil/trace.go:171","msg":"trace[1037536078] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:917; }","duration":"356.024879ms","start":"2026-03-05T07:27:57.20149Z","end":"2026-03-05T07:27:57.557515Z","steps":["trace[1037536078] 'agreement among raft nodes before linearized reading' (duration: 355.880374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:57.557568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:57.201484Z","time spent":"356.07507ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-05T07:27:57.644079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.501887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T07:27:57.644165Z","caller":"traceutil/trace.go:171","msg":"trace[1738367179] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:918; }","duration":"163.641861ms","start":"2026-03-05T07:27:57.480506Z","end":"2026-03-05T07:27:57.644148Z","steps":["trace[1738367179] 'agreement among raft nodes before linearized reading' (duration: 163.460645ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:57.644408Z","caller":"traceutil/trace.go:171","msg":"trace[813697975] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"419.785163ms","start":"2026-03-05T07:27:57.224613Z","end":"2026-03-05T07:27:57.644398Z","steps":["trace[813697975] 'process raft request' (duration: 419.237156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:57.64448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:57.224593Z","time spent":"419.838765ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T07:27:58.179689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.194145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:27:58.179833Z","caller":"traceutil/trace.go:171","msg":"trace[182437379] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:957; }","duration":"493.486294ms","start":"2026-03-05T07:27:57.686337Z","end":"2026-03-05T07:27:58.179823Z","steps":["trace[182437379] 'read index received' (duration: 168.100533ms)","trace[182437379] 'applied index is now lower than readState.Index' (duration: 325.385251ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:58.179895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.558547ms","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-03-05T07:27:58.179911Z","caller":"traceutil/trace.go:171","msg":"trace[903271283] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:920; }","duration":"493.604328ms","start":"2026-03-05T07:27:57.686302Z","end":"2026-03-05T07:27:58.179907Z","steps":["trace[903271283] 'agreement among raft nodes before linearized reading' (duration: 493.551436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:58.179929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:57.686287Z","time spent":"493.63803ms","remote":"127.0.0.1:51860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-03-05T07:27:58.180038Z","caller":"traceutil/trace.go:171","msg":"trace[617027806] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"617.45991ms","start":"2026-03-05T07:27:57.562524Z","end":"2026-03-05T07:27:58.179984Z","steps":["trace[617027806] 'process raft request' (duration: 291.918303ms)","trace[617027806] 'compare' (duration: 325.03132ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:27:58.180041Z","caller":"traceutil/trace.go:171","msg":"trace[1062864296] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"529.201724ms","start":"2026-03-05T07:27:57.65082Z","end":"2026-03-05T07:27:58.180021Z","steps":["trace[1062864296] 'process raft request' (duration: 528.946736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:58.180203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:57.562499Z","time spent":"617.619835ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:27:58.18021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:27:57.650795Z","time spent":"529.326028ms","remote":"127.0.0.1:51692","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-03-05T07:27:58.662437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.103539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T07:27:58.662711Z","caller":"traceutil/trace.go:171","msg":"trace[1818342372] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:920; }","duration":"218.436439ms","start":"2026-03-05T07:27:58.444257Z","end":"2026-03-05T07:27:58.662694Z","steps":["trace[1818342372] 'count revisions from in-memory index tree' (duration: 218.020065ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.010123Z","caller":"traceutil/trace.go:171","msg":"trace[417092406] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"180.080905ms","start":"2026-03-05T07:27:58.83Z","end":"2026-03-05T07:27:59.010081Z","steps":["trace[417092406] 'process raft request' (duration: 179.812407ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.010117Z","caller":"traceutil/trace.go:171","msg":"trace[1349902914] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"129.191512ms","start":"2026-03-05T07:27:58.880875Z","end":"2026-03-05T07:27:59.010066Z","steps":["trace[1349902914] 'read index received' (duration: 128.959135ms)","trace[1349902914] 'applied index is now lower than readState.Index' (duration: 230.707µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:27:59.010338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.43965ms","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-03-05T07:27:59.010381Z","caller":"traceutil/trace.go:171","msg":"trace[774178408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"129.540903ms","start":"2026-03-05T07:27:58.880829Z","end":"2026-03-05T07:27:59.01037Z","steps":["trace[774178408] 'agreement among raft nodes before linearized reading' (duration: 129.343596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:59.010413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.007865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-05T07:27:59.01044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.546041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-05T07:27:59.010468Z","caller":"traceutil/trace.go:171","msg":"trace[1295352886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"128.629704ms","start":"2026-03-05T07:27:58.881831Z","end":"2026-03-05T07:27:59.010461Z","steps":["trace[1295352886] 'agreement among raft nodes before linearized reading' (duration: 128.497939ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.01047Z","caller":"traceutil/trace.go:171","msg":"trace[618038515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"129.067967ms","start":"2026-03-05T07:27:58.881383Z","end":"2026-03-05T07:27:59.010451Z","steps":["trace[618038515] 'agreement among raft nodes before linearized reading' (duration: 128.921303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:27:59.010536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.716657ms","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-03-05T07:27:59.010566Z","caller":"traceutil/trace.go:171","msg":"trace[1141228531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"128.769408ms","start":"2026-03-05T07:27:58.881788Z","end":"2026-03-05T07:27:59.010557Z","steps":["trace[1141228531] 'agreement among raft nodes before linearized reading' (duration: 128.684585ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.181582Z","caller":"traceutil/trace.go:171","msg":"trace[1952777574] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"167.189874ms","start":"2026-03-05T07:27:59.014365Z","end":"2026-03-05T07:27:59.181555Z","steps":["trace[1952777574] 'process raft request' (duration: 164.87224ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.184911Z","caller":"traceutil/trace.go:171","msg":"trace[637538971] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"170.17628ms","start":"2026-03-05T07:27:59.014716Z","end":"2026-03-05T07:27:59.184893Z","steps":["trace[637538971] 'process raft request' (duration: 169.998414ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.185181Z","caller":"traceutil/trace.go:171","msg":"trace[754124609] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"170.384707ms","start":"2026-03-05T07:27:59.014777Z","end":"2026-03-05T07:27:59.185162Z","steps":["trace[754124609] 'process raft request' (duration: 170.331305ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:27:59.185366Z","caller":"traceutil/trace.go:171","msg":"trace[863471904] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"170.604123ms","start":"2026-03-05T07:27:59.014732Z","end":"2026-03-05T07:27:59.185336Z","steps":["trace[863471904] 'process raft request' (duration: 170.119318ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:28:06.820385Z","caller":"traceutil/trace.go:171","msg":"trace[447411005] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"303.40194ms","start":"2026-03-05T07:28:06.516963Z","end":"2026-03-05T07:28:06.820365Z","steps":["trace[447411005] 'read index received' (duration: 303.202794ms)","trace[447411005] 'applied index is now lower than readState.Index' (duration: 198.186µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:28:06.820555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.578035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-05T07:28:06.820589Z","caller":"traceutil/trace.go:171","msg":"trace[482539897] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:973; }","duration":"303.660158ms","start":"2026-03-05T07:28:06.51692Z","end":"2026-03-05T07:28:06.82058Z","steps":["trace[482539897] 'agreement among raft nodes before linearized reading' (duration: 303.526554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:06.820618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:06.516902Z","time spent":"303.70972ms","remote":"127.0.0.1:51456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-03-05T07:28:06.820635Z","caller":"traceutil/trace.go:171","msg":"trace[1723398922] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"417.027696ms","start":"2026-03-05T07:28:06.403577Z","end":"2026-03-05T07:28:06.820605Z","steps":["trace[1723398922] 'process raft request' (duration: 416.653594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:06.820804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:06.403557Z","time spent":"417.152629ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:28:07.63463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.470605ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-05T07:28:07.634766Z","caller":"traceutil/trace.go:171","msg":"trace[1670750697] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"595.957584ms","start":"2026-03-05T07:28:07.038777Z","end":"2026-03-05T07:28:07.634735Z","steps":["trace[1670750697] 'read index received' (duration: 37.442µs)","trace[1670750697] 'applied index is now lower than readState.Index' (duration: 595.918752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:28:07.634891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:06.822526Z","time spent":"812.361228ms","remote":"127.0.0.1:51456","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-05T07:28:07.635223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.427379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-05T07:28:07.635273Z","caller":"traceutil/trace.go:171","msg":"trace[1006807351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"596.537502ms","start":"2026-03-05T07:28:07.038724Z","end":"2026-03-05T07:28:07.635262Z","steps":["trace[1006807351] 'agreement among raft nodes before linearized reading' (duration: 596.359287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:07.635304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.038703Z","time spent":"596.594494ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T07:28:07.635297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.095221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T07:28:07.635377Z","caller":"traceutil/trace.go:171","msg":"trace[1122041379] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:973; }","duration":"477.295888ms","start":"2026-03-05T07:28:07.158063Z","end":"2026-03-05T07:28:07.635359Z","steps":["trace[1122041379] 'agreement among raft nodes before linearized reading' (duration: 477.138113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:07.635423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.158026Z","time spent":"477.387981ms","remote":"127.0.0.1:51764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-05T07:28:07.635583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.737012ms","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-03-05T07:28:07.635661Z","caller":"traceutil/trace.go:171","msg":"trace[1403008729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"419.841085ms","start":"2026-03-05T07:28:07.215802Z","end":"2026-03-05T07:28:07.635643Z","steps":["trace[1403008729] 'agreement among raft nodes before linearized reading' (duration: 419.628839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:07.635654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.014534ms","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-03-05T07:28:07.635669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.558088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-03-05T07:28:07.635669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.956866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-05T07:28:07.635705Z","caller":"traceutil/trace.go:171","msg":"trace[1679467421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"422.103407ms","start":"2026-03-05T07:28:07.213592Z","end":"2026-03-05T07:28:07.635695Z","steps":["trace[1679467421] 'agreement among raft nodes before linearized reading' (duration: 421.995234ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:28:07.635746Z","caller":"traceutil/trace.go:171","msg":"trace[398170575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"424.07074ms","start":"2026-03-05T07:28:07.211659Z","end":"2026-03-05T07:28:07.63573Z","steps":["trace[398170575] 'agreement among raft nodes before linearized reading' (duration: 423.876224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:07.635758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.213538Z","time spent":"422.19925ms","remote":"127.0.0.1:51692","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-03-05T07:28:07.635702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.215795Z","time spent":"419.898297ms","remote":"127.0.0.1:51692","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":"info","ts":"2026-03-05T07:28:07.635769Z","caller":"traceutil/trace.go:171","msg":"trace[1507592851] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:973; }","duration":"467.63037ms","start":"2026-03-05T07:28:07.168071Z","end":"2026-03-05T07:28:07.635701Z","steps":["trace[1507592851] 'agreement among raft nodes before linearized reading' (duration: 467.507626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:07.635787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.211644Z","time spent":"424.134932ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T07:28:07.635807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.168055Z","time spent":"467.743313ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-05T07:28:07.635973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.631204ms","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-03-05T07:28:07.636067Z","caller":"traceutil/trace.go:171","msg":"trace[632005255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"417.80262ms","start":"2026-03-05T07:28:07.218245Z","end":"2026-03-05T07:28:07.636047Z","steps":["trace[632005255] 'agreement among raft nodes before linearized reading' (duration: 417.534152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:28:07.636118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:28:07.218222Z","time spent":"417.887022ms","remote":"127.0.0.1:51692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T07:28:54.046345Z","caller":"traceutil/trace.go:171","msg":"trace[1228684711] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"169.60361ms","start":"2026-03-05T07:28:53.876712Z","end":"2026-03-05T07:28:54.046315Z","steps":["trace[1228684711] 'process raft request' (duration: 169.339592ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:28:54.046406Z","caller":"traceutil/trace.go:171","msg":"trace[1246827996] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"169.62889ms","start":"2026-03-05T07:28:53.876761Z","end":"2026-03-05T07:28:54.04639Z","steps":["trace[1246827996] 'process raft request' (duration: 169.444745ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:28:54.04639Z","caller":"traceutil/trace.go:171","msg":"trace[1181651325] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"167.954198ms","start":"2026-03-05T07:28:53.878412Z","end":"2026-03-05T07:28:54.046367Z","steps":["trace[1181651325] 'process raft request' (duration: 167.913936ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:28:54.046431Z","caller":"traceutil/trace.go:171","msg":"trace[747048472] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"168.898558ms","start":"2026-03-05T07:28:53.877516Z","end":"2026-03-05T07:28:54.046415Z","steps":["trace[747048472] 'process raft request' (duration: 168.775524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:31:12.658712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.515677ms","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-03-05T07:31:12.658797Z","caller":"traceutil/trace.go:171","msg":"trace[682963463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1761; }","duration":"115.646922ms","start":"2026-03-05T07:31:12.54313Z","end":"2026-03-05T07:31:12.658777Z","steps":["trace[682963463] 'range keys from in-memory index tree' (duration: 115.367672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:31:12.659171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.518437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-05T07:31:12.659244Z","caller":"traceutil/trace.go:171","msg":"trace[1714757452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1761; }","duration":"115.647391ms","start":"2026-03-05T07:31:12.543583Z","end":"2026-03-05T07:31:12.65923Z","steps":["trace[1714757452] 'range keys from in-memory index tree' (duration: 115.349912ms)"],"step_count":1}