{"level":"warn","ts":"2026-02-25T02:13:14.949069Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:13:14.94919Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-02-25T02:13:14.949281Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:13:14.949294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-02-25T02:13:14.949319Z","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-02-25T02:13:14.950177Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-02-25T02:13:14.950296Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-02-25T02:13:14.959421Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.816961ms"} {"level":"info","ts":"2026-02-25T02:13:14.995954Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-02-25T02:13:14.99606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:13:14.996086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-02-25T02:13:14.996098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:13:14.996113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-02-25T02:13:14.996161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-02-25T02:13:15.002665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:13:15.004162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:13:15.005347Z","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-02-25T02:13:15.00695Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:13:15.007283Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:13:15.008661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-02-25T02:13:15.009016Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-02-25T02:13:15.007278Z","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-02-25T02:13:15.009158Z","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-02-25T02:13:15.009169Z","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-02-25T02:13:15.012923Z","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-02-25T02:13:15.013207Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:13:15.013268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:13:15.013371Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-02-25T02:13:15.014466Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-02-25T02:13:15.697219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:13:15.697332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:13:15.697376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-02-25T02:13:15.697402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:13:15.697414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-02-25T02:13:15.697434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-02-25T02:13:15.69745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-02-25T02:13:15.699704Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:15.701004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:13:15.701008Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:13:15.701237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:13:15.701422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:15.701542Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:15.701651Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:15.701709Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:13:15.701765Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:13:15.703555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:13:15.711849Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"warn","ts":"2026-02-25T02:13:22.07268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309612686243485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:13:22.080029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.187212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T02:13:22.08009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.402779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:22.080146Z","caller":"traceutil/trace.go:171","msg":"trace[431055979] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:303; }","duration":"657.368757ms","start":"2026-02-25T02:13:21.422756Z","end":"2026-02-25T02:13:22.080125Z","steps":["trace[431055979] 'range keys from in-memory index tree' (duration: 657.066209ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:22.080172Z","caller":"traceutil/trace.go:171","msg":"trace[1402559912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:303; }","duration":"578.553952ms","start":"2026-02-25T02:13:21.501597Z","end":"2026-02-25T02:13:22.080151Z","steps":["trace[1402559912] 'range keys from in-memory index tree' (duration: 578.303506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.080241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:21.422717Z","time spent":"657.51243ms","remote":"127.0.0.1:44764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-02-25T02:13:22.080244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.836002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5865"} {"level":"warn","ts":"2026-02-25T02:13:22.080289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:21.50157Z","time spent":"578.662415ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T02:13:22.08034Z","caller":"traceutil/trace.go:171","msg":"trace[816850433] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:303; }","duration":"530.022506ms","start":"2026-02-25T02:13:21.550296Z","end":"2026-02-25T02:13:22.080319Z","steps":["trace[816850433] 'range keys from in-memory index tree' (duration: 529.634466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.080383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:21.550262Z","time spent":"530.113599ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5888,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-25T02:13:22.234758Z","caller":"traceutil/trace.go:171","msg":"trace[1444001813] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"681.545662ms","start":"2026-02-25T02:13:21.55318Z","end":"2026-02-25T02:13:22.234726Z","steps":["trace[1444001813] 'process raft request' (duration: 681.44974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.235314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:21.553152Z","time spent":"681.698156ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:13:22.235351Z","caller":"traceutil/trace.go:171","msg":"trace[1519279929] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:312; }","duration":"663.664164ms","start":"2026-02-25T02:13:21.571658Z","end":"2026-02-25T02:13:22.235322Z","steps":["trace[1519279929] 'read index received' (duration: 663.647194ms)","trace[1519279929] 'applied index is now lower than readState.Index' (duration: 10.83µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:22.235561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.883991ms","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-02-25T02:13:22.235659Z","caller":"traceutil/trace.go:171","msg":"trace[2121935887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:303; }","duration":"664.056024ms","start":"2026-02-25T02:13:21.571583Z","end":"2026-02-25T02:13:22.235639Z","steps":["trace[2121935887] 'agreement among raft nodes before linearized reading' (duration: 663.843619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.235705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:21.571554Z","time spent":"664.142307ms","remote":"127.0.0.1:44840","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-02-25T02:13:22.24203Z","caller":"traceutil/trace.go:171","msg":"trace[255745683] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"157.26992ms","start":"2026-02-25T02:13:22.084742Z","end":"2026-02-25T02:13:22.242012Z","steps":["trace[255745683] 'process raft request' (duration: 156.991533ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:22.2421Z","caller":"traceutil/trace.go:171","msg":"trace[1302445238] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"136.468589ms","start":"2026-02-25T02:13:22.105603Z","end":"2026-02-25T02:13:22.242072Z","steps":["trace[1302445238] 'process raft request' (duration: 136.334855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.242274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.31242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:22.242367Z","caller":"traceutil/trace.go:171","msg":"trace[1532984850] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:305; }","duration":"157.472535ms","start":"2026-02-25T02:13:22.08487Z","end":"2026-02-25T02:13:22.242343Z","steps":["trace[1532984850] 'agreement among raft nodes before linearized reading' (duration: 157.171238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.242374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.270475ms","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-02-25T02:13:22.242502Z","caller":"traceutil/trace.go:171","msg":"trace[1396717675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"663.47593ms","start":"2026-02-25T02:13:21.579Z","end":"2026-02-25T02:13:22.242476Z","steps":["trace[1396717675] 'agreement among raft nodes before linearized reading' (duration: 663.131591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:22.242554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:21.578978Z","time spent":"663.567242ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:13:27.831402Z","caller":"traceutil/trace.go:171","msg":"trace[2079980218] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"262.962377ms","start":"2026-02-25T02:13:27.56841Z","end":"2026-02-25T02:13:27.831372Z","steps":["trace[2079980218] 'read index received' (duration: 262.718051ms)","trace[2079980218] 'applied index is now lower than readState.Index' (duration: 243.196µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:27.8316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.163232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-xtskg\" ","response":"range_response_count:1 size:4413"} {"level":"info","ts":"2026-02-25T02:13:27.831643Z","caller":"traceutil/trace.go:171","msg":"trace[1965302570] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-xtskg; range_end:; response_count:1; response_revision:394; }","duration":"263.276405ms","start":"2026-02-25T02:13:27.568353Z","end":"2026-02-25T02:13:27.83163Z","steps":["trace[1965302570] 'agreement among raft nodes before linearized reading' (duration: 263.164362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:27.831887Z","caller":"traceutil/trace.go:171","msg":"trace[1941885822] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"277.810979ms","start":"2026-02-25T02:13:27.554031Z","end":"2026-02-25T02:13:27.831842Z","steps":["trace[1941885822] 'process raft request' (duration: 277.161433ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:48.032329Z","caller":"traceutil/trace.go:171","msg":"trace[706237319] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"301.302948ms","start":"2026-02-25T02:13:47.730999Z","end":"2026-02-25T02:13:48.032302Z","steps":["trace[706237319] 'process raft request' (duration: 301.090043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:48.032702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:47.730982Z","time spent":"301.432761ms","remote":"127.0.0.1:44732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4256,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:55.414403Z","caller":"traceutil/trace.go:171","msg":"trace[1534859416] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"274.032654ms","start":"2026-02-25T02:13:55.140336Z","end":"2026-02-25T02:13:55.414368Z","steps":["trace[1534859416] 'process raft request' (duration: 273.685175ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:56.950294Z","caller":"traceutil/trace.go:171","msg":"trace[1281823118] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"457.07498ms","start":"2026-02-25T02:13:56.493184Z","end":"2026-02-25T02:13:56.950259Z","steps":["trace[1281823118] 'process raft request' (duration: 456.644879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:56.951626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:56.493153Z","time spent":"458.103516ms","remote":"127.0.0.1:44840","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-02-25T02:13:57.593897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.493278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:13:57.594108Z","caller":"traceutil/trace.go:171","msg":"trace[1635751078] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"1.088076635s","start":"2026-02-25T02:13:56.505966Z","end":"2026-02-25T02:13:57.594042Z","steps":["trace[1635751078] 'read index received' (duration: 444.042013ms)","trace[1635751078] 'applied index is now lower than readState.Index' (duration: 644.033132ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:57.594137Z","caller":"traceutil/trace.go:171","msg":"trace[2038260899] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.098376203s","start":"2026-02-25T02:13:56.495743Z","end":"2026-02-25T02:13:57.594119Z","steps":["trace[2038260899] 'process raft request' (duration: 482.372193ms)","trace[2038260899] 'compare' (duration: 615.360824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:57.594218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:56.495723Z","time spent":"1.098449875s","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:57.594246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008286607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:57.594286Z","caller":"traceutil/trace.go:171","msg":"trace[1351376337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.00839877s","start":"2026-02-25T02:13:56.585878Z","end":"2026-02-25T02:13:57.594277Z","steps":["trace[1351376337] 'agreement among raft nodes before linearized reading' (duration: 1.008328258s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:57.594326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:56.58584Z","time spent":"1.008479002s","remote":"127.0.0.1:44562","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-02-25T02:13:57.594305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088311132s","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-02-25T02:13:57.594389Z","caller":"traceutil/trace.go:171","msg":"trace[557790106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"1.088408474s","start":"2026-02-25T02:13:56.505959Z","end":"2026-02-25T02:13:57.594367Z","steps":["trace[557790106] 'agreement among raft nodes before linearized reading' (duration: 1.088212329s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:57.594444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:56.505933Z","time spent":"1.088502176s","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:13:57.594471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.974798ms","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-02-25T02:13:57.594567Z","caller":"traceutil/trace.go:171","msg":"trace[1697084345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"174.134872ms","start":"2026-02-25T02:13:57.420405Z","end":"2026-02-25T02:13:57.59454Z","steps":["trace[1697084345] 'agreement among raft nodes before linearized reading' (duration: 173.885705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:59.125883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.004067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-02-25T02:13:59.125925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.758272ms","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-02-25T02:13:59.125978Z","caller":"traceutil/trace.go:171","msg":"trace[2080913327] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:551; }","duration":"241.174381ms","start":"2026-02-25T02:13:58.884782Z","end":"2026-02-25T02:13:59.125957Z","steps":["trace[2080913327] 'range keys from in-memory index tree' (duration: 240.877334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:59.125993Z","caller":"traceutil/trace.go:171","msg":"trace[999147888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"169.850065ms","start":"2026-02-25T02:13:58.956117Z","end":"2026-02-25T02:13:59.125967Z","steps":["trace[999147888] 'range keys from in-memory index tree' (duration: 169.583348ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:15.747029Z","caller":"traceutil/trace.go:171","msg":"trace[1254793031] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"160.731952ms","start":"2026-02-25T02:14:15.586268Z","end":"2026-02-25T02:14:15.747Z","steps":["trace[1254793031] 'read index received' (duration: 160.453725ms)","trace[1254793031] 'applied index is now lower than readState.Index' (duration: 276.787µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:15.747181Z","caller":"traceutil/trace.go:171","msg":"trace[1022388691] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"284.305461ms","start":"2026-02-25T02:14:15.462859Z","end":"2026-02-25T02:14:15.747164Z","steps":["trace[1022388691] 'process raft request' (duration: 283.968633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:15.747429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.153342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:15.747467Z","caller":"traceutil/trace.go:171","msg":"trace[1179236697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"161.262415ms","start":"2026-02-25T02:14:15.586192Z","end":"2026-02-25T02:14:15.747454Z","steps":["trace[1179236697] 'agreement among raft nodes before linearized reading' (duration: 161.181283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:16.376368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.878186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:14:16.376628Z","caller":"traceutil/trace.go:171","msg":"trace[282098809] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"606.161775ms","start":"2026-02-25T02:14:15.770445Z","end":"2026-02-25T02:14:16.376607Z","steps":["trace[282098809] 'process raft request' (duration: 606.119164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:16.376716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:15.77042Z","time spent":"606.254018ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:16.376927Z","caller":"traceutil/trace.go:171","msg":"trace[361976202] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"609.718925ms","start":"2026-02-25T02:14:15.767165Z","end":"2026-02-25T02:14:16.376884Z","steps":["trace[361976202] 'process raft request' (duration: 440.208413ms)","trace[361976202] 'compare' (duration: 168.531087ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:16.376944Z","caller":"traceutil/trace.go:171","msg":"trace[395376386] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"606.615757ms","start":"2026-02-25T02:14:15.770298Z","end":"2026-02-25T02:14:16.376913Z","steps":["trace[395376386] 'process raft request' (duration: 606.203296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:16.377107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:15.767151Z","time spent":"609.859628ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:16.377165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:15.770282Z","time spent":"606.73616ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:49.954987Z","caller":"traceutil/trace.go:171","msg":"trace[993767195] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"369.405606ms","start":"2026-02-25T02:14:49.585542Z","end":"2026-02-25T02:14:49.954948Z","steps":["trace[993767195] 'read index received' (duration: 369.12671ms)","trace[993767195] 'applied index is now lower than readState.Index' (duration: 277.246µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:49.955052Z","caller":"traceutil/trace.go:171","msg":"trace[652980858] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"492.005231ms","start":"2026-02-25T02:14:49.463012Z","end":"2026-02-25T02:14:49.955018Z","steps":["trace[652980858] 'process raft request' (duration: 491.685043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:49.955213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:49.462967Z","time spent":"492.163335ms","remote":"127.0.0.1:44840","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-02-25T02:14:49.955517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.782006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:14:49.955692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.726329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:49.955768Z","caller":"traceutil/trace.go:171","msg":"trace[1277343741] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:829; }","duration":"183.895364ms","start":"2026-02-25T02:14:49.771853Z","end":"2026-02-25T02:14:49.955748Z","steps":["trace[1277343741] 'agreement among raft nodes before linearized reading' (duration: 183.75485ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:49.955713Z","caller":"traceutil/trace.go:171","msg":"trace[516682443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"370.176866ms","start":"2026-02-25T02:14:49.585503Z","end":"2026-02-25T02:14:49.955679Z","steps":["trace[516682443] 'agreement among raft nodes before linearized reading' (duration: 369.644562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:49.955867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:49.585487Z","time spent":"370.36649ms","remote":"127.0.0.1:44562","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-02-25T02:14:52.69618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.566449ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:14:52.696349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.133587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:14:52.696364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.196044ms","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-02-25T02:14:52.697462Z","caller":"traceutil/trace.go:171","msg":"trace[1994963322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"112.306298ms","start":"2026-02-25T02:14:52.585127Z","end":"2026-02-25T02:14:52.697433Z","steps":["trace[1994963322] 'range keys from in-memory index tree' (duration: 110.994665ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:52.697411Z","caller":"traceutil/trace.go:171","msg":"trace[243320902] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:835; }","duration":"352.757159ms","start":"2026-02-25T02:14:52.344554Z","end":"2026-02-25T02:14:52.697312Z","steps":["trace[243320902] 'range keys from in-memory index tree' (duration: 351.541308ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:52.697578Z","caller":"traceutil/trace.go:171","msg":"trace[1568443691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:835; }","duration":"111.444786ms","start":"2026-02-25T02:14:52.586112Z","end":"2026-02-25T02:14:52.697557Z","steps":["trace[1568443691] 'range keys from in-memory index tree' (duration: 109.847845ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:52.982857Z","caller":"traceutil/trace.go:171","msg":"trace[1441846196] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"347.147258ms","start":"2026-02-25T02:14:52.635671Z","end":"2026-02-25T02:14:52.982818Z","steps":["trace[1441846196] 'process raft request' (duration: 346.81003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:52.983119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:52.635645Z","time spent":"347.348654ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:53.212782Z","caller":"traceutil/trace.go:171","msg":"trace[2138131362] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"510.601559ms","start":"2026-02-25T02:14:52.702153Z","end":"2026-02-25T02:14:53.212754Z","steps":["trace[2138131362] 'process raft request' (duration: 507.583513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:53.212951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:52.702128Z","time spent":"510.728803ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:53.213144Z","caller":"traceutil/trace.go:171","msg":"trace[261377866] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"510.784924ms","start":"2026-02-25T02:14:52.702305Z","end":"2026-02-25T02:14:53.21309Z","steps":["trace[261377866] 'process raft request' (duration: 510.368073ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:53.213443Z","caller":"traceutil/trace.go:171","msg":"trace[265217149] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"510.041305ms","start":"2026-02-25T02:14:52.703391Z","end":"2026-02-25T02:14:53.213432Z","steps":["trace[265217149] 'process raft request' (duration: 509.571633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:53.213814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:52.702265Z","time spent":"511.212655ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:53.214018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:52.703346Z","time spent":"510.528437ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:54.359506Z","caller":"traceutil/trace.go:171","msg":"trace[1079286220] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"293.330699ms","start":"2026-02-25T02:14:54.066123Z","end":"2026-02-25T02:14:54.359454Z","steps":["trace[1079286220] 'read index received' (duration: 293.015481ms)","trace[1079286220] 'applied index is now lower than readState.Index' (duration: 313.338µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:54.359588Z","caller":"traceutil/trace.go:171","msg":"trace[705399012] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"339.107977ms","start":"2026-02-25T02:14:54.02043Z","end":"2026-02-25T02:14:54.359538Z","steps":["trace[705399012] 'process raft request' (duration: 338.731948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.35979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.654437ms","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-02-25T02:14:54.359837Z","caller":"traceutil/trace.go:171","msg":"trace[1493698193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"293.751209ms","start":"2026-02-25T02:14:54.066073Z","end":"2026-02-25T02:14:54.359825Z","steps":["trace[1493698193] 'agreement among raft nodes before linearized reading' (duration: 293.541584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.359822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.020395Z","time spent":"339.285262ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:55.224693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.884713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:14:55.225363Z","caller":"traceutil/trace.go:171","msg":"trace[523107617] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"860.126639ms","start":"2026-02-25T02:14:54.365162Z","end":"2026-02-25T02:14:55.225289Z","steps":["trace[523107617] 'process raft request' (duration: 859.716999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:55.225578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.365134Z","time spent":"860.330334ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:56.601144Z","caller":"traceutil/trace.go:171","msg":"trace[1867854348] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"232.255586ms","start":"2026-02-25T02:14:56.368858Z","end":"2026-02-25T02:14:56.601114Z","steps":["trace[1867854348] 'process raft request' (duration: 231.954448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:57.779206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309612686244705,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:14:58.068742Z","caller":"traceutil/trace.go:171","msg":"trace[438393488] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"818.348439ms","start":"2026-02-25T02:14:57.250358Z","end":"2026-02-25T02:14:58.068706Z","steps":["trace[438393488] 'process raft request' (duration: 818.067762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:58.069167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:57.250344Z","time spent":"818.653686ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:58.081101Z","caller":"traceutil/trace.go:171","msg":"trace[1571127797] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"802.659395ms","start":"2026-02-25T02:14:57.27842Z","end":"2026-02-25T02:14:58.08108Z","steps":["trace[1571127797] 'read index received' (duration: 790.125921ms)","trace[1571127797] 'applied index is now lower than readState.Index' (duration: 12.530574ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:58.081254Z","caller":"traceutil/trace.go:171","msg":"trace[1001285646] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"829.364086ms","start":"2026-02-25T02:14:57.25188Z","end":"2026-02-25T02:14:58.081244Z","steps":["trace[1001285646] 'process raft request' (duration: 829.098279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:58.081457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:57.251874Z","time spent":"829.473468ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:58.081743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.800199ms","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-02-25T02:14:58.08152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.44686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:58.081852Z","caller":"traceutil/trace.go:171","msg":"trace[186511446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:847; }","duration":"802.957863ms","start":"2026-02-25T02:14:57.278869Z","end":"2026-02-25T02:14:58.081826Z","steps":["trace[186511446] 'agreement among raft nodes before linearized reading' (duration: 802.543203ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:58.081909Z","caller":"traceutil/trace.go:171","msg":"trace[1619340125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"495.880741ms","start":"2026-02-25T02:14:57.585997Z","end":"2026-02-25T02:14:58.081878Z","steps":["trace[1619340125] 'agreement among raft nodes before linearized reading' (duration: 495.47039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:58.081951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:57.278861Z","time spent":"803.076386ms","remote":"127.0.0.1:44840","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-02-25T02:14:58.082061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:57.585967Z","time spent":"496.038675ms","remote":"127.0.0.1:44562","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-02-25T02:14:58.082091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.64432ms","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-02-25T02:14:58.082288Z","caller":"traceutil/trace.go:171","msg":"trace[1473496547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"803.917367ms","start":"2026-02-25T02:14:57.278345Z","end":"2026-02-25T02:14:58.082262Z","steps":["trace[1473496547] 'agreement among raft nodes before linearized reading' (duration: 803.369863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:58.082501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:57.278318Z","time spent":"804.112322ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:15:12.346891Z","caller":"traceutil/trace.go:171","msg":"trace[1274513647] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"201.24399ms","start":"2026-02-25T02:15:12.145621Z","end":"2026-02-25T02:15:12.346865Z","steps":["trace[1274513647] 'process raft request' (duration: 201.097617ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:12.856298Z","caller":"traceutil/trace.go:171","msg":"trace[105431048] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:972; }","duration":"675.872467ms","start":"2026-02-25T02:15:12.180395Z","end":"2026-02-25T02:15:12.856268Z","steps":["trace[105431048] 'read index received' (duration: 166.379194ms)","trace[105431048] 'applied index is now lower than readState.Index' (duration: 509.492453ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:12.856457Z","caller":"traceutil/trace.go:171","msg":"trace[468757233] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"708.717028ms","start":"2026-02-25T02:15:12.147731Z","end":"2026-02-25T02:15:12.856448Z","steps":["trace[468757233] 'process raft request' (duration: 619.556733ms)","trace[468757233] 'compare' (duration: 88.652074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:12.856571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:12.147707Z","time spent":"708.77421ms","remote":"127.0.0.1:44840","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-02-25T02:15:12.856595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.391796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:12.856737Z","caller":"traceutil/trace.go:171","msg":"trace[235693059] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:938; }","duration":"609.623652ms","start":"2026-02-25T02:15:12.247094Z","end":"2026-02-25T02:15:12.856718Z","steps":["trace[235693059] 'agreement among raft nodes before linearized reading' (duration: 609.422177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:12.856775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:12.247025Z","time spent":"609.739694ms","remote":"127.0.0.1:39776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:15:12.856973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.32563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:15:12.856994Z","caller":"traceutil/trace.go:171","msg":"trace[94850818] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:938; }","duration":"196.370722ms","start":"2026-02-25T02:15:12.660616Z","end":"2026-02-25T02:15:12.856986Z","steps":["trace[94850818] 'agreement among raft nodes before linearized reading' (duration: 196.328311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:12.857007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.170284ms","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-02-25T02:15:12.857043Z","caller":"traceutil/trace.go:171","msg":"trace[1581671612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"177.240436ms","start":"2026-02-25T02:15:12.679792Z","end":"2026-02-25T02:15:12.857032Z","steps":["trace[1581671612] 'agreement among raft nodes before linearized reading' (duration: 177.102643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:12.857183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.788857ms","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-02-25T02:15:12.857203Z","caller":"traceutil/trace.go:171","msg":"trace[412299995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:938; }","duration":"676.845399ms","start":"2026-02-25T02:15:12.180352Z","end":"2026-02-25T02:15:12.857197Z","steps":["trace[412299995] 'agreement among raft nodes before linearized reading' (duration: 676.777807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:12.857225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:12.180339Z","time spent":"676.881469ms","remote":"127.0.0.1:44840","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-02-25T02:15:12.857302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.351075ms","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-02-25T02:15:12.85733Z","caller":"traceutil/trace.go:171","msg":"trace[1984973413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"582.383575ms","start":"2026-02-25T02:15:12.274939Z","end":"2026-02-25T02:15:12.857322Z","steps":["trace[1984973413] 'agreement among raft nodes before linearized reading' (duration: 582.229462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:12.857349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:12.274876Z","time spent":"582.468297ms","remote":"127.0.0.1:44840","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-02-25T02:15:13.046837Z","caller":"traceutil/trace.go:171","msg":"trace[503797047] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"185.780405ms","start":"2026-02-25T02:15:12.861017Z","end":"2026-02-25T02:15:13.046798Z","steps":["trace[503797047] 'process raft request' (duration: 184.17744ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.05417Z","caller":"traceutil/trace.go:171","msg":"trace[1189964972] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"190.831918ms","start":"2026-02-25T02:15:12.863306Z","end":"2026-02-25T02:15:13.054138Z","steps":["trace[1189964972] 'process raft request' (duration: 190.791317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:13.054229Z","caller":"traceutil/trace.go:171","msg":"trace[131849029] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"192.583987ms","start":"2026-02-25T02:15:12.861616Z","end":"2026-02-25T02:15:13.0542Z","steps":["trace[131849029] 'process raft request' (duration: 192.375333ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.626318Z","caller":"traceutil/trace.go:171","msg":"trace[1990020070] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"191.003392ms","start":"2026-02-25T02:15:26.435286Z","end":"2026-02-25T02:15:26.62629Z","steps":["trace[1990020070] 'process raft request' (duration: 190.219885ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:28.96723Z","caller":"traceutil/trace.go:171","msg":"trace[88951018] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"257.490492ms","start":"2026-02-25T02:15:28.709709Z","end":"2026-02-25T02:15:28.967199Z","steps":["trace[88951018] 'process raft request' (duration: 228.731122ms)","trace[88951018] 'compare' (duration: 28.166877ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:29.232798Z","caller":"traceutil/trace.go:171","msg":"trace[1885615006] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"223.753801ms","start":"2026-02-25T02:15:29.009009Z","end":"2026-02-25T02:15:29.232763Z","steps":["trace[1885615006] 'read index received' (duration: 171.58847ms)","trace[1885615006] 'applied index is now lower than readState.Index' (duration: 52.164271ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:29.232916Z","caller":"traceutil/trace.go:171","msg":"trace[715399459] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"230.797898ms","start":"2026-02-25T02:15:29.002077Z","end":"2026-02-25T02:15:29.232875Z","steps":["trace[715399459] 'process raft request' (duration: 178.633996ms)","trace[715399459] 'compare' (duration: 51.907606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:29.23309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.043798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-02-25T02:15:29.233132Z","caller":"traceutil/trace.go:171","msg":"trace[28905627] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:999; }","duration":"224.14437ms","start":"2026-02-25T02:15:29.008977Z","end":"2026-02-25T02:15:29.233121Z","steps":["trace[28905627] 'agreement among raft nodes before linearized reading' (duration: 223.996587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:29.233334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.52708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-44msk\" ","response":"range_response_count:1 size:10920"} {"level":"info","ts":"2026-02-25T02:15:29.233375Z","caller":"traceutil/trace.go:171","msg":"trace[357306947] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-44msk; range_end:; response_count:1; response_revision:999; }","duration":"154.633343ms","start":"2026-02-25T02:15:29.07873Z","end":"2026-02-25T02:15:29.233363Z","steps":["trace[357306947] 'agreement among raft nodes before linearized reading' (duration: 154.436078ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:29.60287Z","caller":"traceutil/trace.go:171","msg":"trace[1646286031] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"356.571828ms","start":"2026-02-25T02:15:29.24626Z","end":"2026-02-25T02:15:29.602832Z","steps":["trace[1646286031] 'process raft request' (duration: 356.509736ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:29.60291Z","caller":"traceutil/trace.go:171","msg":"trace[1529067345] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"363.679346ms","start":"2026-02-25T02:15:29.239167Z","end":"2026-02-25T02:15:29.602847Z","steps":["trace[1529067345] 'process raft request' (duration: 340.876718ms)","trace[1529067345] 'compare' (duration: 22.479901ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:29.603051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:29.246249Z","time spent":"356.706511ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11168,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:29.603011Z","caller":"traceutil/trace.go:171","msg":"trace[925926837] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"363.473972ms","start":"2026-02-25T02:15:29.2395Z","end":"2026-02-25T02:15:29.602974Z","steps":["trace[925926837] 'process raft request' (duration: 363.196956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:29.603156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:29.239149Z","time spent":"363.867191ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:29.603206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:29.23949Z","time spent":"363.633895ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:29.843682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.556217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:29.843937Z","caller":"traceutil/trace.go:171","msg":"trace[389894942] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"229.369917ms","start":"2026-02-25T02:15:29.614543Z","end":"2026-02-25T02:15:29.843913Z","steps":["trace[389894942] 'process raft request' (duration: 229.273085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:29.843956Z","caller":"traceutil/trace.go:171","msg":"trace[998305623] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"229.756075ms","start":"2026-02-25T02:15:29.61414Z","end":"2026-02-25T02:15:29.843896Z","steps":["trace[998305623] 'process raft request' (duration: 111.875601ms)","trace[998305623] 'compare' (duration: 117.353183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:31.253611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.293201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:31.253774Z","caller":"traceutil/trace.go:171","msg":"trace[1723577166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"656.486914ms","start":"2026-02-25T02:15:30.597219Z","end":"2026-02-25T02:15:31.253706Z","steps":["trace[1723577166] 'range keys from in-memory index tree' (duration: 656.189738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.253823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.597196Z","time spent":"656.615718ms","remote":"127.0.0.1:44562","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-02-25T02:15:31.254832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.652419ms","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-02-25T02:15:31.254903Z","caller":"traceutil/trace.go:171","msg":"trace[1464546266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"612.751571ms","start":"2026-02-25T02:15:30.64211Z","end":"2026-02-25T02:15:31.254862Z","steps":["trace[1464546266] 'range keys from in-memory index tree' (duration: 611.862582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.254937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.642085Z","time spent":"612.843613ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:15:31.26139Z","caller":"traceutil/trace.go:171","msg":"trace[655772818] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"256.062661ms","start":"2026-02-25T02:15:31.005296Z","end":"2026-02-25T02:15:31.261359Z","steps":["trace[655772818] 'read index received' (duration: 255.923618ms)","trace[655772818] 'applied index is now lower than readState.Index' (duration: 137.853µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:31.261451Z","caller":"traceutil/trace.go:171","msg":"trace[593633964] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"301.689996ms","start":"2026-02-25T02:15:30.95974Z","end":"2026-02-25T02:15:31.26143Z","steps":["trace[593633964] 'process raft request' (duration: 301.434861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.261596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:30.959709Z","time spent":"301.790899ms","remote":"127.0.0.1:44840","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-02-25T02:15:31.261624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.329756ms","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-02-25T02:15:31.261658Z","caller":"traceutil/trace.go:171","msg":"trace[45474303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"256.420268ms","start":"2026-02-25T02:15:31.005227Z","end":"2026-02-25T02:15:31.261648Z","steps":["trace[45474303] 'agreement among raft nodes before linearized reading' (duration: 256.264315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.495769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.701179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:31.496519Z","caller":"traceutil/trace.go:171","msg":"trace[1802646028] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"235.029162ms","start":"2026-02-25T02:15:31.261469Z","end":"2026-02-25T02:15:31.496498Z","steps":["trace[1802646028] 'read index received' (duration: 94.447953ms)","trace[1802646028] 'applied index is now lower than readState.Index' (duration: 140.580029ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:31.496605Z","caller":"traceutil/trace.go:171","msg":"trace[1150833994] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"237.406805ms","start":"2026-02-25T02:15:31.259185Z","end":"2026-02-25T02:15:31.496592Z","steps":["trace[1150833994] 'process raft request' (duration: 96.773655ms)","trace[1150833994] 'compare' (duration: 139.557416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:31.496868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.00444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:31.496906Z","caller":"traceutil/trace.go:171","msg":"trace[546583422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"239.067622ms","start":"2026-02-25T02:15:31.257828Z","end":"2026-02-25T02:15:31.496896Z","steps":["trace[546583422] 'agreement among raft nodes before linearized reading' (duration: 239.00251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:31.499234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.849303ms","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-02-25T02:15:31.499357Z","caller":"traceutil/trace.go:171","msg":"trace[772184655] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"234.04908ms","start":"2026-02-25T02:15:31.264916Z","end":"2026-02-25T02:15:31.498965Z","steps":["trace[772184655] 'process raft request' (duration: 233.777764ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:31.49954Z","caller":"traceutil/trace.go:171","msg":"trace[1363503991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"193.017807ms","start":"2026-02-25T02:15:31.306302Z","end":"2026-02-25T02:15:31.49932Z","steps":["trace[1363503991] 'agreement among raft nodes before linearized reading' (duration: 192.542156ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:31.635478Z","caller":"traceutil/trace.go:171","msg":"trace[2020782476] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"130.372583ms","start":"2026-02-25T02:15:31.505064Z","end":"2026-02-25T02:15:31.635437Z","steps":["trace[2020782476] 'process raft request' (duration: 123.543381ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:33.873503Z","caller":"traceutil/trace.go:171","msg":"trace[224615916] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"350.970432ms","start":"2026-02-25T02:15:33.522508Z","end":"2026-02-25T02:15:33.873479Z","steps":["trace[224615916] 'process raft request' (duration: 350.752368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:33.87368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.52249Z","time spent":"351.101896ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:34.559757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.303872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:34.560039Z","caller":"traceutil/trace.go:171","msg":"trace[49322939] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.037472056s","start":"2026-02-25T02:15:33.522538Z","end":"2026-02-25T02:15:34.56001Z","steps":["trace[49322939] 'process raft request' (duration: 866.855588ms)","trace[49322939] 'compare' (duration: 169.614585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:34.560534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.52249Z","time spent":"1.037633579s","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:34.56095Z","caller":"traceutil/trace.go:171","msg":"trace[1178706546] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"472.516849ms","start":"2026-02-25T02:15:34.08842Z","end":"2026-02-25T02:15:34.560937Z","steps":["trace[1178706546] 'process raft request' (duration: 471.415135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:34.561027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:34.088404Z","time spent":"472.58429ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:34.561333Z","caller":"traceutil/trace.go:171","msg":"trace[1220965480] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"968.230854ms","start":"2026-02-25T02:15:33.592611Z","end":"2026-02-25T02:15:34.560842Z","steps":["trace[1220965480] 'read index received' (duration: 280.581066ms)","trace[1220965480] 'applied index is now lower than readState.Index' (duration: 687.648578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:34.678621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.728544ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:15:34.678729Z","caller":"traceutil/trace.go:171","msg":"trace[1482754877] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"117.342942ms","start":"2026-02-25T02:15:34.561368Z","end":"2026-02-25T02:15:34.678711Z","steps":["trace[1482754877] 'read index received' (duration: 50.301µs)","trace[1482754877] 'applied index is now lower than readState.Index' (duration: 117.290881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:34.679156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086535788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:34.679198Z","caller":"traceutil/trace.go:171","msg":"trace[1507913170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"1.08662374s","start":"2026-02-25T02:15:33.592563Z","end":"2026-02-25T02:15:34.679187Z","steps":["trace[1507913170] 'agreement among raft nodes before linearized reading' (duration: 968.841888ms)","trace[1507913170] 'range keys from in-memory index tree' (duration: 117.716071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:34.679237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.592543Z","time spent":"1.086686641s","remote":"127.0.0.1:44562","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-02-25T02:15:34.679675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.485077ms","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-02-25T02:15:34.679715Z","caller":"traceutil/trace.go:171","msg":"trace[1745423067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"993.552507ms","start":"2026-02-25T02:15:33.686154Z","end":"2026-02-25T02:15:34.679707Z","steps":["trace[1745423067] 'agreement among raft nodes before linearized reading' (duration: 993.457205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:34.679742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.686141Z","time spent":"993.594839ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:15:34.679919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005014543s","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-02-25T02:15:34.679952Z","caller":"traceutil/trace.go:171","msg":"trace[1743895720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"1.005078255s","start":"2026-02-25T02:15:33.674866Z","end":"2026-02-25T02:15:34.679944Z","steps":["trace[1743895720] 'agreement among raft nodes before linearized reading' (duration: 1.004996743s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:34.679982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.674849Z","time spent":"1.005127366s","remote":"127.0.0.1:44840","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-02-25T02:15:34.680643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033496227s","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-02-25T02:15:34.680733Z","caller":"traceutil/trace.go:171","msg":"trace[897613851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"1.033659831s","start":"2026-02-25T02:15:33.647054Z","end":"2026-02-25T02:15:34.680713Z","steps":["trace[897613851] 'agreement among raft nodes before linearized reading' (duration: 1.031705127s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:34.680787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.647028Z","time spent":"1.033750143s","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T02:15:34.80372Z","caller":"traceutil/trace.go:171","msg":"trace[162687469] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"176.149621ms","start":"2026-02-25T02:15:34.627541Z","end":"2026-02-25T02:15:34.803691Z","steps":["trace[162687469] 'process raft request' (duration: 175.963577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:34.803844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.689771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:34.803895Z","caller":"traceutil/trace.go:171","msg":"trace[1685553521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"122.791764ms","start":"2026-02-25T02:15:34.681089Z","end":"2026-02-25T02:15:34.803881Z","steps":["trace[1685553521] 'agreement among raft nodes before linearized reading' (duration: 122.670981ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:34.803701Z","caller":"traceutil/trace.go:171","msg":"trace[1547490414] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"122.542918ms","start":"2026-02-25T02:15:34.681129Z","end":"2026-02-25T02:15:34.803672Z","steps":["trace[1547490414] 'read index received' (duration: 122.377534ms)","trace[1547490414] 'applied index is now lower than readState.Index' (duration: 164.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:34.81413Z","caller":"traceutil/trace.go:171","msg":"trace[819041051] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"127.939529ms","start":"2026-02-25T02:15:34.686171Z","end":"2026-02-25T02:15:34.81411Z","steps":["trace[819041051] 'process raft request' (duration: 127.867307ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:34.814242Z","caller":"traceutil/trace.go:171","msg":"trace[1220898012] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"129.107424ms","start":"2026-02-25T02:15:34.685125Z","end":"2026-02-25T02:15:34.814233Z","steps":["trace[1220898012] 'process raft request' (duration: 128.789317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:36.105495Z","caller":"traceutil/trace.go:171","msg":"trace[1355887312] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"227.414732ms","start":"2026-02-25T02:15:35.878061Z","end":"2026-02-25T02:15:36.105475Z","steps":["trace[1355887312] 'read index received' (duration: 227.290889ms)","trace[1355887312] 'applied index is now lower than readState.Index' (duration: 123.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:36.105587Z","caller":"traceutil/trace.go:171","msg":"trace[82678515] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"253.576135ms","start":"2026-02-25T02:15:35.852004Z","end":"2026-02-25T02:15:36.10558Z","steps":["trace[82678515] 'process raft request' (duration: 253.281919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:36.106062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.869028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:36.106102Z","caller":"traceutil/trace.go:171","msg":"trace[1228034293] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1020; }","duration":"145.952759ms","start":"2026-02-25T02:15:35.96014Z","end":"2026-02-25T02:15:36.106093Z","steps":["trace[1228034293] 'agreement among raft nodes before linearized reading' (duration: 145.814706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:36.106363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.300282ms","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-02-25T02:15:36.106392Z","caller":"traceutil/trace.go:171","msg":"trace[121059144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"228.363143ms","start":"2026-02-25T02:15:35.878022Z","end":"2026-02-25T02:15:36.106385Z","steps":["trace[121059144] 'agreement among raft nodes before linearized reading' (duration: 228.274201ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:36.677991Z","caller":"traceutil/trace.go:171","msg":"trace[351032316] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"567.32275ms","start":"2026-02-25T02:15:36.110645Z","end":"2026-02-25T02:15:36.677968Z","steps":["trace[351032316] 'process raft request' (duration: 567.180897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:36.678207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.110622Z","time spent":"567.482223ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:15:36.906998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.720863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:36.907177Z","caller":"traceutil/trace.go:171","msg":"trace[44976832] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"211.809885ms","start":"2026-02-25T02:15:36.695354Z","end":"2026-02-25T02:15:36.907164Z","steps":["trace[44976832] 'process raft request' (duration: 211.778465ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:36.90712Z","caller":"traceutil/trace.go:171","msg":"trace[102743689] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"795.130361ms","start":"2026-02-25T02:15:36.111955Z","end":"2026-02-25T02:15:36.907085Z","steps":["trace[102743689] 'process raft request' (duration: 689.209253ms)","trace[102743689] 'compare' (duration: 105.627551ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:36.907419Z","caller":"traceutil/trace.go:171","msg":"trace[492547182] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"340.670464ms","start":"2026-02-25T02:15:36.56674Z","end":"2026-02-25T02:15:36.90741Z","steps":["trace[492547182] 'read index received' (duration: 111.190995ms)","trace[492547182] 'applied index is now lower than readState.Index' (duration: 229.478319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:36.907425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.111931Z","time spent":"795.436478ms","remote":"127.0.0.1:44840","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-02-25T02:15:36.907471Z","caller":"traceutil/trace.go:171","msg":"trace[985559923] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"223.69896ms","start":"2026-02-25T02:15:36.683767Z","end":"2026-02-25T02:15:36.907466Z","steps":["trace[985559923] 'process raft request' (duration: 223.309231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:36.907683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.424287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:36.907784Z","caller":"traceutil/trace.go:171","msg":"trace[1348921825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"322.564181ms","start":"2026-02-25T02:15:36.585196Z","end":"2026-02-25T02:15:36.907761Z","steps":["trace[1348921825] 'agreement among raft nodes before linearized reading' (duration: 322.391747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:36.907785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.027201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T02:15:36.907827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.191201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:15:36.907842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.58518Z","time spent":"322.648022ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:15:36.907873Z","caller":"traceutil/trace.go:171","msg":"trace[1685933904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"341.167305ms","start":"2026-02-25T02:15:36.566687Z","end":"2026-02-25T02:15:36.907854Z","steps":["trace[1685933904] 'agreement among raft nodes before linearized reading' (duration: 340.848068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:36.907886Z","caller":"traceutil/trace.go:171","msg":"trace[1827629752] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1024; }","duration":"193.290273ms","start":"2026-02-25T02:15:36.714577Z","end":"2026-02-25T02:15:36.907868Z","steps":["trace[1827629752] 'agreement among raft nodes before linearized reading' (duration: 193.192491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:36.907926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.566667Z","time spent":"341.249596ms","remote":"127.0.0.1:44840","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-02-25T02:15:36.9079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.874718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-25T02:15:36.907973Z","caller":"traceutil/trace.go:171","msg":"trace[443527744] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1024; }","duration":"225.984641ms","start":"2026-02-25T02:15:36.681973Z","end":"2026-02-25T02:15:36.907958Z","steps":["trace[443527744] 'agreement among raft nodes before linearized reading' (duration: 225.807987ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:37.337983Z","caller":"traceutil/trace.go:171","msg":"trace[2027062702] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"422.091987ms","start":"2026-02-25T02:15:36.915853Z","end":"2026-02-25T02:15:37.337945Z","steps":["trace[2027062702] 'process raft request' (duration: 422.051966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:37.338126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.915844Z","time spent":"422.211649ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:37.338241Z","caller":"traceutil/trace.go:171","msg":"trace[1531441284] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"425.074764ms","start":"2026-02-25T02:15:36.913136Z","end":"2026-02-25T02:15:37.33821Z","steps":["trace[1531441284] 'process raft request' (duration: 424.643714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:37.338243Z","caller":"traceutil/trace.go:171","msg":"trace[1133838085] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"425.434872ms","start":"2026-02-25T02:15:36.91278Z","end":"2026-02-25T02:15:37.338215Z","steps":["trace[1133838085] 'process raft request' (duration: 424.837068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:37.338363Z","caller":"traceutil/trace.go:171","msg":"trace[1894302104] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"424.881589ms","start":"2026-02-25T02:15:36.913466Z","end":"2026-02-25T02:15:37.338347Z","steps":["trace[1894302104] 'process raft request' (duration: 424.368667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:37.338385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.912759Z","time spent":"425.555674ms","remote":"127.0.0.1:44636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:15:37.338394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.913127Z","time spent":"425.180506ms","remote":"127.0.0.1:44840","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-02-25T02:15:37.338437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.913454Z","time spent":"424.94311ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:37.338533Z","caller":"traceutil/trace.go:171","msg":"trace[619816293] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"422.873164ms","start":"2026-02-25T02:15:36.915644Z","end":"2026-02-25T02:15:37.338518Z","steps":["trace[619816293] 'process raft request' (duration: 422.22781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:37.3386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:36.91563Z","time spent":"422.938676ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:45.624265Z","caller":"traceutil/trace.go:171","msg":"trace[370055167] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"237.6661ms","start":"2026-02-25T02:15:45.386561Z","end":"2026-02-25T02:15:45.624227Z","steps":["trace[370055167] 'process raft request' (duration: 237.359714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:45.624454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.160292ms","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-02-25T02:15:45.624559Z","caller":"traceutil/trace.go:171","msg":"trace[891621387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"238.403998ms","start":"2026-02-25T02:15:45.386144Z","end":"2026-02-25T02:15:45.624548Z","steps":["trace[891621387] 'agreement among raft nodes before linearized reading' (duration: 238.0809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:45.624562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.326205ms","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-02-25T02:15:45.624656Z","caller":"traceutil/trace.go:171","msg":"trace[1781451994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"238.457919ms","start":"2026-02-25T02:15:45.386177Z","end":"2026-02-25T02:15:45.624635Z","steps":["trace[1781451994] 'agreement among raft nodes before linearized reading' (duration: 238.142632ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:45.624256Z","caller":"traceutil/trace.go:171","msg":"trace[1444515952] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"236.316991ms","start":"2026-02-25T02:15:45.387841Z","end":"2026-02-25T02:15:45.624158Z","steps":["trace[1444515952] 'read index received' (duration: 236.077826ms)","trace[1444515952] 'applied index is now lower than readState.Index' (duration: 237.945µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:46.235777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309612686245223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:46.92664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.916293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:46.926802Z","caller":"traceutil/trace.go:171","msg":"trace[536901215] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.29777156s","start":"2026-02-25T02:15:45.629005Z","end":"2026-02-25T02:15:46.926777Z","steps":["trace[536901215] 'process raft request' (duration: 1.003618292s)","trace[536901215] 'compare' (duration: 293.737519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:46.926889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:45.62899Z","time spent":"1.297856302s","remote":"127.0.0.1:44840","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-02-25T02:15:46.927452Z","caller":"traceutil/trace.go:171","msg":"trace[858160273] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1129; }","duration":"1.191887523s","start":"2026-02-25T02:15:45.735538Z","end":"2026-02-25T02:15:46.927426Z","steps":["trace[858160273] 'read index received' (duration: 897.163582ms)","trace[858160273] 'applied index is now lower than readState.Index' (duration: 294.722661ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:46.92779Z","caller":"traceutil/trace.go:171","msg":"trace[1443847329] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"1.298429114s","start":"2026-02-25T02:15:45.629348Z","end":"2026-02-25T02:15:46.927777Z","steps":["trace[1443847329] 'process raft request' (duration: 1.297961644s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.927895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:45.629327Z","time spent":"1.298500907s","remote":"127.0.0.1:44840","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-02-25T02:15:46.927997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168066993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:46.928061Z","caller":"traceutil/trace.go:171","msg":"trace[796172252] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1088; }","duration":"1.168160524s","start":"2026-02-25T02:15:45.759885Z","end":"2026-02-25T02:15:46.928045Z","steps":["trace[796172252] 'agreement among raft nodes before linearized reading' (duration: 1.168056492s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.92811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:45.759854Z","time spent":"1.168242607s","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:15:46.928144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.958582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:46.928204Z","caller":"traceutil/trace.go:171","msg":"trace[1999351956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"342.053184ms","start":"2026-02-25T02:15:46.58613Z","end":"2026-02-25T02:15:46.928183Z","steps":["trace[1999351956] 'agreement among raft nodes before linearized reading' (duration: 341.945002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.928237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.811761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-25T02:15:46.928303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:46.586078Z","time spent":"342.210338ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:15:46.928379Z","caller":"traceutil/trace.go:171","msg":"trace[374694628] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1088; }","duration":"439.961134ms","start":"2026-02-25T02:15:46.488342Z","end":"2026-02-25T02:15:46.928303Z","steps":["trace[374694628] 'agreement among raft nodes before linearized reading' (duration: 439.77388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.928373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192835074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-25T02:15:46.928386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.060799ms","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-02-25T02:15:46.928421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:46.488319Z","time spent":"440.092487ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T02:15:46.928442Z","caller":"traceutil/trace.go:171","msg":"trace[192035965] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1088; }","duration":"1.192941037s","start":"2026-02-25T02:15:45.735481Z","end":"2026-02-25T02:15:46.928422Z","steps":["trace[192035965] 'agreement among raft nodes before linearized reading' (duration: 1.192687731s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:46.928451Z","caller":"traceutil/trace.go:171","msg":"trace[1658550009] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1088; }","duration":"680.166301ms","start":"2026-02-25T02:15:46.248271Z","end":"2026-02-25T02:15:46.928437Z","steps":["trace[1658550009] 'agreement among raft nodes before linearized reading' (duration: 680.075189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.928498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:46.248251Z","time spent":"680.234643ms","remote":"127.0.0.1:44774","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-02-25T02:15:46.928497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:45.735461Z","time spent":"1.193021918s","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-25T02:16:07.519882Z","caller":"traceutil/trace.go:171","msg":"trace[1729147546] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"497.652089ms","start":"2026-02-25T02:16:07.022186Z","end":"2026-02-25T02:16:07.519838Z","steps":["trace[1729147546] 'read index received' (duration: 497.325042ms)","trace[1729147546] 'applied index is now lower than readState.Index' (duration: 325.627µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:07.519972Z","caller":"traceutil/trace.go:171","msg":"trace[1774668252] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"546.293001ms","start":"2026-02-25T02:16:06.973655Z","end":"2026-02-25T02:16:07.519948Z","steps":["trace[1774668252] 'process raft request' (duration: 545.983875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.520166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.964726ms","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-02-25T02:16:07.520278Z","caller":"traceutil/trace.go:171","msg":"trace[634288059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"498.121769ms","start":"2026-02-25T02:16:07.022128Z","end":"2026-02-25T02:16:07.52025Z","steps":["trace[634288059] 'agreement among raft nodes before linearized reading' (duration: 497.846803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.52032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.022104Z","time spent":"498.199861ms","remote":"127.0.0.1:44840","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-02-25T02:16:07.520388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.606229ms","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-02-25T02:16:07.520481Z","caller":"traceutil/trace.go:171","msg":"trace[86770826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"440.770733ms","start":"2026-02-25T02:16:07.079689Z","end":"2026-02-25T02:16:07.52046Z","steps":["trace[86770826] 'agreement among raft nodes before linearized reading' (duration: 440.440726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.520539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.907445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-25T02:16:07.520578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.300064ms","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-02-25T02:16:07.520628Z","caller":"traceutil/trace.go:171","msg":"trace[270643120] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1170; }","duration":"127.056289ms","start":"2026-02-25T02:16:07.39355Z","end":"2026-02-25T02:16:07.520606Z","steps":["trace[270643120] 'agreement among raft nodes before linearized reading' (duration: 126.689581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.520617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.172966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T02:16:07.520619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.77677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-25T02:16:07.520657Z","caller":"traceutil/trace.go:171","msg":"trace[761623444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"388.402657ms","start":"2026-02-25T02:16:07.132231Z","end":"2026-02-25T02:16:07.520634Z","steps":["trace[761623444] 'agreement among raft nodes before linearized reading' (duration: 387.871105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:07.520693Z","caller":"traceutil/trace.go:171","msg":"trace[1918056138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"474.290049ms","start":"2026-02-25T02:16:07.046384Z","end":"2026-02-25T02:16:07.520674Z","steps":["trace[1918056138] 'agreement among raft nodes before linearized reading' (duration: 473.855799ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:07.520699Z","caller":"traceutil/trace.go:171","msg":"trace[850115326] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1170; }","duration":"496.897082ms","start":"2026-02-25T02:16:07.023785Z","end":"2026-02-25T02:16:07.520682Z","steps":["trace[850115326] 'agreement among raft nodes before linearized reading' (duration: 496.714548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.520704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.132218Z","time spent":"388.473098ms","remote":"127.0.0.1:44840","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-02-25T02:16:07.52073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.023757Z","time spent":"496.966364ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T02:16:07.520731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.046362Z","time spent":"474.359431ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:07.520552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.079665Z","time spent":"440.870625ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:07.520383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.973623Z","time spent":"546.405084ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:42.142001Z","caller":"traceutil/trace.go:171","msg":"trace[1139022040] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"455.361073ms","start":"2026-02-25T02:16:41.686614Z","end":"2026-02-25T02:16:42.141975Z","steps":["trace[1139022040] 'process raft request' (duration: 455.255481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.142129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:41.686594Z","time spent":"455.471055ms","remote":"127.0.0.1:44840","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-02-25T02:16:42.147037Z","caller":"traceutil/trace.go:171","msg":"trace[1751521539] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"451.874755ms","start":"2026-02-25T02:16:41.695087Z","end":"2026-02-25T02:16:42.146962Z","steps":["trace[1751521539] 'read index received' (duration: 446.907975ms)","trace[1751521539] 'applied index is now lower than readState.Index' (duration: 4.96565ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:42.147103Z","caller":"traceutil/trace.go:171","msg":"trace[248399289] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"457.997882ms","start":"2026-02-25T02:16:41.689084Z","end":"2026-02-25T02:16:42.147082Z","steps":["trace[248399289] 'process raft request' (duration: 457.752116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.147322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.232531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T02:16:42.147279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:41.689062Z","time spent":"458.086633ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:42.147383Z","caller":"traceutil/trace.go:171","msg":"trace[1539966939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"442.322232ms","start":"2026-02-25T02:16:41.705048Z","end":"2026-02-25T02:16:42.14737Z","steps":["trace[1539966939] 'agreement among raft nodes before linearized reading' (duration: 442.156028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.147422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:41.705037Z","time spent":"442.378003ms","remote":"127.0.0.1:44840","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-02-25T02:16:42.147449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.714537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:16:42.147339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.599296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:42.147503Z","caller":"traceutil/trace.go:171","msg":"trace[2009278391] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1311; }","duration":"146.788849ms","start":"2026-02-25T02:16:42.000694Z","end":"2026-02-25T02:16:42.147483Z","steps":["trace[2009278391] 'agreement among raft nodes before linearized reading' (duration: 146.697007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.147512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.535993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-25T02:16:42.147526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.805141ms","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-02-25T02:16:42.147566Z","caller":"traceutil/trace.go:171","msg":"trace[1321412764] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1311; }","duration":"440.625895ms","start":"2026-02-25T02:16:41.706922Z","end":"2026-02-25T02:16:42.147548Z","steps":["trace[1321412764] 'agreement among raft nodes before linearized reading' (duration: 440.493712ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:42.147585Z","caller":"traceutil/trace.go:171","msg":"trace[1092045920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"274.892043ms","start":"2026-02-25T02:16:41.872677Z","end":"2026-02-25T02:16:42.147569Z","steps":["trace[1092045920] 'agreement among raft nodes before linearized reading' (duration: 274.686519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.147607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:41.706901Z","time spent":"440.697586ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-25T02:16:42.147562Z","caller":"traceutil/trace.go:171","msg":"trace[1032035821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"274.832652ms","start":"2026-02-25T02:16:41.87268Z","end":"2026-02-25T02:16:42.147513Z","steps":["trace[1032035821] 'agreement among raft nodes before linearized reading' (duration: 274.551776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.147323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.210232ms","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-02-25T02:16:42.148002Z","caller":"traceutil/trace.go:171","msg":"trace[283721895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"452.871538ms","start":"2026-02-25T02:16:41.695065Z","end":"2026-02-25T02:16:42.147936Z","steps":["trace[283721895] 'agreement among raft nodes before linearized reading' (duration: 452.072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:42.148079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:41.695056Z","time spent":"453.00822ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:17:02.456403Z","caller":"traceutil/trace.go:171","msg":"trace[983095414] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"204.380612ms","start":"2026-02-25T02:17:02.251996Z","end":"2026-02-25T02:17:02.456377Z","steps":["trace[983095414] 'process raft request' (duration: 204.158627ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:02.457258Z","caller":"traceutil/trace.go:171","msg":"trace[475574979] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1449; }","duration":"204.212538ms","start":"2026-02-25T02:17:02.253022Z","end":"2026-02-25T02:17:02.457234Z","steps":["trace[475574979] 'read index received' (duration: 203.125864ms)","trace[475574979] 'applied index is now lower than readState.Index' (duration: 1.085694ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:17:02.457343Z","caller":"traceutil/trace.go:171","msg":"trace[723642571] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"204.958225ms","start":"2026-02-25T02:17:02.252348Z","end":"2026-02-25T02:17:02.457306Z","steps":["trace[723642571] 'process raft request' (duration: 204.7409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:02.457479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.718868ms","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-02-25T02:17:02.457519Z","caller":"traceutil/trace.go:171","msg":"trace[646508715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1393; }","duration":"203.790689ms","start":"2026-02-25T02:17:02.253714Z","end":"2026-02-25T02:17:02.457505Z","steps":["trace[646508715] 'agreement among raft nodes before linearized reading' (duration: 203.641636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:02.457629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.597458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-25T02:17:02.457691Z","caller":"traceutil/trace.go:171","msg":"trace[119295778] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1393; }","duration":"200.67896ms","start":"2026-02-25T02:17:02.256994Z","end":"2026-02-25T02:17:02.457673Z","steps":["trace[119295778] 'agreement among raft nodes before linearized reading' (duration: 200.505876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:02.457802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.429594ms","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-02-25T02:17:02.457837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.015244ms","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-02-25T02:17:02.457888Z","caller":"traceutil/trace.go:171","msg":"trace[325428616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1393; }","duration":"204.881163ms","start":"2026-02-25T02:17:02.252988Z","end":"2026-02-25T02:17:02.45787Z","steps":["trace[325428616] 'agreement among raft nodes before linearized reading' (duration: 204.344261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:02.45791Z","caller":"traceutil/trace.go:171","msg":"trace[1165348099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1393; }","duration":"204.119527ms","start":"2026-02-25T02:17:02.25377Z","end":"2026-02-25T02:17:02.45789Z","steps":["trace[1165348099] 'agreement among raft nodes before linearized reading' (duration: 203.941203ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:49.718049Z","caller":"traceutil/trace.go:171","msg":"trace[537392985] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"137.008108ms","start":"2026-02-25T02:18:49.581017Z","end":"2026-02-25T02:18:49.718025Z","steps":["trace[537392985] 'process raft request' (duration: 136.872108ms)"],"step_count":1}