{"level":"warn","ts":"2026-03-06T00:23:51.765269Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:23:51.765381Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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://162.253.55.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-06T00:23:51.765453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:23:51.765465Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-03-06T00:23:51.765486Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T00:23:51.766241Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-03-06T00:23:51.766328Z","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://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-06T00:23:51.771967Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.414919ms"} {"level":"info","ts":"2026-03-06T00:23:51.784582Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-03-06T00:23:51.7847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T00:23:51.784729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-03-06T00:23:51.784741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T00:23:51.784751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-03-06T00:23:51.784799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-03-06T00:23:51.792722Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T00:23:51.794271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T00:23:51.796938Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-06T00:23:51.799267Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T00:23:51.799516Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T00:23:51.80115Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T00:23:51.801242Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T00:23:51.801253Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T00:23:51.80166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-03-06T00:23:51.802801Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-03-06T00:23:51.806431Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T00:23:51.806596Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-03-06T00:23:51.80683Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-03-06T00:23:51.806995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T00:23:51.807054Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T00:23:52.785825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T00:23:52.786451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T00:23:52.787608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-03-06T00:23:52.78764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-03-06T00:23:52.78765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-03-06T00:23:52.787662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-03-06T00:23:52.78768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-03-06T00:23:52.789603Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:23:52.792263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:23:52.792264Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T00:23:52.792316Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:23:52.792687Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T00:23:52.792757Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T00:23:52.794325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-03-06T00:23:52.795187Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T00:23:52.799033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:23:52.799166Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:23:52.799223Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:23:55.682678Z","caller":"traceutil/trace.go:171","msg":"trace[559729012] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"450.996905ms","start":"2026-03-06T00:23:55.231639Z","end":"2026-03-06T00:23:55.682636Z","steps":["trace[559729012] 'read index received' (duration: 405.532459ms)","trace[559729012] 'applied index is now lower than readState.Index' (duration: 45.462676ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:23:55.682944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.260692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:23:55.683079Z","caller":"traceutil/trace.go:171","msg":"trace[883706462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"451.463466ms","start":"2026-03-06T00:23:55.231588Z","end":"2026-03-06T00:23:55.683052Z","steps":["trace[883706462] 'agreement among raft nodes before linearized reading' (duration: 451.20332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:23:55.683122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:55.23157Z","time spent":"451.542469ms","remote":"127.0.0.1:44742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T00:23:55.682896Z","caller":"traceutil/trace.go:171","msg":"trace[2116949410] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"506.683176ms","start":"2026-03-06T00:23:55.17615Z","end":"2026-03-06T00:23:55.682833Z","steps":["trace[2116949410] 'process raft request' (duration: 460.965894ms)","trace[2116949410] 'compare' (duration: 45.342183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:23:55.684128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:55.176133Z","time spent":"507.247409ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:23:55.753563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:55.34495Z","time spent":"408.586992ms","remote":"127.0.0.1:44816","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-06T00:23:56.32267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.671944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:23:56.322889Z","caller":"traceutil/trace.go:171","msg":"trace[1123993686] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:82; }","duration":"514.168985ms","start":"2026-03-06T00:23:55.8087Z","end":"2026-03-06T00:23:56.322869Z","steps":["trace[1123993686] 'read index received' (duration: 208.039161ms)","trace[1123993686] 'applied index is now lower than readState.Index' (duration: 306.128434ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:23:56.323041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.332669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:23:56.323078Z","caller":"traceutil/trace.go:171","msg":"trace[695040598] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:79; }","duration":"514.410811ms","start":"2026-03-06T00:23:55.808657Z","end":"2026-03-06T00:23:56.323068Z","steps":["trace[695040598] 'agreement among raft nodes before linearized reading' (duration: 514.314509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:23:56.323136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:55.808645Z","time spent":"514.481522ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-06T00:23:56.323198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.246439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:23:56.323221Z","caller":"traceutil/trace.go:171","msg":"trace[24193984] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"568.110024ms","start":"2026-03-06T00:23:55.755041Z","end":"2026-03-06T00:23:56.323151Z","steps":["trace[24193984] 'process raft request' (duration: 261.680512ms)","trace[24193984] 'compare' (duration: 305.50934ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:23:56.32332Z","caller":"traceutil/trace.go:171","msg":"trace[1754865252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"191.435034ms","start":"2026-03-06T00:23:56.131846Z","end":"2026-03-06T00:23:56.323281Z","steps":["trace[1754865252] 'agreement among raft nodes before linearized reading' (duration: 191.229379ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:23:56.323261Z","caller":"traceutil/trace.go:171","msg":"trace[1937028082] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"565.03622ms","start":"2026-03-06T00:23:55.758007Z","end":"2026-03-06T00:23:56.323044Z","steps":["trace[1937028082] 'process raft request' (duration: 564.802525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:23:56.32345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:55.755013Z","time spent":"568.323218ms","remote":"127.0.0.1:44816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:23:56.323669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:55.757973Z","time spent":"565.560753ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:23:56.891212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.496305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:23:56.891313Z","caller":"traceutil/trace.go:171","msg":"trace[1587458681] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"562.380446ms","start":"2026-03-06T00:23:56.328917Z","end":"2026-03-06T00:23:56.891297Z","steps":["trace[1587458681] 'process raft request' (duration: 357.736337ms)","trace[1587458681] 'compare' (duration: 204.381063ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:23:56.891355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:56.328895Z","time spent":"562.443448ms","remote":"127.0.0.1:44816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:23:56.896275Z","caller":"traceutil/trace.go:171","msg":"trace[469439592] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:84; }","duration":"441.377184ms","start":"2026-03-06T00:23:56.454873Z","end":"2026-03-06T00:23:56.89625Z","steps":["trace[469439592] 'read index received' (duration: 232.020983ms)","trace[469439592] 'applied index is now lower than readState.Index' (duration: 209.355041ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:23:56.896555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.670682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:23:56.89662Z","caller":"traceutil/trace.go:171","msg":"trace[1539604986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:82; }","duration":"441.825696ms","start":"2026-03-06T00:23:56.454785Z","end":"2026-03-06T00:23:56.896611Z","steps":["trace[1539604986] 'agreement among raft nodes before linearized reading' (duration: 441.640302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:23:56.896682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:56.454756Z","time spent":"441.917568ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-06T00:23:56.89679Z","caller":"traceutil/trace.go:171","msg":"trace[328089753] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"566.482734ms","start":"2026-03-06T00:23:56.330252Z","end":"2026-03-06T00:23:56.896735Z","steps":["trace[328089753] 'process raft request' (duration: 565.869049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:23:56.896946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:56.330234Z","time spent":"566.640018ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:23:56.897076Z","caller":"traceutil/trace.go:171","msg":"trace[372901401] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"564.251201ms","start":"2026-03-06T00:23:56.332814Z","end":"2026-03-06T00:23:56.897065Z","steps":["trace[372901401] 'process raft request' (duration: 563.399201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:23:56.897168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:23:56.332787Z","time spent":"564.332143ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:24:25.248064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.492583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:24:25.248144Z","caller":"traceutil/trace.go:171","msg":"trace[2125113934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"169.614106ms","start":"2026-03-06T00:24:25.078514Z","end":"2026-03-06T00:24:25.248128Z","steps":["trace[2125113934] 'range keys from in-memory index tree' (duration: 169.414121ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:31.856065Z","caller":"traceutil/trace.go:171","msg":"trace[1428485454] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"282.562774ms","start":"2026-03-06T00:24:31.573479Z","end":"2026-03-06T00:24:31.856042Z","steps":["trace[1428485454] 'read index received' (duration: 282.41618ms)","trace[1428485454] 'applied index is now lower than readState.Index' (duration: 145.364µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:31.85654Z","caller":"traceutil/trace.go:171","msg":"trace[1307711564] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"298.213943ms","start":"2026-03-06T00:24:31.558292Z","end":"2026-03-06T00:24:31.856506Z","steps":["trace[1307711564] 'process raft request' (duration: 297.613498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:31.856633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.142089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbr9x\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-06T00:24:31.856677Z","caller":"traceutil/trace.go:171","msg":"trace[1638747546] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbr9x; range_end:; response_count:1; response_revision:531; }","duration":"283.23633ms","start":"2026-03-06T00:24:31.573429Z","end":"2026-03-06T00:24:31.856665Z","steps":["trace[1638747546] 'agreement among raft nodes before linearized reading' (duration: 283.088127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:31.857233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.470593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-06T00:24:31.857317Z","caller":"traceutil/trace.go:171","msg":"trace[1000519251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"117.607597ms","start":"2026-03-06T00:24:31.73969Z","end":"2026-03-06T00:24:31.857298Z","steps":["trace[1000519251] 'agreement among raft nodes before linearized reading' (duration: 116.97429ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:32.199355Z","caller":"traceutil/trace.go:171","msg":"trace[145513670] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"337.883668ms","start":"2026-03-06T00:24:31.861438Z","end":"2026-03-06T00:24:32.199322Z","steps":["trace[145513670] 'process raft request' (duration: 272.604119ms)","trace[145513670] 'compare' (duration: 65.161445ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:32.19976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:31.861418Z","time spent":"338.031832ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:24:32.235026Z","caller":"traceutil/trace.go:171","msg":"trace[32351286] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"363.589706ms","start":"2026-03-06T00:24:31.871402Z","end":"2026-03-06T00:24:32.234991Z","steps":["trace[32351286] 'process raft request' (duration: 363.424031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:32.235313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:31.871379Z","time spent":"363.839151ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:33.623384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.517475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:24:33.623646Z","caller":"traceutil/trace.go:171","msg":"trace[213529459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"791.831833ms","start":"2026-03-06T00:24:32.831792Z","end":"2026-03-06T00:24:33.623624Z","steps":["trace[213529459] 'range keys from in-memory index tree' (duration: 791.377451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:33.623698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:32.831769Z","time spent":"791.915945ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:24:33.672481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.984424ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T00:24:33.672716Z","caller":"traceutil/trace.go:171","msg":"trace[583603122] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"346.718654ms","start":"2026-03-06T00:24:33.325944Z","end":"2026-03-06T00:24:33.672663Z","steps":["trace[583603122] 'read index received' (duration: 45.371µs)","trace[583603122] 'applied index is now lower than readState.Index' (duration: 346.668752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:33.672832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.873368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:24:33.672925Z","caller":"traceutil/trace.go:171","msg":"trace[84398507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"347.018982ms","start":"2026-03-06T00:24:33.325885Z","end":"2026-03-06T00:24:33.672905Z","steps":["trace[84398507] 'agreement among raft nodes before linearized reading' (duration: 346.853648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:33.672977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:33.325865Z","time spent":"347.103233ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T00:24:34.969585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.119432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:24:34.969691Z","caller":"traceutil/trace.go:171","msg":"trace[1749844347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"140.290096ms","start":"2026-03-06T00:24:34.829379Z","end":"2026-03-06T00:24:34.969669Z","steps":["trace[1749844347] 'range keys from in-memory index tree' (duration: 140.02791ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:39.378997Z","caller":"traceutil/trace.go:171","msg":"trace[1754883214] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"126.001421ms","start":"2026-03-06T00:24:39.252947Z","end":"2026-03-06T00:24:39.378949Z","steps":["trace[1754883214] 'process raft request' (duration: 76.119765ms)","trace[1754883214] 'compare' (duration: 49.563568ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:44.277613Z","caller":"traceutil/trace.go:171","msg":"trace[1926804782] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"113.252936ms","start":"2026-03-06T00:24:44.16432Z","end":"2026-03-06T00:24:44.277573Z","steps":["trace[1926804782] 'process raft request' (duration: 112.970448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.291279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.105084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:24:48.291371Z","caller":"traceutil/trace.go:171","msg":"trace[128604131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"462.241147ms","start":"2026-03-06T00:24:47.829109Z","end":"2026-03-06T00:24:48.29135Z","steps":["trace[128604131] 'range keys from in-memory index tree' (duration: 461.99043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.291413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:47.829093Z","time spent":"462.311459ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:24:48.291556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.942145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-06T00:24:48.291279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.583833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T00:24:48.29164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.543456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-9xhsf\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-03-06T00:24:48.291646Z","caller":"traceutil/trace.go:171","msg":"trace[1899920725] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:692; }","duration":"657.078248ms","start":"2026-03-06T00:24:47.634542Z","end":"2026-03-06T00:24:48.291621Z","steps":["trace[1899920725] 'range keys from in-memory index tree' (duration: 656.75962ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.29168Z","caller":"traceutil/trace.go:171","msg":"trace[1624438084] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-9xhsf; range_end:; response_count:1; response_revision:692; }","duration":"613.616377ms","start":"2026-03-06T00:24:47.678053Z","end":"2026-03-06T00:24:48.29167Z","steps":["trace[1624438084] 'range keys from in-memory index tree' (duration: 613.399641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.291691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:47.634522Z","time spent":"657.16066ms","remote":"127.0.0.1:44794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-03-06T00:24:48.291714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:47.678029Z","time spent":"613.678479ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5105,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-9xhsf\" "} {"level":"info","ts":"2026-03-06T00:24:48.291659Z","caller":"traceutil/trace.go:171","msg":"trace[249981459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"272.024324ms","start":"2026-03-06T00:24:48.019623Z","end":"2026-03-06T00:24:48.291647Z","steps":["trace[249981459] 'range keys from in-memory index tree' (duration: 271.401028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.291582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.239726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:24:48.291892Z","caller":"traceutil/trace.go:171","msg":"trace[1636423280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"469.583005ms","start":"2026-03-06T00:24:47.822298Z","end":"2026-03-06T00:24:48.291881Z","steps":["trace[1636423280] 'range keys from in-memory index tree' (duration: 469.116823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.291926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:47.822278Z","time spent":"469.637996ms","remote":"127.0.0.1:44964","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-03-06T00:24:48.542625Z","caller":"traceutil/trace.go:171","msg":"trace[2130367483] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"246.905142ms","start":"2026-03-06T00:24:48.295693Z","end":"2026-03-06T00:24:48.542598Z","steps":["trace[2130367483] 'process raft request' (duration: 246.577684ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.542829Z","caller":"traceutil/trace.go:171","msg":"trace[255886091] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"246.318847ms","start":"2026-03-06T00:24:48.296495Z","end":"2026-03-06T00:24:48.542814Z","steps":["trace[255886091] 'process raft request' (duration: 246.0415ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.542985Z","caller":"traceutil/trace.go:171","msg":"trace[551624774] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:716; }","duration":"211.763253ms","start":"2026-03-06T00:24:48.331191Z","end":"2026-03-06T00:24:48.542954Z","steps":["trace[551624774] 'read index received' (duration: 211.057115ms)","trace[551624774] 'applied index is now lower than readState.Index' (duration: 704.978µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:48.542989Z","caller":"traceutil/trace.go:171","msg":"trace[175119939] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"242.182291ms","start":"2026-03-06T00:24:48.30078Z","end":"2026-03-06T00:24:48.542962Z","steps":["trace[175119939] 'process raft request' (duration: 241.974455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.543317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.420611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-06T00:24:48.543373Z","caller":"traceutil/trace.go:171","msg":"trace[1212815831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:695; }","duration":"221.566084ms","start":"2026-03-06T00:24:48.321794Z","end":"2026-03-06T00:24:48.54336Z","steps":["trace[1212815831] 'agreement among raft nodes before linearized reading' (duration: 221.353488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.753086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.161666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:24:48.753194Z","caller":"traceutil/trace.go:171","msg":"trace[1830917248] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"206.742036ms","start":"2026-03-06T00:24:48.546435Z","end":"2026-03-06T00:24:48.753177Z","steps":["trace[1830917248] 'read index received' (duration: 44.56303ms)","trace[1830917248] 'applied index is now lower than readState.Index' (duration: 162.177906ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:48.753338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.89915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rxtxv\" ","response":"range_response_count:1 size:5085"} {"level":"info","ts":"2026-03-06T00:24:48.753371Z","caller":"traceutil/trace.go:171","msg":"trace[1849950912] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rxtxv; range_end:; response_count:1; response_revision:696; }","duration":"206.955421ms","start":"2026-03-06T00:24:48.546407Z","end":"2026-03-06T00:24:48.753363Z","steps":["trace[1849950912] 'agreement among raft nodes before linearized reading' (duration: 206.815257ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.753416Z","caller":"traceutil/trace.go:171","msg":"trace[684495832] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"420.644944ms","start":"2026-03-06T00:24:48.332738Z","end":"2026-03-06T00:24:48.753383Z","steps":["trace[684495832] 'process raft request' (duration: 258.076498ms)","trace[684495832] 'compare' (duration: 162.045453ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:48.75357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:48.332729Z","time spent":"420.750537ms","remote":"127.0.0.1:44794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:24:48.806939Z","caller":"traceutil/trace.go:171","msg":"trace[1649037943] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"258.433607ms","start":"2026-03-06T00:24:48.548486Z","end":"2026-03-06T00:24:48.80692Z","steps":["trace[1649037943] 'process raft request' (duration: 258.355895ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.806962Z","caller":"traceutil/trace.go:171","msg":"trace[618386987] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"258.982541ms","start":"2026-03-06T00:24:48.547948Z","end":"2026-03-06T00:24:48.80693Z","steps":["trace[618386987] 'process raft request' (duration: 258.850548ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.807093Z","caller":"traceutil/trace.go:171","msg":"trace[1682186500] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"257.469923ms","start":"2026-03-06T00:24:48.549599Z","end":"2026-03-06T00:24:48.807069Z","steps":["trace[1682186500] 'process raft request' (duration: 257.283948ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.807365Z","caller":"traceutil/trace.go:171","msg":"trace[1167515901] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"259.923715ms","start":"2026-03-06T00:24:48.547429Z","end":"2026-03-06T00:24:48.807352Z","steps":["trace[1167515901] 'process raft request' (duration: 259.248968ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:48.969963Z","caller":"traceutil/trace.go:171","msg":"trace[969590574] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"162.912715ms","start":"2026-03-06T00:24:48.80702Z","end":"2026-03-06T00:24:48.969933Z","steps":["trace[969590574] 'read index received' (duration: 162.848214ms)","trace[969590574] 'applied index is now lower than readState.Index' (duration: 63.311µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:48.970377Z","caller":"traceutil/trace.go:171","msg":"trace[1307335288] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"210.167333ms","start":"2026-03-06T00:24:48.76018Z","end":"2026-03-06T00:24:48.970347Z","steps":["trace[1307335288] 'process raft request' (duration: 209.611709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:48.970552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.626017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T00:24:48.970592Z","caller":"traceutil/trace.go:171","msg":"trace[672420151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"171.738231ms","start":"2026-03-06T00:24:48.798843Z","end":"2026-03-06T00:24:48.970581Z","steps":["trace[672420151] 'agreement among raft nodes before linearized reading' (duration: 171.585497ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.102111Z","caller":"traceutil/trace.go:171","msg":"trace[579880425] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"286.098124ms","start":"2026-03-06T00:24:48.816001Z","end":"2026-03-06T00:24:49.1021Z","steps":["trace[579880425] 'process raft request' (duration: 285.93568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.102238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.021189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:24:49.102292Z","caller":"traceutil/trace.go:171","msg":"trace[75347028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"271.120872ms","start":"2026-03-06T00:24:48.831156Z","end":"2026-03-06T00:24:49.102277Z","steps":["trace[75347028] 'agreement among raft nodes before linearized reading' (duration: 270.961168ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.102068Z","caller":"traceutil/trace.go:171","msg":"trace[1304372581] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"132.011705ms","start":"2026-03-06T00:24:48.97004Z","end":"2026-03-06T00:24:49.102052Z","steps":["trace[1304372581] 'read index received' (duration: 131.989504ms)","trace[1304372581] 'applied index is now lower than readState.Index' (duration: 21.411µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:49.102411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.105033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T00:24:49.102475Z","caller":"traceutil/trace.go:171","msg":"trace[1597335493] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:702; }","duration":"293.208626ms","start":"2026-03-06T00:24:48.80925Z","end":"2026-03-06T00:24:49.102459Z","steps":["trace[1597335493] 'agreement among raft nodes before linearized reading' (duration: 292.999071ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.199664Z","caller":"traceutil/trace.go:171","msg":"trace[1032261319] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"224.342816ms","start":"2026-03-06T00:24:48.975294Z","end":"2026-03-06T00:24:49.199637Z","steps":["trace[1032261319] 'process raft request' (duration: 224.12043ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.200155Z","caller":"traceutil/trace.go:171","msg":"trace[1132702472] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"223.961755ms","start":"2026-03-06T00:24:48.97616Z","end":"2026-03-06T00:24:49.200122Z","steps":["trace[1132702472] 'process raft request' (duration: 223.400921ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.200394Z","caller":"traceutil/trace.go:171","msg":"trace[1740032723] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"223.702809ms","start":"2026-03-06T00:24:48.976675Z","end":"2026-03-06T00:24:49.200378Z","steps":["trace[1740032723] 'process raft request' (duration: 223.229207ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.200682Z","caller":"traceutil/trace.go:171","msg":"trace[103549856] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"223.35426ms","start":"2026-03-06T00:24:48.977317Z","end":"2026-03-06T00:24:49.200672Z","steps":["trace[103549856] 'process raft request' (duration: 222.840857ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.33263Z","caller":"traceutil/trace.go:171","msg":"trace[1239830854] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"132.077046ms","start":"2026-03-06T00:24:49.200528Z","end":"2026-03-06T00:24:49.332605Z","steps":["trace[1239830854] 'read index received' (duration: 131.876971ms)","trace[1239830854] 'applied index is now lower than readState.Index' (duration: 199.085µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:49.332681Z","caller":"traceutil/trace.go:171","msg":"trace[894034221] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"223.568086ms","start":"2026-03-06T00:24:49.109096Z","end":"2026-03-06T00:24:49.332664Z","steps":["trace[894034221] 'process raft request' (duration: 223.460553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.332827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.464365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-74s5g\" ","response":"range_response_count:1 size:4871"} {"level":"info","ts":"2026-03-06T00:24:49.332848Z","caller":"traceutil/trace.go:171","msg":"trace[1647933599] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"226.219163ms","start":"2026-03-06T00:24:49.10662Z","end":"2026-03-06T00:24:49.332839Z","steps":["trace[1647933599] 'process raft request' (duration: 225.799862ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.332859Z","caller":"traceutil/trace.go:171","msg":"trace[902237869] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-74s5g; range_end:; response_count:1; response_revision:708; }","duration":"227.536136ms","start":"2026-03-06T00:24:49.105315Z","end":"2026-03-06T00:24:49.332851Z","steps":["trace[902237869] 'agreement among raft nodes before linearized reading' (duration: 227.378782ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.470081Z","caller":"traceutil/trace.go:171","msg":"trace[545163980] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"135.899024ms","start":"2026-03-06T00:24:49.334153Z","end":"2026-03-06T00:24:49.470052Z","steps":["trace[545163980] 'process raft request' (duration: 135.74288ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.713483Z","caller":"traceutil/trace.go:171","msg":"trace[1610816379] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"361.639075ms","start":"2026-03-06T00:24:49.351808Z","end":"2026-03-06T00:24:49.713447Z","steps":["trace[1610816379] 'process raft request' (duration: 361.526452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.713657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.351785Z","time spent":"361.774098ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4186,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:24:49.797815Z","caller":"traceutil/trace.go:171","msg":"trace[935294477] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:733; }","duration":"249.527809ms","start":"2026-03-06T00:24:49.548268Z","end":"2026-03-06T00:24:49.797796Z","steps":["trace[935294477] 'read index received' (duration: 165.122561ms)","trace[935294477] 'applied index is now lower than readState.Index' (duration: 84.404518ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:49.797901Z","caller":"traceutil/trace.go:171","msg":"trace[593876330] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"322.825093ms","start":"2026-03-06T00:24:49.475071Z","end":"2026-03-06T00:24:49.797896Z","steps":["trace[593876330] 'process raft request' (duration: 322.504435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.79798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.475047Z","time spent":"322.870624ms","remote":"127.0.0.1:44816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:24:49.79829Z","caller":"traceutil/trace.go:171","msg":"trace[1944835223] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"322.922385ms","start":"2026-03-06T00:24:49.47536Z","end":"2026-03-06T00:24:49.798282Z","steps":["trace[1944835223] 'process raft request' (duration: 322.33093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.798329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.475352Z","time spent":"322.952797ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:24:49.798439Z","caller":"traceutil/trace.go:171","msg":"trace[690162359] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"322.915295ms","start":"2026-03-06T00:24:49.475519Z","end":"2026-03-06T00:24:49.798434Z","steps":["trace[690162359] 'process raft request' (duration: 322.209477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.798466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.475494Z","time spent":"322.957677ms","remote":"127.0.0.1:44978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:24:49.798649Z","caller":"traceutil/trace.go:171","msg":"trace[1180477782] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"320.813772ms","start":"2026-03-06T00:24:49.477807Z","end":"2026-03-06T00:24:49.798621Z","steps":["trace[1180477782] 'process raft request' (duration: 319.94619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.798796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.477789Z","time spent":"320.922354ms","remote":"127.0.0.1:45172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:49.79895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.687349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:24:49.798934Z","caller":"traceutil/trace.go:171","msg":"trace[1873289332] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"114.975639ms","start":"2026-03-06T00:24:49.683938Z","end":"2026-03-06T00:24:49.798913Z","steps":["trace[1873289332] 'process raft request' (duration: 113.83907ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.798971Z","caller":"traceutil/trace.go:171","msg":"trace[60191559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"250.73741ms","start":"2026-03-06T00:24:49.548228Z","end":"2026-03-06T00:24:49.798966Z","steps":["trace[60191559] 'agreement among raft nodes before linearized reading' (duration: 250.663278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:49.799063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.157404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-74s5g\" ","response":"range_response_count:1 size:4883"} {"level":"info","ts":"2026-03-06T00:24:49.799088Z","caller":"traceutil/trace.go:171","msg":"trace[1420646240] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-74s5g; range_end:; response_count:1; response_revision:715; }","duration":"115.215416ms","start":"2026-03-06T00:24:49.683865Z","end":"2026-03-06T00:24:49.79908Z","steps":["trace[1420646240] 'agreement among raft nodes before linearized reading' (duration: 115.099323ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:49.945444Z","caller":"traceutil/trace.go:171","msg":"trace[1228404177] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"143.56413ms","start":"2026-03-06T00:24:49.801854Z","end":"2026-03-06T00:24:49.945418Z","steps":["trace[1228404177] 'process raft request' (duration: 44.305182ms)","trace[1228404177] 'compare' (duration: 99.166865ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:50.155352Z","caller":"traceutil/trace.go:171","msg":"trace[1212845681] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:739; }","duration":"323.559481ms","start":"2026-03-06T00:24:49.831771Z","end":"2026-03-06T00:24:50.15533Z","steps":["trace[1212845681] 'read index received' (duration: 13.534325ms)","trace[1212845681] 'applied index is now lower than readState.Index' (duration: 310.024136ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:50.155458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.670115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:24:50.15548Z","caller":"traceutil/trace.go:171","msg":"trace[578776260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"323.742686ms","start":"2026-03-06T00:24:49.831731Z","end":"2026-03-06T00:24:50.155473Z","steps":["trace[578776260] 'agreement among raft nodes before linearized reading' (duration: 323.671364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:50.155501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.831697Z","time spent":"323.798397ms","remote":"127.0.0.1:44748","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-03-06T00:24:50.155651Z","caller":"traceutil/trace.go:171","msg":"trace[1369978850] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"345.564684ms","start":"2026-03-06T00:24:49.810077Z","end":"2026-03-06T00:24:50.155642Z","steps":["trace[1369978850] 'process raft request' (duration: 259.162135ms)","trace[1369978850] 'compare' (duration: 85.959278ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:24:50.155714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:49.810061Z","time spent":"345.610936ms","remote":"127.0.0.1:45172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3776,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:24:50.387345Z","caller":"traceutil/trace.go:171","msg":"trace[102485997] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"226.600043ms","start":"2026-03-06T00:24:50.160725Z","end":"2026-03-06T00:24:50.387325Z","steps":["trace[102485997] 'process raft request' (duration: 226.4519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:53.002397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.53827ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T00:24:54.356217Z","caller":"traceutil/trace.go:171","msg":"trace[1147852174] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"372.187464ms","start":"2026-03-06T00:24:53.984004Z","end":"2026-03-06T00:24:54.356192Z","steps":["trace[1147852174] 'process raft request' (duration: 372.04104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:54.356371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:53.983981Z","time spent":"372.306707ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:55.566452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.184886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T00:24:55.566543Z","caller":"traceutil/trace.go:171","msg":"trace[811426657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"295.33253ms","start":"2026-03-06T00:24:55.27119Z","end":"2026-03-06T00:24:55.566523Z","steps":["trace[811426657] 'range keys from in-memory index tree' (duration: 294.825668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:55.566617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.948779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-06T00:24:55.566617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.679292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-06T00:24:55.566702Z","caller":"traceutil/trace.go:171","msg":"trace[425209738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:730; }","duration":"856.823905ms","start":"2026-03-06T00:24:54.709862Z","end":"2026-03-06T00:24:55.566686Z","steps":["trace[425209738] 'range keys from in-memory index tree' (duration: 856.449585ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:55.566703Z","caller":"traceutil/trace.go:171","msg":"trace[1637271782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"956.059352ms","start":"2026-03-06T00:24:54.610619Z","end":"2026-03-06T00:24:55.566679Z","steps":["trace[1637271782] 'range keys from in-memory index tree' (duration: 955.837116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:55.566755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:54.610604Z","time spent":"956.137894ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T00:24:55.566755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:54.709843Z","time spent":"856.898917ms","remote":"127.0.0.1:44964","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-03-06T00:24:55.566812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.287838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-06T00:24:55.566884Z","caller":"traceutil/trace.go:171","msg":"trace[1888393006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"672.419961ms","start":"2026-03-06T00:24:54.894445Z","end":"2026-03-06T00:24:55.566865Z","steps":["trace[1888393006] 'range keys from in-memory index tree' (duration: 672.078172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:55.566863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.484085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-06T00:24:55.566924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:54.89442Z","time spent":"672.495022ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T00:24:55.567035Z","caller":"traceutil/trace.go:171","msg":"trace[341567365] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:730; }","duration":"713.177903ms","start":"2026-03-06T00:24:54.853764Z","end":"2026-03-06T00:24:55.566942Z","steps":["trace[341567365] 'count revisions from in-memory index tree' (duration: 712.397303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:55.567575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:54.853726Z","time spent":"713.361668ms","remote":"127.0.0.1:44918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-03-06T00:24:55.930095Z","caller":"traceutil/trace.go:171","msg":"trace[409450738] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"551.710794ms","start":"2026-03-06T00:24:55.37836Z","end":"2026-03-06T00:24:55.930071Z","steps":["trace[409450738] 'process raft request' (duration: 551.54378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:55.930354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:55.378331Z","time spent":"551.842128ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:57.21316Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283300358s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T00:24:57.213673Z","caller":"traceutil/trace.go:171","msg":"trace[187320020] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:755; }","duration":"1.382895884s","start":"2026-03-06T00:24:55.830752Z","end":"2026-03-06T00:24:57.213648Z","steps":["trace[187320020] 'read index received' (duration: 99.110814ms)","trace[187320020] 'applied index is now lower than readState.Index' (duration: 1.2837842s)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:24:57.21372Z","caller":"traceutil/trace.go:171","msg":"trace[1342008191] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.628457822s","start":"2026-03-06T00:24:55.585224Z","end":"2026-03-06T00:24:57.213682Z","steps":["trace[1342008191] 'process raft request' (duration: 1.628182575s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:57.213807Z","caller":"traceutil/trace.go:171","msg":"trace[841084520] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"1.627250931s","start":"2026-03-06T00:24:55.586545Z","end":"2026-03-06T00:24:57.213795Z","steps":["trace[841084520] 'process raft request' (duration: 1.627072346s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:57.213799Z","caller":"traceutil/trace.go:171","msg":"trace[1742118984] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.628527553s","start":"2026-03-06T00:24:55.585242Z","end":"2026-03-06T00:24:57.213769Z","steps":["trace[1742118984] 'process raft request' (duration: 1.628346768s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:24:57.213815Z","caller":"traceutil/trace.go:171","msg":"trace[529601248] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.628560924s","start":"2026-03-06T00:24:55.585226Z","end":"2026-03-06T00:24:57.213787Z","steps":["trace[529601248] 'process raft request' (duration: 1.628325638s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:57.213928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:55.585207Z","time spent":"1.628600165s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:57.213937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:55.585207Z","time spent":"1.628665927s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:57.213944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:55.585225Z","time spent":"1.628629146s","remote":"127.0.0.1:44964","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-03-06T00:24:57.213995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383250543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:24:57.214029Z","caller":"traceutil/trace.go:171","msg":"trace[895857401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"1.383307294s","start":"2026-03-06T00:24:55.83071Z","end":"2026-03-06T00:24:57.214018Z","steps":["trace[895857401] 'agreement among raft nodes before linearized reading' (duration: 1.383252273s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:57.213934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:55.58654Z","time spent":"1.627303772s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:24:57.214063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:55.83069Z","time spent":"1.383367655s","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:24:57.214182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.387987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:24:57.214237Z","caller":"traceutil/trace.go:171","msg":"trace[497460665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"853.48573ms","start":"2026-03-06T00:24:56.360737Z","end":"2026-03-06T00:24:57.214223Z","steps":["trace[497460665] 'agreement among raft nodes before linearized reading' (duration: 853.340166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:24:57.214295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:24:56.360696Z","time spent":"853.590652ms","remote":"127.0.0.1:44964","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-03-06T00:24:57.792984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.730404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:24:57.793126Z","caller":"traceutil/trace.go:171","msg":"trace[446849617] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"131.575133ms","start":"2026-03-06T00:24:57.661529Z","end":"2026-03-06T00:24:57.793104Z","steps":["trace[446849617] 'process raft request' (duration: 12.624553ms)","trace[446849617] 'compare' (duration: 118.602831ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:28.727262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.066891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:25:28.727381Z","caller":"traceutil/trace.go:171","msg":"trace[975529090] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"896.329553ms","start":"2026-03-06T00:25:27.831034Z","end":"2026-03-06T00:25:28.727364Z","steps":["trace[975529090] 'read index received' (duration: 50.641µs)","trace[975529090] 'applied index is now lower than readState.Index' (duration: 896.277822ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:28.72747Z","caller":"traceutil/trace.go:171","msg":"trace[564849762] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"1.061718188s","start":"2026-03-06T00:25:27.665742Z","end":"2026-03-06T00:25:28.72746Z","steps":["trace[564849762] 'process raft request' (duration: 78.35833ms)","trace[564849762] 'compare' (duration: 982.877997ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:28.727546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:27.665716Z","time spent":"1.061785499s","remote":"127.0.0.1:44794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:25:28.727728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.692451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:25:28.727821Z","caller":"traceutil/trace.go:171","msg":"trace[1117145551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"896.808065ms","start":"2026-03-06T00:25:27.830966Z","end":"2026-03-06T00:25:28.727774Z","steps":["trace[1117145551] 'agreement among raft nodes before linearized reading' (duration: 896.688552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:28.727865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:27.830939Z","time spent":"896.916877ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:25:28.727962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.199334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T00:25:28.7281Z","caller":"traceutil/trace.go:171","msg":"trace[1058319776] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:827; }","duration":"875.708626ms","start":"2026-03-06T00:25:27.852372Z","end":"2026-03-06T00:25:28.72808Z","steps":["trace[1058319776] 'agreement among raft nodes before linearized reading' (duration: 875.114412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:28.728144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:27.852351Z","time spent":"875.782718ms","remote":"127.0.0.1:45080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-03-06T00:25:31.948175Z","caller":"traceutil/trace.go:171","msg":"trace[462233191] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"481.168701ms","start":"2026-03-06T00:25:31.466984Z","end":"2026-03-06T00:25:31.948152Z","steps":["trace[462233191] 'read index received' (duration: 481.024588ms)","trace[462233191] 'applied index is now lower than readState.Index' (duration: 143.053µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:31.948275Z","caller":"traceutil/trace.go:171","msg":"trace[1480043514] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"487.136961ms","start":"2026-03-06T00:25:31.46111Z","end":"2026-03-06T00:25:31.948247Z","steps":["trace[1480043514] 'process raft request' (duration: 486.897616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:31.948468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.436047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-06T00:25:31.948683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:31.461084Z","time spent":"487.371177ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:25:31.948715Z","caller":"traceutil/trace.go:171","msg":"trace[620073375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"481.748085ms","start":"2026-03-06T00:25:31.466949Z","end":"2026-03-06T00:25:31.948697Z","steps":["trace[620073375] 'agreement among raft nodes before linearized reading' (duration: 481.346765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:31.948764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:31.466938Z","time spent":"481.814716ms","remote":"127.0.0.1:44964","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-03-06T00:25:32.028556Z","caller":"traceutil/trace.go:171","msg":"trace[228478987] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"222.104584ms","start":"2026-03-06T00:25:31.80643Z","end":"2026-03-06T00:25:32.028534Z","steps":["trace[228478987] 'process raft request' (duration: 180.931552ms)","trace[228478987] 'compare' (duration: 40.989798ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:32.028879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.406996ms","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-03-06T00:25:32.028924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.420057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-06T00:25:32.028991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.62073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:25:32.029024Z","caller":"traceutil/trace.go:171","msg":"trace[1237549208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"198.711883ms","start":"2026-03-06T00:25:31.830304Z","end":"2026-03-06T00:25:32.029016Z","steps":["trace[1237549208] 'agreement among raft nodes before linearized reading' (duration: 198.232991ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:32.02902Z","caller":"traceutil/trace.go:171","msg":"trace[439903992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"542.53774ms","start":"2026-03-06T00:25:31.48645Z","end":"2026-03-06T00:25:32.028988Z","steps":["trace[439903992] 'agreement among raft nodes before linearized reading' (duration: 542.069869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:32.029156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:31.486428Z","time spent":"542.716085ms","remote":"127.0.0.1:44964","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-03-06T00:25:32.028953Z","caller":"traceutil/trace.go:171","msg":"trace[1930894680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"395.525059ms","start":"2026-03-06T00:25:31.633411Z","end":"2026-03-06T00:25:32.028937Z","steps":["trace[1930894680] 'agreement among raft nodes before linearized reading' (duration: 395.135269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:32.028904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.11652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T00:25:32.029348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:31.633398Z","time spent":"395.915489ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-06T00:25:32.029409Z","caller":"traceutil/trace.go:171","msg":"trace[487601501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"423.624022ms","start":"2026-03-06T00:25:31.60577Z","end":"2026-03-06T00:25:32.029394Z","steps":["trace[487601501] 'agreement among raft nodes before linearized reading' (duration: 422.785933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:32.029459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:31.605716Z","time spent":"423.729105ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:25:36.881338Z","caller":"traceutil/trace.go:171","msg":"trace[1980947948] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"137.739072ms","start":"2026-03-06T00:25:36.743573Z","end":"2026-03-06T00:25:36.881312Z","steps":["trace[1980947948] 'process raft request' (duration: 137.519286ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.420412Z","caller":"traceutil/trace.go:171","msg":"trace[1670280477] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"100.309268ms","start":"2026-03-06T00:25:41.320077Z","end":"2026-03-06T00:25:41.420386Z","steps":["trace[1670280477] 'process raft request' (duration: 99.906889ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.586601Z","caller":"traceutil/trace.go:171","msg":"trace[501123348] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"146.699544ms","start":"2026-03-06T00:25:41.439807Z","end":"2026-03-06T00:25:41.586506Z","steps":["trace[501123348] 'process raft request' (duration: 97.83434ms)","trace[501123348] 'compare' (duration: 48.495055ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:41.589192Z","caller":"traceutil/trace.go:171","msg":"trace[2126472295] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"121.175692ms","start":"2026-03-06T00:25:41.467989Z","end":"2026-03-06T00:25:41.589165Z","steps":["trace[2126472295] 'process raft request' (duration: 120.952476ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.589234Z","caller":"traceutil/trace.go:171","msg":"trace[810711465] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"121.191682ms","start":"2026-03-06T00:25:41.468022Z","end":"2026-03-06T00:25:41.589213Z","steps":["trace[810711465] 'process raft request' (duration: 121.070589ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.589325Z","caller":"traceutil/trace.go:171","msg":"trace[439523095] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"121.072409ms","start":"2026-03-06T00:25:41.468209Z","end":"2026-03-06T00:25:41.589281Z","steps":["trace[439523095] 'process raft request' (duration: 120.919916ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.589608Z","caller":"traceutil/trace.go:171","msg":"trace[299977840] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"117.941325ms","start":"2026-03-06T00:25:41.471652Z","end":"2026-03-06T00:25:41.589593Z","steps":["trace[299977840] 'process raft request' (duration: 117.537475ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.590016Z","caller":"traceutil/trace.go:171","msg":"trace[441293743] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"116.984422ms","start":"2026-03-06T00:25:41.473017Z","end":"2026-03-06T00:25:41.590002Z","steps":["trace[441293743] 'process raft request' (duration: 116.239125ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.590099Z","caller":"traceutil/trace.go:171","msg":"trace[1206850735] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"113.207133ms","start":"2026-03-06T00:25:41.476876Z","end":"2026-03-06T00:25:41.590083Z","steps":["trace[1206850735] 'process raft request' (duration: 112.756813ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.590104Z","caller":"traceutil/trace.go:171","msg":"trace[1322791150] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:929; }","duration":"113.607702ms","start":"2026-03-06T00:25:41.476473Z","end":"2026-03-06T00:25:41.59008Z","steps":["trace[1322791150] 'read index received' (duration: 61.176945ms)","trace[1322791150] 'applied index is now lower than readState.Index' (duration: 52.429927ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:41.590279Z","caller":"traceutil/trace.go:171","msg":"trace[1957845197] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"112.724612ms","start":"2026-03-06T00:25:41.477538Z","end":"2026-03-06T00:25:41.590263Z","steps":["trace[1957845197] 'process raft request' (duration: 112.450295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:41.590333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.839739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-03-06T00:25:41.590375Z","caller":"traceutil/trace.go:171","msg":"trace[2017255495] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:900; }","duration":"113.92865ms","start":"2026-03-06T00:25:41.476435Z","end":"2026-03-06T00:25:41.590363Z","steps":["trace[2017255495] 'agreement among raft nodes before linearized reading' (duration: 113.736635ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.755922Z","caller":"traceutil/trace.go:171","msg":"trace[1175988106] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"158.953493ms","start":"2026-03-06T00:25:41.596928Z","end":"2026-03-06T00:25:41.755882Z","steps":["trace[1175988106] 'process raft request' (duration: 142.71025ms)","trace[1175988106] 'compare' (duration: 15.990157ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:41.755998Z","caller":"traceutil/trace.go:171","msg":"trace[131081275] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"157.050248ms","start":"2026-03-06T00:25:41.598924Z","end":"2026-03-06T00:25:41.755974Z","steps":["trace[131081275] 'process raft request' (duration: 156.875774ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.756367Z","caller":"traceutil/trace.go:171","msg":"trace[1753589731] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"156.229778ms","start":"2026-03-06T00:25:41.600125Z","end":"2026-03-06T00:25:41.756355Z","steps":["trace[1753589731] 'process raft request' (duration: 155.756207ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.756424Z","caller":"traceutil/trace.go:171","msg":"trace[406672234] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:937; }","duration":"157.305224ms","start":"2026-03-06T00:25:41.599092Z","end":"2026-03-06T00:25:41.756397Z","steps":["trace[406672234] 'read index received' (duration: 48.26183ms)","trace[406672234] 'applied index is now lower than readState.Index' (duration: 109.042004ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:41.75654Z","caller":"traceutil/trace.go:171","msg":"trace[1695262882] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"154.285522ms","start":"2026-03-06T00:25:41.602242Z","end":"2026-03-06T00:25:41.756528Z","steps":["trace[1695262882] 'process raft request' (duration: 153.965945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:41.756923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.520828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-03-06T00:25:41.75698Z","caller":"traceutil/trace.go:171","msg":"trace[223435824] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:905; }","duration":"115.646131ms","start":"2026-03-06T00:25:41.641317Z","end":"2026-03-06T00:25:41.756964Z","steps":["trace[223435824] 'agreement among raft nodes before linearized reading' (duration: 115.307993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:41.75727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.857317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-gsctt\" ","response":"range_response_count:1 size:10774"} {"level":"info","ts":"2026-03-06T00:25:41.757428Z","caller":"traceutil/trace.go:171","msg":"trace[2085219200] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-gsctt; range_end:; response_count:1; response_revision:905; }","duration":"158.304927ms","start":"2026-03-06T00:25:41.599063Z","end":"2026-03-06T00:25:41.757368Z","steps":["trace[2085219200] 'agreement among raft nodes before linearized reading' (duration: 157.767324ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.962974Z","caller":"traceutil/trace.go:171","msg":"trace[264298691] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"194.171485ms","start":"2026-03-06T00:25:41.768762Z","end":"2026-03-06T00:25:41.962933Z","steps":["trace[264298691] 'process raft request' (duration: 102.237014ms)","trace[264298691] 'compare' (duration: 91.636884ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:41.963105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.827059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:25:41.962956Z","caller":"traceutil/trace.go:171","msg":"trace[1073477998] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:942; }","duration":"133.658126ms","start":"2026-03-06T00:25:41.829265Z","end":"2026-03-06T00:25:41.962923Z","steps":["trace[1073477998] 'read index received' (duration: 41.741435ms)","trace[1073477998] 'applied index is now lower than readState.Index' (duration: 91.915111ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:41.963167Z","caller":"traceutil/trace.go:171","msg":"trace[2085769664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"133.952873ms","start":"2026-03-06T00:25:41.829194Z","end":"2026-03-06T00:25:41.963147Z","steps":["trace[2085769664] 'agreement among raft nodes before linearized reading' (duration: 133.83966ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:41.963153Z","caller":"traceutil/trace.go:171","msg":"trace[584819385] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"192.969696ms","start":"2026-03-06T00:25:41.770145Z","end":"2026-03-06T00:25:41.963115Z","steps":["trace[584819385] 'process raft request' (duration: 192.69009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:42.479112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.00556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T00:25:42.47922Z","caller":"traceutil/trace.go:171","msg":"trace[990982332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"335.170653ms","start":"2026-03-06T00:25:42.144021Z","end":"2026-03-06T00:25:42.479192Z","steps":["trace[990982332] 'range keys from in-memory index tree' (duration: 334.731694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:42.479203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.730589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-06T00:25:42.479219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.924799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-06T00:25:42.479324Z","caller":"traceutil/trace.go:171","msg":"trace[186413985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"291.073502ms","start":"2026-03-06T00:25:42.188239Z","end":"2026-03-06T00:25:42.479313Z","steps":["trace[186413985] 'range keys from in-memory index tree' (duration: 290.761666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:42.480211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.834241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-06T00:25:42.480251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.507023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:25:42.481469Z","caller":"traceutil/trace.go:171","msg":"trace[1144526533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"353.805624ms","start":"2026-03-06T00:25:42.127648Z","end":"2026-03-06T00:25:42.481454Z","steps":["trace[1144526533] 'range keys from in-memory index tree' (duration: 351.423177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:42.479854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:42.144006Z","time spent":"335.262536ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T00:25:42.48064Z","caller":"traceutil/trace.go:171","msg":"trace[473575854] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:908; }","duration":"413.845861ms","start":"2026-03-06T00:25:42.065425Z","end":"2026-03-06T00:25:42.479271Z","steps":["trace[473575854] 'count revisions from in-memory index tree' (duration: 413.649246ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:42.481393Z","caller":"traceutil/trace.go:171","msg":"trace[2054042659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"342.66471ms","start":"2026-03-06T00:25:42.138301Z","end":"2026-03-06T00:25:42.480966Z","steps":["trace[2054042659] 'range keys from in-memory index tree' (duration: 341.665167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:42.481803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:42.138275Z","time spent":"343.47938ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T00:25:42.481521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:42.127613Z","time spent":"353.882966ms","remote":"127.0.0.1:44964","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-03-06T00:25:42.481679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:42.065375Z","time spent":"416.276508ms","remote":"127.0.0.1:44828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-03-06T00:25:42.616804Z","caller":"traceutil/trace.go:171","msg":"trace[1468138233] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:947; }","duration":"103.652607ms","start":"2026-03-06T00:25:42.513124Z","end":"2026-03-06T00:25:42.616777Z","steps":["trace[1468138233] 'read index received' (duration: 65.458715ms)","trace[1468138233] 'applied index is now lower than readState.Index' (duration: 38.192682ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:42.616867Z","caller":"traceutil/trace.go:171","msg":"trace[2070838547] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"127.553252ms","start":"2026-03-06T00:25:42.489273Z","end":"2026-03-06T00:25:42.616826Z","steps":["trace[2070838547] 'process raft request' (duration: 89.547425ms)","trace[2070838547] 'compare' (duration: 37.746651ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:42.616971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.818251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T00:25:42.617014Z","caller":"traceutil/trace.go:171","msg":"trace[377107640] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:913; }","duration":"103.886082ms","start":"2026-03-06T00:25:42.513116Z","end":"2026-03-06T00:25:42.617002Z","steps":["trace[377107640] 'agreement among raft nodes before linearized reading' (duration: 103.761739ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:42.616997Z","caller":"traceutil/trace.go:171","msg":"trace[258389403] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"127.48735ms","start":"2026-03-06T00:25:42.489477Z","end":"2026-03-06T00:25:42.616964Z","steps":["trace[258389403] 'process raft request' (duration: 127.238004ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:42.924648Z","caller":"traceutil/trace.go:171","msg":"trace[721806659] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"140.564889ms","start":"2026-03-06T00:25:42.784066Z","end":"2026-03-06T00:25:42.924631Z","steps":["trace[721806659] 'process raft request' (duration: 140.20617ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:44.9853Z","caller":"traceutil/trace.go:171","msg":"trace[1772972115] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"401.916309ms","start":"2026-03-06T00:25:44.583366Z","end":"2026-03-06T00:25:44.985283Z","steps":["trace[1772972115] 'read index received' (duration: 401.768726ms)","trace[1772972115] 'applied index is now lower than readState.Index' (duration: 146.513µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:44.985352Z","caller":"traceutil/trace.go:171","msg":"trace[1481615238] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"465.44202ms","start":"2026-03-06T00:25:44.519899Z","end":"2026-03-06T00:25:44.985341Z","steps":["trace[1481615238] 'process raft request' (duration: 465.284766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:44.985438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:44.519887Z","time spent":"465.493301ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:25:44.985451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.113074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T00:25:44.985475Z","caller":"traceutil/trace.go:171","msg":"trace[2079400416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"402.173106ms","start":"2026-03-06T00:25:44.583294Z","end":"2026-03-06T00:25:44.985467Z","steps":["trace[2079400416] 'agreement among raft nodes before linearized reading' (duration: 402.078443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:44.985492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:44.583274Z","time spent":"402.214337ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T00:25:44.985523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.199961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:25:44.985552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.389847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:25:44.985582Z","caller":"traceutil/trace.go:171","msg":"trace[768831022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"154.284083ms","start":"2026-03-06T00:25:44.831269Z","end":"2026-03-06T00:25:44.985553Z","steps":["trace[768831022] 'agreement among raft nodes before linearized reading' (duration: 154.216381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:44.985601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.418469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-06T00:25:44.985603Z","caller":"traceutil/trace.go:171","msg":"trace[93424634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"365.46953ms","start":"2026-03-06T00:25:44.620118Z","end":"2026-03-06T00:25:44.985588Z","steps":["trace[93424634] 'agreement among raft nodes before linearized reading' (duration: 365.301586ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:44.985621Z","caller":"traceutil/trace.go:171","msg":"trace[1360833561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"365.475739ms","start":"2026-03-06T00:25:44.62014Z","end":"2026-03-06T00:25:44.985615Z","steps":["trace[1360833561] 'agreement among raft nodes before linearized reading' (duration: 365.419248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:44.985642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:44.62012Z","time spent":"365.517121ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T00:25:44.985639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:44.620103Z","time spent":"365.527761ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-06T00:25:45.183656Z","caller":"traceutil/trace.go:171","msg":"trace[532805155] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"194.095822ms","start":"2026-03-06T00:25:44.989535Z","end":"2026-03-06T00:25:45.183631Z","steps":["trace[532805155] 'process raft request' (duration: 193.930148ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:45.185319Z","caller":"traceutil/trace.go:171","msg":"trace[489766696] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"195.190589ms","start":"2026-03-06T00:25:44.990106Z","end":"2026-03-06T00:25:45.185296Z","steps":["trace[489766696] 'process raft request' (duration: 195.050305ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:45.185347Z","caller":"traceutil/trace.go:171","msg":"trace[992339373] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"194.064182ms","start":"2026-03-06T00:25:44.991257Z","end":"2026-03-06T00:25:45.185321Z","steps":["trace[992339373] 'process raft request' (duration: 193.991031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.226151Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107209713s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T00:25:47.226721Z","caller":"traceutil/trace.go:171","msg":"trace[1007120309] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"395.297195ms","start":"2026-03-06T00:25:46.831393Z","end":"2026-03-06T00:25:47.22669Z","steps":["trace[1007120309] 'read index received' (duration: 395.035098ms)","trace[1007120309] 'applied index is now lower than readState.Index' (duration: 260.086µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:47.22687Z","caller":"traceutil/trace.go:171","msg":"trace[856075817] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.108002521s","start":"2026-03-06T00:25:46.118853Z","end":"2026-03-06T00:25:47.226855Z","steps":["trace[856075817] 'process raft request' (duration: 1.107621302s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.22701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.604712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:25:47.227035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:46.118835Z","time spent":"1.108071674s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:25:47.227062Z","caller":"traceutil/trace.go:171","msg":"trace[575985468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"395.683964ms","start":"2026-03-06T00:25:46.831358Z","end":"2026-03-06T00:25:47.227042Z","steps":["trace[575985468] 'agreement among raft nodes before linearized reading' (duration: 395.603932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.227096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:46.831344Z","time spent":"395.746005ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:25:47.727697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255562140545025,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:25:47.834705Z","caller":"traceutil/trace.go:171","msg":"trace[382208101] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"607.853771ms","start":"2026-03-06T00:25:47.226812Z","end":"2026-03-06T00:25:47.834666Z","steps":["trace[382208101] 'read index received' (duration: 589.261042ms)","trace[382208101] 'applied index is now lower than readState.Index' (duration: 18.591639ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:47.83494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.009202ms","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-03-06T00:25:47.834942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.142942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:25:47.834978Z","caller":"traceutil/trace.go:171","msg":"trace[1119591553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"646.053753ms","start":"2026-03-06T00:25:47.188913Z","end":"2026-03-06T00:25:47.834967Z","steps":["trace[1119591553] 'agreement among raft nodes before linearized reading' (duration: 645.90148ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:47.835005Z","caller":"traceutil/trace.go:171","msg":"trace[1425178423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"645.245874ms","start":"2026-03-06T00:25:47.189738Z","end":"2026-03-06T00:25:47.834984Z","steps":["trace[1425178423] 'agreement among raft nodes before linearized reading' (duration: 644.932276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.835014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:47.188833Z","time spent":"646.172575ms","remote":"127.0.0.1:44964","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-03-06T00:25:47.835049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:47.189723Z","time spent":"645.313115ms","remote":"127.0.0.1:44964","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-03-06T00:25:47.835214Z","caller":"traceutil/trace.go:171","msg":"trace[165678703] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"767.153874ms","start":"2026-03-06T00:25:47.068034Z","end":"2026-03-06T00:25:47.835188Z","steps":["trace[165678703] 'process raft request' (duration: 748.123444ms)","trace[165678703] 'compare' (duration: 18.127388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:25:47.835258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.486249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:25:47.835301Z","caller":"traceutil/trace.go:171","msg":"trace[396017199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"606.53063ms","start":"2026-03-06T00:25:47.228756Z","end":"2026-03-06T00:25:47.835287Z","steps":["trace[396017199] 'agreement among raft nodes before linearized reading' (duration: 606.459648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.835336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:47.228717Z","time spent":"606.610552ms","remote":"127.0.0.1:44742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:25:47.835364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:47.068008Z","time spent":"767.254285ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:25:47.835461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.475795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-06T00:25:47.835542Z","caller":"traceutil/trace.go:171","msg":"trace[258532365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"846.618108ms","start":"2026-03-06T00:25:46.988899Z","end":"2026-03-06T00:25:47.835517Z","steps":["trace[258532365] 'agreement among raft nodes before linearized reading' (duration: 846.329962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.835587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:46.988879Z","time spent":"846.699791ms","remote":"127.0.0.1:44964","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-03-06T00:25:47.835647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.018181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-06T00:25:47.835659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.143082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T00:25:47.837163Z","caller":"traceutil/trace.go:171","msg":"trace[1425670283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"649.678209ms","start":"2026-03-06T00:25:47.187464Z","end":"2026-03-06T00:25:47.837143Z","steps":["trace[1425670283] 'agreement among raft nodes before linearized reading' (duration: 648.119982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:47.837234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:25:47.187443Z","time spent":"649.7734ms","remote":"127.0.0.1:44964","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-03-06T00:25:47.835702Z","caller":"traceutil/trace.go:171","msg":"trace[614979044] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:924; }","duration":"197.146673ms","start":"2026-03-06T00:25:47.638539Z","end":"2026-03-06T00:25:47.835685Z","steps":["trace[614979044] 'agreement among raft nodes before linearized reading' (duration: 197.00409ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:47.990647Z","caller":"traceutil/trace.go:171","msg":"trace[2020716236] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"152.286516ms","start":"2026-03-06T00:25:47.838332Z","end":"2026-03-06T00:25:47.990618Z","steps":["trace[2020716236] 'process raft request' (duration: 152.145702ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:48.005618Z","caller":"traceutil/trace.go:171","msg":"trace[542001184] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"161.933723ms","start":"2026-03-06T00:25:47.84365Z","end":"2026-03-06T00:25:48.005584Z","steps":["trace[542001184] 'process raft request' (duration: 161.747818ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:48.006137Z","caller":"traceutil/trace.go:171","msg":"trace[848992303] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"162.424345ms","start":"2026-03-06T00:25:47.843697Z","end":"2026-03-06T00:25:48.006121Z","steps":["trace[848992303] 'process raft request' (duration: 161.838051ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:48.006143Z","caller":"traceutil/trace.go:171","msg":"trace[673263386] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"162.544387ms","start":"2026-03-06T00:25:47.843573Z","end":"2026-03-06T00:25:48.006117Z","steps":["trace[673263386] 'read index received' (duration: 147.123653ms)","trace[673263386] 'applied index is now lower than readState.Index' (duration: 15.419684ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:48.006196Z","caller":"traceutil/trace.go:171","msg":"trace[1918872149] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"162.448266ms","start":"2026-03-06T00:25:47.843718Z","end":"2026-03-06T00:25:48.006166Z","steps":["trace[1918872149] 'process raft request' (duration: 162.129568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:48.006256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.66394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:25:48.006307Z","caller":"traceutil/trace.go:171","msg":"trace[783454234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"162.749743ms","start":"2026-03-06T00:25:47.843547Z","end":"2026-03-06T00:25:48.006297Z","steps":["trace[783454234] 'agreement among raft nodes before linearized reading' (duration: 162.657891ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:50.265377Z","caller":"traceutil/trace.go:171","msg":"trace[1434815763] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"220.65678ms","start":"2026-03-06T00:25:50.044507Z","end":"2026-03-06T00:25:50.265164Z","steps":["trace[1434815763] 'process raft request' (duration: 192.885134ms)","trace[1434815763] 'compare' (duration: 27.643813ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:50.285271Z","caller":"traceutil/trace.go:171","msg":"trace[617256411] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"239.365771ms","start":"2026-03-06T00:25:50.045888Z","end":"2026-03-06T00:25:50.285254Z","steps":["trace[617256411] 'process raft request' (duration: 239.338871ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:50.285338Z","caller":"traceutil/trace.go:171","msg":"trace[668921221] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"239.835323ms","start":"2026-03-06T00:25:50.045484Z","end":"2026-03-06T00:25:50.285319Z","steps":["trace[668921221] 'process raft request' (duration: 239.649718ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:54.482663Z","caller":"traceutil/trace.go:171","msg":"trace[1988097465] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"173.136588ms","start":"2026-03-06T00:25:54.309509Z","end":"2026-03-06T00:25:54.482645Z","steps":["trace[1988097465] 'read index received' (duration: 173.098127ms)","trace[1988097465] 'applied index is now lower than readState.Index' (duration: 37.721µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:25:54.482869Z","caller":"traceutil/trace.go:171","msg":"trace[1280365963] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"196.837127ms","start":"2026-03-06T00:25:54.286001Z","end":"2026-03-06T00:25:54.482838Z","steps":["trace[1280365963] 'process raft request' (duration: 196.51833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:25:54.482956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.417614ms","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-03-06T00:25:54.482896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.666293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:25:54.483038Z","caller":"traceutil/trace.go:171","msg":"trace[1984357147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"171.863088ms","start":"2026-03-06T00:25:54.311165Z","end":"2026-03-06T00:25:54.483028Z","steps":["trace[1984357147] 'agreement among raft nodes before linearized reading' (duration: 171.625102ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:54.483041Z","caller":"traceutil/trace.go:171","msg":"trace[1195264233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"173.540048ms","start":"2026-03-06T00:25:54.309482Z","end":"2026-03-06T00:25:54.483022Z","steps":["trace[1195264233] 'agreement among raft nodes before linearized reading' (duration: 173.286382ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:25:55.497001Z","caller":"traceutil/trace.go:171","msg":"trace[59056834] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"153.006693ms","start":"2026-03-06T00:25:55.343968Z","end":"2026-03-06T00:25:55.496974Z","steps":["trace[59056834] 'process raft request' (duration: 152.834419ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:04.42189Z","caller":"traceutil/trace.go:171","msg":"trace[500294979] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"288.777819ms","start":"2026-03-06T00:26:04.133087Z","end":"2026-03-06T00:26:04.421864Z","steps":["trace[500294979] 'process raft request' (duration: 288.585604ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:05.56607Z","caller":"traceutil/trace.go:171","msg":"trace[1914644395] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"100.36614ms","start":"2026-03-06T00:26:05.465666Z","end":"2026-03-06T00:26:05.566032Z","steps":["trace[1914644395] 'process raft request' (duration: 100.178525ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:06.747178Z","caller":"traceutil/trace.go:171","msg":"trace[994452468] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"117.163156ms","start":"2026-03-06T00:26:06.629971Z","end":"2026-03-06T00:26:06.747134Z","steps":["trace[994452468] 'process raft request' (duration: 63.9711ms)","trace[994452468] 'compare' (duration: 53.006482ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:26:08.140311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255562140545204,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:26:08.174566Z","caller":"traceutil/trace.go:171","msg":"trace[1288049899] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"534.591292ms","start":"2026-03-06T00:26:07.639937Z","end":"2026-03-06T00:26:08.174528Z","steps":["trace[1288049899] 'read index received' (duration: 534.342227ms)","trace[1288049899] 'applied index is now lower than readState.Index' (duration: 247.245µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:08.174634Z","caller":"traceutil/trace.go:171","msg":"trace[1216527500] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"571.243598ms","start":"2026-03-06T00:26:07.603331Z","end":"2026-03-06T00:26:08.174575Z","steps":["trace[1216527500] 'process raft request' (duration: 570.983441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:08.17483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:07.60331Z","time spent":"571.407752ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:26:08.174906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.93035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T00:26:08.174966Z","caller":"traceutil/trace.go:171","msg":"trace[1680162556] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:1000; }","duration":"535.054613ms","start":"2026-03-06T00:26:07.639896Z","end":"2026-03-06T00:26:08.174951Z","steps":["trace[1680162556] 'agreement among raft nodes before linearized reading' (duration: 534.834588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:08.175018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:07.639872Z","time spent":"535.135595ms","remote":"127.0.0.1:44794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-03-06T00:26:08.174853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.521631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:26:08.175391Z","caller":"traceutil/trace.go:171","msg":"trace[317810416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"344.105514ms","start":"2026-03-06T00:26:07.831262Z","end":"2026-03-06T00:26:08.175367Z","steps":["trace[317810416] 'agreement among raft nodes before linearized reading' (duration: 343.51125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:08.175467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:07.83124Z","time spent":"344.212297ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:26:08.618745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.112727ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-06T00:26:08.618844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.176943Z","time spent":"441.896134ms","remote":"127.0.0.1:44794","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-06T00:26:08.926853Z","caller":"traceutil/trace.go:171","msg":"trace[35464325] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"307.270905ms","start":"2026-03-06T00:26:08.619553Z","end":"2026-03-06T00:26:08.926824Z","steps":["trace[35464325] 'process raft request' (duration: 307.214584ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:08.926895Z","caller":"traceutil/trace.go:171","msg":"trace[1314859667] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"668.777631ms","start":"2026-03-06T00:26:08.258078Z","end":"2026-03-06T00:26:08.926856Z","steps":["trace[1314859667] 'process raft request' (duration: 624.755001ms)","trace[1314859667] 'compare' (duration: 43.716813ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:08.926948Z","caller":"traceutil/trace.go:171","msg":"trace[110130773] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"442.655352ms","start":"2026-03-06T00:26:08.484247Z","end":"2026-03-06T00:26:08.926902Z","steps":["trace[110130773] 'read index received' (duration: 398.604432ms)","trace[110130773] 'applied index is now lower than readState.Index' (duration: 44.04062ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:26:08.927036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.619539Z","time spent":"307.393168ms","remote":"127.0.0.1:44794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:26:08.927204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.258054Z","time spent":"668.985025ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:26:08.92725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.988199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-06T00:26:08.927376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.137306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T00:26:08.927465Z","caller":"traceutil/trace.go:171","msg":"trace[1103144189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"358.239419ms","start":"2026-03-06T00:26:08.569203Z","end":"2026-03-06T00:26:08.927443Z","steps":["trace[1103144189] 'agreement among raft nodes before linearized reading' (duration: 358.014853ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:08.927467Z","caller":"traceutil/trace.go:171","msg":"trace[1886527375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"443.197114ms","start":"2026-03-06T00:26:08.484226Z","end":"2026-03-06T00:26:08.927423Z","steps":["trace[1886527375] 'agreement among raft nodes before linearized reading' (duration: 442.792915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:08.927515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.5692Z","time spent":"358.30553ms","remote":"127.0.0.1:44964","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-03-06T00:26:08.927558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.48413Z","time spent":"443.409059ms","remote":"127.0.0.1:44964","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-03-06T00:26:08.928356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.525375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:26:08.928412Z","caller":"traceutil/trace.go:171","msg":"trace[663678654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"333.627667ms","start":"2026-03-06T00:26:08.594773Z","end":"2026-03-06T00:26:08.928401Z","steps":["trace[663678654] 'agreement among raft nodes before linearized reading' (duration: 332.429069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:08.928449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.594768Z","time spent":"333.672668ms","remote":"127.0.0.1:44964","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-03-06T00:26:08.928877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.308998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:26:08.928906Z","caller":"traceutil/trace.go:171","msg":"trace[1552962245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"363.37265ms","start":"2026-03-06T00:26:08.565527Z","end":"2026-03-06T00:26:08.928899Z","steps":["trace[1552962245] 'agreement among raft nodes before linearized reading' (duration: 361.655619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:08.928925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:08.565506Z","time spent":"363.41508ms","remote":"127.0.0.1:44964","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-03-06T00:26:09.117504Z","caller":"traceutil/trace.go:171","msg":"trace[1564065394] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"124.803498ms","start":"2026-03-06T00:26:08.992661Z","end":"2026-03-06T00:26:09.117464Z","steps":["trace[1564065394] 'read index received' (duration: 39.128114ms)","trace[1564065394] 'applied index is now lower than readState.Index' (duration: 85.674344ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:26:09.117768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.070424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-06T00:26:09.117813Z","caller":"traceutil/trace.go:171","msg":"trace[180057166] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1007; }","duration":"125.171666ms","start":"2026-03-06T00:26:08.99263Z","end":"2026-03-06T00:26:09.117801Z","steps":["trace[180057166] 'agreement among raft nodes before linearized reading' (duration: 124.995391ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:09.11752Z","caller":"traceutil/trace.go:171","msg":"trace[407942945] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"166.548452ms","start":"2026-03-06T00:26:08.950934Z","end":"2026-03-06T00:26:09.117483Z","steps":["trace[407942945] 'process raft request' (duration: 80.882449ms)","trace[407942945] 'compare' (duration: 85.431407ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:09.284405Z","caller":"traceutil/trace.go:171","msg":"trace[959039051] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"138.091439ms","start":"2026-03-06T00:26:09.146282Z","end":"2026-03-06T00:26:09.284374Z","steps":["trace[959039051] 'process raft request' (duration: 134.846022ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:09.293416Z","caller":"traceutil/trace.go:171","msg":"trace[87135326] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"137.049895ms","start":"2026-03-06T00:26:09.156341Z","end":"2026-03-06T00:26:09.293391Z","steps":["trace[87135326] 'process raft request' (duration: 136.997484ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:09.293823Z","caller":"traceutil/trace.go:171","msg":"trace[1189058083] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"138.376236ms","start":"2026-03-06T00:26:09.155436Z","end":"2026-03-06T00:26:09.293812Z","steps":["trace[1189058083] 'process raft request' (duration: 137.750842ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:10.284363Z","caller":"traceutil/trace.go:171","msg":"trace[320746984] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"100.875532ms","start":"2026-03-06T00:26:10.183464Z","end":"2026-03-06T00:26:10.284339Z","steps":["trace[320746984] 'process raft request' (duration: 100.689997ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:11.348331Z","caller":"traceutil/trace.go:171","msg":"trace[1830964463] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"353.436175ms","start":"2026-03-06T00:26:10.994867Z","end":"2026-03-06T00:26:11.348303Z","steps":["trace[1830964463] 'read index received' (duration: 353.175889ms)","trace[1830964463] 'applied index is now lower than readState.Index' (duration: 259.096µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:11.348395Z","caller":"traceutil/trace.go:171","msg":"trace[909535674] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"397.28886ms","start":"2026-03-06T00:26:10.951055Z","end":"2026-03-06T00:26:11.348343Z","steps":["trace[909535674] 'process raft request' (duration: 397.063235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:11.348625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.692234ms","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-03-06T00:26:11.348609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:10.951032Z","time spent":"397.452454ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:26:11.348621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.713501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-06T00:26:11.348676Z","caller":"traceutil/trace.go:171","msg":"trace[1354130622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"351.788586ms","start":"2026-03-06T00:26:10.996874Z","end":"2026-03-06T00:26:11.348662Z","steps":["trace[1354130622] 'agreement among raft nodes before linearized reading' (duration: 351.601632ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:11.348713Z","caller":"traceutil/trace.go:171","msg":"trace[2126755035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"353.857075ms","start":"2026-03-06T00:26:10.99483Z","end":"2026-03-06T00:26:11.348687Z","steps":["trace[2126755035] 'agreement among raft nodes before linearized reading' (duration: 353.555038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:11.348774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:10.994815Z","time spent":"353.949697ms","remote":"127.0.0.1:44964","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-03-06T00:26:11.348721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:10.99686Z","time spent":"351.852528ms","remote":"127.0.0.1:44964","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-03-06T00:26:11.348835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.150021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:26:11.348943Z","caller":"traceutil/trace.go:171","msg":"trace[1208901593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"351.314764ms","start":"2026-03-06T00:26:10.997601Z","end":"2026-03-06T00:26:11.348916Z","steps":["trace[1208901593] 'agreement among raft nodes before linearized reading' (duration: 351.057018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:11.348996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:10.997579Z","time spent":"351.408097ms","remote":"127.0.0.1:44964","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-03-06T00:26:11.616853Z","caller":"traceutil/trace.go:171","msg":"trace[661541146] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"263.145293ms","start":"2026-03-06T00:26:11.353679Z","end":"2026-03-06T00:26:11.616824Z","steps":["trace[661541146] 'process raft request' (duration: 262.882447ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:11.641564Z","caller":"traceutil/trace.go:171","msg":"trace[1094721303] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"286.856423ms","start":"2026-03-06T00:26:11.35468Z","end":"2026-03-06T00:26:11.641536Z","steps":["trace[1094721303] 'process raft request' (duration: 286.610277ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:11.641791Z","caller":"traceutil/trace.go:171","msg":"trace[894555297] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"284.417866ms","start":"2026-03-06T00:26:11.35733Z","end":"2026-03-06T00:26:11.641748Z","steps":["trace[894555297] 'process raft request' (duration: 284.144089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:13.70326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.536422ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T00:26:13.703659Z","caller":"traceutil/trace.go:171","msg":"trace[300171370] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"335.670276ms","start":"2026-03-06T00:26:13.367969Z","end":"2026-03-06T00:26:13.703639Z","steps":["trace[300171370] 'read index received' (duration: 96.711443ms)","trace[300171370] 'applied index is now lower than readState.Index' (duration: 238.957813ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:13.703807Z","caller":"traceutil/trace.go:171","msg":"trace[616848878] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"422.903756ms","start":"2026-03-06T00:26:13.280873Z","end":"2026-03-06T00:26:13.703777Z","steps":["trace[616848878] 'process raft request' (duration: 422.493526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:13.703971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:13.280843Z","time spent":"423.036178ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:26:13.704108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.123576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-06T00:26:13.704189Z","caller":"traceutil/trace.go:171","msg":"trace[1148704616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"336.254749ms","start":"2026-03-06T00:26:13.367919Z","end":"2026-03-06T00:26:13.704174Z","steps":["trace[1148704616] 'agreement among raft nodes before linearized reading' (duration: 335.883271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:13.704267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:13.367897Z","time spent":"336.358432ms","remote":"127.0.0.1:44964","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-03-06T00:26:14.006032Z","caller":"traceutil/trace.go:171","msg":"trace[317851117] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1064; }","duration":"174.318956ms","start":"2026-03-06T00:26:13.831676Z","end":"2026-03-06T00:26:14.005995Z","steps":["trace[317851117] 'read index received' (duration: 168.179631ms)","trace[317851117] 'applied index is now lower than readState.Index' (duration: 6.137785ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:14.006138Z","caller":"traceutil/trace.go:171","msg":"trace[1333760344] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"288.4355ms","start":"2026-03-06T00:26:13.717658Z","end":"2026-03-06T00:26:14.006094Z","steps":["trace[1333760344] 'process raft request' (duration: 282.325416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:14.006199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.51393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:26:14.006237Z","caller":"traceutil/trace.go:171","msg":"trace[194857472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"174.625063ms","start":"2026-03-06T00:26:13.8316Z","end":"2026-03-06T00:26:14.006225Z","steps":["trace[194857472] 'agreement among raft nodes before linearized reading' (duration: 174.515791ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:14.006337Z","caller":"traceutil/trace.go:171","msg":"trace[1771687221] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"288.520043ms","start":"2026-03-06T00:26:13.717768Z","end":"2026-03-06T00:26:14.006288Z","steps":["trace[1771687221] 'process raft request' (duration: 288.127164ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:14.006382Z","caller":"traceutil/trace.go:171","msg":"trace[1926496658] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"285.176404ms","start":"2026-03-06T00:26:13.721185Z","end":"2026-03-06T00:26:14.006361Z","steps":["trace[1926496658] 'process raft request' (duration: 284.757854ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:14.4483Z","caller":"traceutil/trace.go:171","msg":"trace[1613650506] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"127.578713ms","start":"2026-03-06T00:26:14.320698Z","end":"2026-03-06T00:26:14.448277Z","steps":["trace[1613650506] 'read index received' (duration: 127.412129ms)","trace[1613650506] 'applied index is now lower than readState.Index' (duration: 165.504µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:14.448422Z","caller":"traceutil/trace.go:171","msg":"trace[1751563368] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"143.875278ms","start":"2026-03-06T00:26:14.304515Z","end":"2026-03-06T00:26:14.448391Z","steps":["trace[1751563368] 'process raft request' (duration: 143.57871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:14.448519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.802518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-06T00:26:14.448543Z","caller":"traceutil/trace.go:171","msg":"trace[1375812469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"127.849409ms","start":"2026-03-06T00:26:14.320687Z","end":"2026-03-06T00:26:14.448536Z","steps":["trace[1375812469] 'agreement among raft nodes before linearized reading' (duration: 127.676315ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:14.581632Z","caller":"traceutil/trace.go:171","msg":"trace[2090278936] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"123.480806ms","start":"2026-03-06T00:26:14.458117Z","end":"2026-03-06T00:26:14.581598Z","steps":["trace[2090278936] 'process raft request' (duration: 118.775805ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:16.128949Z","caller":"traceutil/trace.go:171","msg":"trace[1962957427] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"114.004299ms","start":"2026-03-06T00:26:16.01492Z","end":"2026-03-06T00:26:16.128924Z","steps":["trace[1962957427] 'process raft request' (duration: 113.837385ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:16.22686Z","caller":"traceutil/trace.go:171","msg":"trace[916906925] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"211.080524ms","start":"2026-03-06T00:26:16.015754Z","end":"2026-03-06T00:26:16.226834Z","steps":["trace[916906925] 'process raft request' (duration: 211.014302ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:16.227084Z","caller":"traceutil/trace.go:171","msg":"trace[883225667] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"211.969305ms","start":"2026-03-06T00:26:16.015105Z","end":"2026-03-06T00:26:16.227074Z","steps":["trace[883225667] 'process raft request' (duration: 211.480803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:18.591723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.417702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-06T00:26:18.591815Z","caller":"traceutil/trace.go:171","msg":"trace[527661083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"359.549395ms","start":"2026-03-06T00:26:18.232243Z","end":"2026-03-06T00:26:18.591793Z","steps":["trace[527661083] 'range keys from in-memory index tree' (duration: 359.229827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:18.591862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.232192Z","time spent":"359.661008ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T00:26:18.592045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.661398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:26:18.592142Z","caller":"traceutil/trace.go:171","msg":"trace[772437770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"458.848223ms","start":"2026-03-06T00:26:18.133269Z","end":"2026-03-06T00:26:18.592117Z","steps":["trace[772437770] 'range keys from in-memory index tree' (duration: 458.359032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:18.592189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.133237Z","time spent":"458.942135ms","remote":"127.0.0.1:44964","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-03-06T00:26:18.592305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.093202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-06T00:26:18.592473Z","caller":"traceutil/trace.go:171","msg":"trace[443727884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"361.332478ms","start":"2026-03-06T00:26:18.231114Z","end":"2026-03-06T00:26:18.592446Z","steps":["trace[443727884] 'range keys from in-memory index tree' (duration: 360.770084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:18.592532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.231087Z","time spent":"361.43308ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T00:26:19.130606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255562140545308,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:26:19.37166Z","caller":"traceutil/trace.go:171","msg":"trace[610328287] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"774.84855ms","start":"2026-03-06T00:26:18.596779Z","end":"2026-03-06T00:26:19.371627Z","steps":["trace[610328287] 'process raft request' (duration: 774.659885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:19.372039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.596751Z","time spent":"775.000394ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:26:19.618169Z","caller":"traceutil/trace.go:171","msg":"trace[2040253626] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.01974307s","start":"2026-03-06T00:26:18.59841Z","end":"2026-03-06T00:26:19.618153Z","steps":["trace[2040253626] 'process raft request' (duration: 1.019650638s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:19.618217Z","caller":"traceutil/trace.go:171","msg":"trace[263159518] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.020915698s","start":"2026-03-06T00:26:18.59726Z","end":"2026-03-06T00:26:19.618176Z","steps":["trace[263159518] 'process raft request' (duration: 1.020622861s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:19.618359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.598394Z","time spent":"1.019820661s","remote":"127.0.0.1:44964","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-03-06T00:26:19.618411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.597239Z","time spent":"1.021058611s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:26:19.618542Z","caller":"traceutil/trace.go:171","msg":"trace[1907498972] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1091; }","duration":"988.440053ms","start":"2026-03-06T00:26:18.630066Z","end":"2026-03-06T00:26:19.618506Z","steps":["trace[1907498972] 'read index received' (duration: 741.34569ms)","trace[1907498972] 'applied index is now lower than readState.Index' (duration: 247.093123ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:26:19.618642Z","caller":"traceutil/trace.go:171","msg":"trace[258148397] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"1.019944875s","start":"2026-03-06T00:26:18.598652Z","end":"2026-03-06T00:26:19.618597Z","steps":["trace[258148397] 'process raft request' (duration: 1.019450593s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:19.618785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.699309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-06T00:26:19.618783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.246031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:26:19.618835Z","caller":"traceutil/trace.go:171","msg":"trace[946875788] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1051; }","duration":"988.793362ms","start":"2026-03-06T00:26:18.630029Z","end":"2026-03-06T00:26:19.618822Z","steps":["trace[946875788] 'agreement among raft nodes before linearized reading' (duration: 988.682319ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:19.618842Z","caller":"traceutil/trace.go:171","msg":"trace[936105733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"786.388184ms","start":"2026-03-06T00:26:18.832441Z","end":"2026-03-06T00:26:19.618829Z","steps":["trace[936105733] 'agreement among raft nodes before linearized reading' (duration: 786.287912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:19.618872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.832406Z","time spent":"786.460066ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:26:19.618872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.630018Z","time spent":"988.844293ms","remote":"127.0.0.1:44908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:26:19.618992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.263866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-06T00:26:19.619062Z","caller":"traceutil/trace.go:171","msg":"trace[2055982785] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1051; }","duration":"166.414509ms","start":"2026-03-06T00:26:19.452626Z","end":"2026-03-06T00:26:19.61904Z","steps":["trace[2055982785] 'agreement among raft nodes before linearized reading' (duration: 166.150863ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:26:19.619094Z","caller":"traceutil/trace.go:171","msg":"trace[418064833] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"482.044446ms","start":"2026-03-06T00:26:19.137033Z","end":"2026-03-06T00:26:19.619077Z","steps":["trace[418064833] 'process raft request' (duration: 481.35621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:26:19.619214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:19.137002Z","time spent":"482.136649ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:26:19.619386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:26:18.59864Z","time spent":"1.020112388s","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:29:28.82694Z","caller":"traceutil/trace.go:171","msg":"trace[217195684] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"144.41692ms","start":"2026-03-06T00:29:28.682487Z","end":"2026-03-06T00:29:28.826904Z","steps":["trace[217195684] 'process raft request' (duration: 144.247926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:28.97225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.344674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:29:28.972763Z","caller":"traceutil/trace.go:171","msg":"trace[225986408] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1947; }","duration":"286.153907ms","start":"2026-03-06T00:29:28.686581Z","end":"2026-03-06T00:29:28.972735Z","steps":["trace[225986408] 'read index received' (duration: 140.218181ms)","trace[225986408] 'applied index is now lower than readState.Index' (duration: 145.934416ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:29:28.972764Z","caller":"traceutil/trace.go:171","msg":"trace[1910845451] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"289.498276ms","start":"2026-03-06T00:29:28.683223Z","end":"2026-03-06T00:29:28.972722Z","steps":["trace[1910845451] 'process raft request' (duration: 165.530767ms)","trace[1910845451] 'compare' (duration: 123.16392ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:29:28.972824Z","caller":"traceutil/trace.go:171","msg":"trace[282331143] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"288.82143ms","start":"2026-03-06T00:29:28.683984Z","end":"2026-03-06T00:29:28.972805Z","steps":["trace[282331143] 'process raft request' (duration: 288.39403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:28.973116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.497385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T00:29:28.973218Z","caller":"traceutil/trace.go:171","msg":"trace[2078230539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1866; }","duration":"286.655019ms","start":"2026-03-06T00:29:28.686541Z","end":"2026-03-06T00:29:28.973196Z","steps":["trace[2078230539] 'agreement among raft nodes before linearized reading' (duration: 286.27926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:28.973915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.494748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:29:28.974007Z","caller":"traceutil/trace.go:171","msg":"trace[1003771035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"143.641362ms","start":"2026-03-06T00:29:28.830343Z","end":"2026-03-06T00:29:28.973984Z","steps":["trace[1003771035] 'agreement among raft nodes before linearized reading' (duration: 142.576537ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:29:37.247778Z","caller":"traceutil/trace.go:171","msg":"trace[420275871] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"189.023951ms","start":"2026-03-06T00:29:37.05872Z","end":"2026-03-06T00:29:37.247744Z","steps":["trace[420275871] 'process raft request' (duration: 122.134436ms)","trace[420275871] 'compare' (duration: 66.662899ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:29:37.248083Z","caller":"traceutil/trace.go:171","msg":"trace[516521571] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"188.770204ms","start":"2026-03-06T00:29:37.059287Z","end":"2026-03-06T00:29:37.248057Z","steps":["trace[516521571] 'process raft request' (duration: 188.402605ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:29:37.248533Z","caller":"traceutil/trace.go:171","msg":"trace[959393759] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"188.435457ms","start":"2026-03-06T00:29:37.060085Z","end":"2026-03-06T00:29:37.248521Z","steps":["trace[959393759] 'process raft request' (duration: 187.894044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:37.510782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.149617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:29:37.51154Z","caller":"traceutil/trace.go:171","msg":"trace[127298512] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"196.926077ms","start":"2026-03-06T00:29:37.313973Z","end":"2026-03-06T00:29:37.510899Z","steps":["trace[127298512] 'process raft request' (duration: 68.534864ms)","trace[127298512] 'compare' (duration: 127.783098ms)"],"step_count":2}