{"level":"warn","ts":"2026-02-24T04:32:37.244676Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T04:32:37.244833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T04:32:37.244969Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T04:32:37.244988Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-24T04:32:37.245085Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T04:32:37.245958Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-24T04:32:37.246056Z","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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T04:32:37.250635Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.284071ms"} {"level":"info","ts":"2026-02-24T04:32:37.258848Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-24T04:32:37.258915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T04:32:37.258946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-24T04:32:37.258962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T04:32:37.258975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-24T04:32:37.259011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-24T04:32:37.267269Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T04:32:37.268548Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T04:32:37.269621Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-24T04:32:37.271693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T04:32:37.271934Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T04:32:37.272069Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T04:32:37.272687Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T04:32:37.272713Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T04:32:37.273507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-24T04:32:37.273719Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-24T04:32:37.279421Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T04:32:37.279564Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-24T04:32:37.279607Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-24T04:32:37.279853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T04:32:37.279893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T04:32:37.959689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T04:32:37.95979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T04:32:37.959857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-24T04:32:37.95989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-24T04:32:37.959909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-24T04:32:37.959935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-24T04:32:37.959983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-24T04:32:37.961097Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:32:37.961981Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T04:32:37.962085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:32:37.962459Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:32:37.962618Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:32:37.962674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:32:37.962708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:32:37.966862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-24T04:32:37.967195Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T04:32:37.967332Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T04:32:37.967397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T04:32:54.184809Z","caller":"traceutil/trace.go:171","msg":"trace[1802225757] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"100.094548ms","start":"2026-02-24T04:32:54.084678Z","end":"2026-02-24T04:32:54.184772Z","steps":["trace[1802225757] 'process raft request' (duration: 61.396806ms)","trace[1802225757] 'compare' (duration: 38.560018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:32:54.926776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.514363ms","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-02-24T04:32:54.926878Z","caller":"traceutil/trace.go:171","msg":"trace[1100112738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"407.671597ms","start":"2026-02-24T04:32:54.519186Z","end":"2026-02-24T04:32:54.926857Z","steps":["trace[1100112738] 'range keys from in-memory index tree' (duration: 407.295966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:54.926917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:54.519163Z","time spent":"407.746618ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:32:54.925899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.782722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:32:54.933177Z","caller":"traceutil/trace.go:171","msg":"trace[1431197487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"270.84867ms","start":"2026-02-24T04:32:54.66204Z","end":"2026-02-24T04:32:54.932888Z","steps":["trace[1431197487] 'range keys from in-memory index tree' (duration: 263.639087ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:55.278258Z","caller":"traceutil/trace.go:171","msg":"trace[1330214626] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"341.699448ms","start":"2026-02-24T04:32:54.936533Z","end":"2026-02-24T04:32:55.278233Z","steps":["trace[1330214626] 'process raft request' (duration: 341.251456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:55.278744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:54.936521Z","time spent":"341.7976ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:32:56.135316Z","caller":"traceutil/trace.go:171","msg":"trace[1011936548] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"1.07152205s","start":"2026-02-24T04:32:55.063762Z","end":"2026-02-24T04:32:56.135284Z","steps":["trace[1011936548] 'read index received' (duration: 214.176499ms)","trace[1011936548] 'applied index is now lower than readState.Index' (duration: 857.344491ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:32:56.135395Z","caller":"traceutil/trace.go:171","msg":"trace[1958096730] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.194900629s","start":"2026-02-24T04:32:54.940448Z","end":"2026-02-24T04:32:56.135349Z","steps":["trace[1958096730] 'process raft request' (duration: 1.194648702s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:56.135561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071752776s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:32:56.135695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.027555ms","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-02-24T04:32:56.135726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.479745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:32:56.13574Z","caller":"traceutil/trace.go:171","msg":"trace[1118594934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"432.119158ms","start":"2026-02-24T04:32:55.703604Z","end":"2026-02-24T04:32:56.135723Z","steps":["trace[1118594934] 'agreement among raft nodes before linearized reading' (duration: 431.959283ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:56.135786Z","caller":"traceutil/trace.go:171","msg":"trace[462605274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"310.572478ms","start":"2026-02-24T04:32:55.82519Z","end":"2026-02-24T04:32:56.135763Z","steps":["trace[462605274] 'agreement among raft nodes before linearized reading' (duration: 310.438914ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:56.135745Z","caller":"traceutil/trace.go:171","msg":"trace[1754417416] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:376; }","duration":"1.071960121s","start":"2026-02-24T04:32:55.063751Z","end":"2026-02-24T04:32:56.135711Z","steps":["trace[1754417416] 'agreement among raft nodes before linearized reading' (duration: 1.071698044s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:56.135822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:55.825164Z","time spent":"310.65193ms","remote":"127.0.0.1:49006","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-02-24T04:32:56.13579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:55.703577Z","time spent":"432.20186ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:32:56.135595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:54.940442Z","time spent":"1.195052153s","remote":"127.0.0.1:49198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:04.401994Z","caller":"traceutil/trace.go:171","msg":"trace[123865316] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"133.499919ms","start":"2026-02-24T04:33:04.268459Z","end":"2026-02-24T04:33:04.401959Z","steps":["trace[123865316] 'process raft request' (duration: 133.326094ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:04.404699Z","caller":"traceutil/trace.go:171","msg":"trace[324764401] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"135.511442ms","start":"2026-02-24T04:33:04.269161Z","end":"2026-02-24T04:33:04.404672Z","steps":["trace[324764401] 'process raft request' (duration: 135.458751ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:04.40481Z","caller":"traceutil/trace.go:171","msg":"trace[1085640837] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"135.749589ms","start":"2026-02-24T04:33:04.269014Z","end":"2026-02-24T04:33:04.404763Z","steps":["trace[1085640837] 'process raft request' (duration: 135.465981ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:10.204204Z","caller":"traceutil/trace.go:171","msg":"trace[1052194360] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"339.908179ms","start":"2026-02-24T04:33:09.864257Z","end":"2026-02-24T04:33:10.204166Z","steps":["trace[1052194360] 'process raft request' (duration: 339.727745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:10.204412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:09.864221Z","time spent":"340.079714ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:16.392349Z","caller":"traceutil/trace.go:171","msg":"trace[1567784498] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"242.488913ms","start":"2026-02-24T04:33:16.14983Z","end":"2026-02-24T04:33:16.392319Z","steps":["trace[1567784498] 'process raft request' (duration: 242.325039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.07813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.793925ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:33:17.07838Z","caller":"traceutil/trace.go:171","msg":"trace[1195708391] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"616.536873ms","start":"2026-02-24T04:33:16.461819Z","end":"2026-02-24T04:33:17.078356Z","steps":["trace[1195708391] 'read index received' (duration: 126.278876ms)","trace[1195708391] 'applied index is now lower than readState.Index' (duration: 490.256167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:17.078615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.765279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:33:17.078722Z","caller":"traceutil/trace.go:171","msg":"trace[546422912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"616.936235ms","start":"2026-02-24T04:33:16.46176Z","end":"2026-02-24T04:33:17.078696Z","steps":["trace[546422912] 'agreement among raft nodes before linearized reading' (duration: 616.649847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.078769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:16.461728Z","time spent":"617.031647ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:33:17.078845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.456552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T04:33:17.078831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.243566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:33:17.078888Z","caller":"traceutil/trace.go:171","msg":"trace[1905146259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"616.535533ms","start":"2026-02-24T04:33:16.46234Z","end":"2026-02-24T04:33:17.078876Z","steps":["trace[1905146259] 'agreement among raft nodes before linearized reading' (duration: 616.145653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.078929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:16.462331Z","time spent":"616.589025ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T04:33:17.078928Z","caller":"traceutil/trace.go:171","msg":"trace[6478629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"616.369879ms","start":"2026-02-24T04:33:16.462535Z","end":"2026-02-24T04:33:17.078905Z","steps":["trace[6478629] 'agreement among raft nodes before linearized reading' (duration: 616.03428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.078984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:16.462446Z","time spent":"616.527243ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:33:17.786148Z","caller":"traceutil/trace.go:171","msg":"trace[331899910] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"702.303219ms","start":"2026-02-24T04:33:17.083813Z","end":"2026-02-24T04:33:17.786116Z","steps":["trace[331899910] 'process raft request' (duration: 702.102284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.78652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:17.083781Z","time spent":"702.466654ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:17.811638Z","caller":"traceutil/trace.go:171","msg":"trace[1305861615] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"727.446259ms","start":"2026-02-24T04:33:17.08416Z","end":"2026-02-24T04:33:17.811606Z","steps":["trace[1305861615] 'process raft request' (duration: 727.225424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.811808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:17.084145Z","time spent":"727.571692ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:17.812286Z","caller":"traceutil/trace.go:171","msg":"trace[1858200975] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"727.684716ms","start":"2026-02-24T04:33:17.084566Z","end":"2026-02-24T04:33:17.812251Z","steps":["trace[1858200975] 'process raft request' (duration: 726.973847ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:17.812335Z","caller":"traceutil/trace.go:171","msg":"trace[1964029421] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:512; }","duration":"406.761382ms","start":"2026-02-24T04:33:17.405546Z","end":"2026-02-24T04:33:17.812307Z","steps":["trace[1964029421] 'read index received' (duration: 380.554404ms)","trace[1964029421] 'applied index is now lower than readState.Index' (duration: 26.132596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:17.81241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.862825ms","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-02-24T04:33:17.812444Z","caller":"traceutil/trace.go:171","msg":"trace[418432614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"406.897865ms","start":"2026-02-24T04:33:17.405536Z","end":"2026-02-24T04:33:17.812433Z","steps":["trace[418432614] 'agreement among raft nodes before linearized reading' (duration: 406.783242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:17.812467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:17.405452Z","time spent":"407.009699ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:33:17.812479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:17.084556Z","time spent":"727.816369ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:17.812488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.776686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:17.812563Z","caller":"traceutil/trace.go:171","msg":"trace[1232499871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"148.910999ms","start":"2026-02-24T04:33:17.66363Z","end":"2026-02-24T04:33:17.812541Z","steps":["trace[1232499871] 'agreement among raft nodes before linearized reading' (duration: 148.794846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:18.87078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.646969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:18.87092Z","caller":"traceutil/trace.go:171","msg":"trace[147861151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"206.843174ms","start":"2026-02-24T04:33:18.664041Z","end":"2026-02-24T04:33:18.870884Z","steps":["trace[147861151] 'range keys from in-memory index tree' (duration: 206.529345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:18.870905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.450169ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:18.871032Z","caller":"traceutil/trace.go:171","msg":"trace[1502815534] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:496; }","duration":"892.794928ms","start":"2026-02-24T04:33:17.978212Z","end":"2026-02-24T04:33:18.871007Z","steps":["trace[1502815534] 'range keys from in-memory index tree' (duration: 892.427137ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:21.693798Z","caller":"traceutil/trace.go:171","msg":"trace[775391506] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"301.444595ms","start":"2026-02-24T04:33:21.392324Z","end":"2026-02-24T04:33:21.693768Z","steps":["trace[775391506] 'process raft request' (duration: 301.265861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:21.693939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:21.392285Z","time spent":"301.587709ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:33:21.699462Z","caller":"traceutil/trace.go:171","msg":"trace[307481674] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"108.388839ms","start":"2026-02-24T04:33:21.591052Z","end":"2026-02-24T04:33:21.69944Z","steps":["trace[307481674] 'process raft request' (duration: 108.04249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:24.51783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.654292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-24T04:33:24.517946Z","caller":"traceutil/trace.go:171","msg":"trace[92271004] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"127.877789ms","start":"2026-02-24T04:33:24.390044Z","end":"2026-02-24T04:33:24.517922Z","steps":["trace[92271004] 'range keys from in-memory index tree' (duration: 127.397826ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:32.443569Z","caller":"traceutil/trace.go:171","msg":"trace[75541990] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"149.048333ms","start":"2026-02-24T04:33:32.294496Z","end":"2026-02-24T04:33:32.443544Z","steps":["trace[75541990] 'process raft request' (duration: 148.869498ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:38.017296Z","caller":"traceutil/trace.go:171","msg":"trace[1601293982] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"130.773416ms","start":"2026-02-24T04:33:37.886507Z","end":"2026-02-24T04:33:38.01728Z","steps":["trace[1601293982] 'process raft request' (duration: 130.618772ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:38.017282Z","caller":"traceutil/trace.go:171","msg":"trace[1755697039] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"128.64635ms","start":"2026-02-24T04:33:37.888612Z","end":"2026-02-24T04:33:38.017258Z","steps":["trace[1755697039] 'read index received' (duration: 128.490976ms)","trace[1755697039] 'applied index is now lower than readState.Index' (duration: 154.274µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:38.017929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.274346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:33:38.01798Z","caller":"traceutil/trace.go:171","msg":"trace[490986169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"129.40695ms","start":"2026-02-24T04:33:37.888561Z","end":"2026-02-24T04:33:38.017968Z","steps":["trace[490986169] 'agreement among raft nodes before linearized reading' (duration: 128.781983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:38.715148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.222333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:33:38.715294Z","caller":"traceutil/trace.go:171","msg":"trace[903104337] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"693.778842ms","start":"2026-02-24T04:33:38.021493Z","end":"2026-02-24T04:33:38.715272Z","steps":["trace[903104337] 'process raft request' (duration: 223.326643ms)","trace[903104337] 'compare' (duration: 470.078369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:38.715377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:38.021475Z","time spent":"693.872154ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:39.333067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.643429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:33:39.334195Z","caller":"traceutil/trace.go:171","msg":"trace[626240177] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.312182375s","start":"2026-02-24T04:33:38.021951Z","end":"2026-02-24T04:33:39.334134Z","steps":["trace[626240177] 'process raft request' (duration: 868.314703ms)","trace[626240177] 'compare' (duration: 441.17106ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:39.334508Z","caller":"traceutil/trace.go:171","msg":"trace[1586168006] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.312407441s","start":"2026-02-24T04:33:38.022059Z","end":"2026-02-24T04:33:39.334467Z","steps":["trace[1586168006] 'process raft request' (duration: 1.311173338s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:39.334522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:38.021944Z","time spent":"1.312423632s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:39.334605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:38.022054Z","time spent":"1.312503864s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:40.332232Z","caller":"traceutil/trace.go:171","msg":"trace[807166447] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"305.839722ms","start":"2026-02-24T04:33:40.026363Z","end":"2026-02-24T04:33:40.332202Z","steps":["trace[807166447] 'process raft request' (duration: 305.670828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:40.332417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:40.026322Z","time spent":"305.981246ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:05.601695Z","caller":"traceutil/trace.go:171","msg":"trace[1134100424] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"112.883994ms","start":"2026-02-24T04:34:05.488783Z","end":"2026-02-24T04:34:05.601667Z","steps":["trace[1134100424] 'process raft request' (duration: 112.73535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:06.913902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.066515ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:34:06.914025Z","caller":"traceutil/trace.go:171","msg":"trace[637339098] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"430.71111ms","start":"2026-02-24T04:34:06.483294Z","end":"2026-02-24T04:34:06.914005Z","steps":["trace[637339098] 'read index received' (duration: 7.46548ms)","trace[637339098] 'applied index is now lower than readState.Index' (duration: 423.24424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:06.914175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.955307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:06.914218Z","caller":"traceutil/trace.go:171","msg":"trace[1407206619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"250.05979ms","start":"2026-02-24T04:34:06.664141Z","end":"2026-02-24T04:34:06.9142Z","steps":["trace[1407206619] 'agreement among raft nodes before linearized reading' (duration: 249.975218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:06.914259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.957196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:34:06.914331Z","caller":"traceutil/trace.go:171","msg":"trace[1882445589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"431.070849ms","start":"2026-02-24T04:34:06.483245Z","end":"2026-02-24T04:34:06.914316Z","steps":["trace[1882445589] 'agreement among raft nodes before linearized reading' (duration: 430.856433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:06.914376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:06.483209Z","time spent":"431.157331ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:34:09.174914Z","caller":"traceutil/trace.go:171","msg":"trace[781586073] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"165.873594ms","start":"2026-02-24T04:34:09.009005Z","end":"2026-02-24T04:34:09.174878Z","steps":["trace[781586073] 'read index received' (duration: 165.671409ms)","trace[781586073] 'applied index is now lower than readState.Index' (duration: 200.805µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:09.174954Z","caller":"traceutil/trace.go:171","msg":"trace[369580194] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"209.36574ms","start":"2026-02-24T04:34:08.965549Z","end":"2026-02-24T04:34:09.174914Z","steps":["trace[369580194] 'process raft request' (duration: 209.119453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:09.175186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.160961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:34:09.17527Z","caller":"traceutil/trace.go:171","msg":"trace[888650213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"166.278475ms","start":"2026-02-24T04:34:09.008971Z","end":"2026-02-24T04:34:09.175249Z","steps":["trace[888650213] 'agreement among raft nodes before linearized reading' (duration: 166.038719ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:09.623469Z","caller":"traceutil/trace.go:171","msg":"trace[1935418221] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"116.102881ms","start":"2026-02-24T04:34:09.507337Z","end":"2026-02-24T04:34:09.62344Z","steps":["trace[1935418221] 'read index received' (duration: 54.914251ms)","trace[1935418221] 'applied index is now lower than readState.Index' (duration: 61.18754ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:09.623483Z","caller":"traceutil/trace.go:171","msg":"trace[1145561377] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"443.835852ms","start":"2026-02-24T04:34:09.179609Z","end":"2026-02-24T04:34:09.623445Z","steps":["trace[1145561377] 'process raft request' (duration: 382.699264ms)","trace[1145561377] 'compare' (duration: 61.001844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:09.623704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.345737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:34:09.623745Z","caller":"traceutil/trace.go:171","msg":"trace[1345177232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"116.458831ms","start":"2026-02-24T04:34:09.507275Z","end":"2026-02-24T04:34:09.623734Z","steps":["trace[1345177232] 'agreement among raft nodes before linearized reading' (duration: 116.267745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:09.623926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:09.179583Z","time spent":"444.232752ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:10.144824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349752170648016,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:34:10.399647Z","caller":"traceutil/trace.go:171","msg":"trace[58724733] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"755.80354ms","start":"2026-02-24T04:34:09.643814Z","end":"2026-02-24T04:34:10.399617Z","steps":["trace[58724733] 'read index received' (duration: 703.475418ms)","trace[58724733] 'applied index is now lower than readState.Index' (duration: 52.327162ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:10.399914Z","caller":"traceutil/trace.go:171","msg":"trace[870261330] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"771.324836ms","start":"2026-02-24T04:34:09.628573Z","end":"2026-02-24T04:34:10.399898Z","steps":["trace[870261330] 'process raft request' (duration: 718.83966ms)","trace[870261330] 'compare' (duration: 51.87453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:10.400083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.849032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:10.400171Z","caller":"traceutil/trace.go:171","msg":"trace[2122164448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"737.004966ms","start":"2026-02-24T04:34:09.663145Z","end":"2026-02-24T04:34:10.40015Z","steps":["trace[2122164448] 'agreement among raft nodes before linearized reading' (duration: 736.860872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:10.400211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:09.663121Z","time spent":"737.083159ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:34:10.400245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.831822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:34:10.40032Z","caller":"traceutil/trace.go:171","msg":"trace[1056243979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"737.063318ms","start":"2026-02-24T04:34:09.663244Z","end":"2026-02-24T04:34:10.400307Z","steps":["trace[1056243979] 'agreement among raft nodes before linearized reading' (duration: 736.7696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:10.400385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:09.663232Z","time spent":"737.113509ms","remote":"127.0.0.1:49006","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-02-24T04:34:10.4009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:09.62855Z","time spent":"771.394938ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:10.400916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.094834ms","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-02-24T04:34:10.400966Z","caller":"traceutil/trace.go:171","msg":"trace[1922055419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"757.164697ms","start":"2026-02-24T04:34:09.643787Z","end":"2026-02-24T04:34:10.400952Z","steps":["trace[1922055419] 'agreement among raft nodes before linearized reading' (duration: 757.034293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:10.400996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:09.643773Z","time spent":"757.216338ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:34:10.653404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.110374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:34:10.65354Z","caller":"traceutil/trace.go:171","msg":"trace[308523906] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"247.882281ms","start":"2026-02-24T04:34:10.405635Z","end":"2026-02-24T04:34:10.653518Z","steps":["trace[308523906] 'process raft request' (duration: 135.517041ms)","trace[308523906] 'compare' (duration: 111.96567ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:10.681495Z","caller":"traceutil/trace.go:171","msg":"trace[487786805] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"275.196093ms","start":"2026-02-24T04:34:10.406278Z","end":"2026-02-24T04:34:10.681474Z","steps":["trace[487786805] 'process raft request' (duration: 274.751451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:26.972366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349752170648236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:34:27.473193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349752170648236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:34:27.64193Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.204628304s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T04:34:28.281421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.151174ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:34:28.281532Z","caller":"traceutil/trace.go:171","msg":"trace[1652666174] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"1.810109486s","start":"2026-02-24T04:34:26.471404Z","end":"2026-02-24T04:34:28.281513Z","steps":["trace[1652666174] 'read index received' (duration: 1.170817448s)","trace[1652666174] 'applied index is now lower than readState.Index' (duration: 639.290748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:28.281757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810353983s","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-02-24T04:34:28.281699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.626115ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:28.281785Z","caller":"traceutil/trace.go:171","msg":"trace[150690919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"1.810437185s","start":"2026-02-24T04:34:26.471339Z","end":"2026-02-24T04:34:28.281776Z","steps":["trace[150690919] 'agreement among raft nodes before linearized reading' (duration: 1.810284191s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.28182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:26.471313Z","time spent":"1.810497896s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:34:28.282049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488663884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:28.282079Z","caller":"traceutil/trace.go:171","msg":"trace[428040600] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:869; }","duration":"1.488732226s","start":"2026-02-24T04:34:26.793336Z","end":"2026-02-24T04:34:28.282068Z","steps":["trace[428040600] 'agreement among raft nodes before linearized reading' (duration: 1.488691025s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.2821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:26.793316Z","time spent":"1.488775997s","remote":"127.0.0.1:49242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:28.282292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486194168s","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-02-24T04:34:28.282311Z","caller":"traceutil/trace.go:171","msg":"trace[909422724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"1.486235559s","start":"2026-02-24T04:34:26.796069Z","end":"2026-02-24T04:34:28.282305Z","steps":["trace[909422724] 'agreement among raft nodes before linearized reading' (duration: 1.486177577s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.282304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381316249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:34:28.282329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:26.796062Z","time spent":"1.486263429s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:34:28.282346Z","caller":"traceutil/trace.go:171","msg":"trace[1131734272] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:869; }","duration":"1.381432121s","start":"2026-02-24T04:34:26.900901Z","end":"2026-02-24T04:34:28.282333Z","steps":["trace[1131734272] 'agreement among raft nodes before linearized reading' (duration: 1.381325889s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.28238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:26.900877Z","time spent":"1.381496152s","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:28.282455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486713322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:34:28.282476Z","caller":"traceutil/trace.go:171","msg":"trace[319131600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"1.486778313s","start":"2026-02-24T04:34:26.795692Z","end":"2026-02-24T04:34:28.28247Z","steps":["trace[319131600] 'agreement among raft nodes before linearized reading' (duration: 1.486719131s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.2825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:26.79567Z","time spent":"1.486825755s","remote":"127.0.0.1:49006","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-02-24T04:34:28.282678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.459144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:34:28.282706Z","caller":"traceutil/trace.go:171","msg":"trace[1301768569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"492.517495ms","start":"2026-02-24T04:34:27.790181Z","end":"2026-02-24T04:34:28.282698Z","steps":["trace[1301768569] 'agreement among raft nodes before linearized reading' (duration: 492.378321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.282727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:27.790157Z","time spent":"492.564447ms","remote":"127.0.0.1:49006","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-02-24T04:34:28.282837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.478625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:34:28.282854Z","caller":"traceutil/trace.go:171","msg":"trace[410986671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"978.536597ms","start":"2026-02-24T04:34:27.304312Z","end":"2026-02-24T04:34:28.282848Z","steps":["trace[410986671] 'agreement among raft nodes before linearized reading' (duration: 978.463935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.282864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.317813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:34:28.282873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:27.304285Z","time spent":"978.583458ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:34:28.282888Z","caller":"traceutil/trace.go:171","msg":"trace[898154349] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:869; }","duration":"516.366205ms","start":"2026-02-24T04:34:27.766514Z","end":"2026-02-24T04:34:28.282881Z","steps":["trace[898154349] 'agreement among raft nodes before linearized reading' (duration: 516.317364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.282913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:27.766497Z","time spent":"516.410656ms","remote":"127.0.0.1:49194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:28.282864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.599193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:28.283007Z","caller":"traceutil/trace.go:171","msg":"trace[142121905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"618.789968ms","start":"2026-02-24T04:34:27.664179Z","end":"2026-02-24T04:34:28.282969Z","steps":["trace[142121905] 'agreement among raft nodes before linearized reading' (duration: 618.521181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:28.283075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:27.66416Z","time spent":"618.899282ms","remote":"127.0.0.1:48750","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-02-24T04:34:28.28312Z","caller":"traceutil/trace.go:171","msg":"trace[1924761869] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:869; }","duration":"303.753008ms","start":"2026-02-24T04:34:27.978042Z","end":"2026-02-24T04:34:28.281795Z","steps":["trace[1924761869] 'range keys from in-memory index tree' (duration: 303.610474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:40.886617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.806892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:34:40.886831Z","caller":"traceutil/trace.go:171","msg":"trace[2044372932] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"417.498466ms","start":"2026-02-24T04:34:40.469304Z","end":"2026-02-24T04:34:40.886803Z","steps":["trace[2044372932] 'process raft request' (duration: 36.396456ms)","trace[2044372932] 'compare' (duration: 380.680679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:40.887499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:40.469295Z","time spent":"418.168834ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:40.888098Z","caller":"traceutil/trace.go:171","msg":"trace[651883346] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:943; }","duration":"413.555671ms","start":"2026-02-24T04:34:40.474529Z","end":"2026-02-24T04:34:40.888085Z","steps":["trace[651883346] 'read index received' (duration: 31.291589ms)","trace[651883346] 'applied index is now lower than readState.Index' (duration: 382.263042ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:40.888158Z","caller":"traceutil/trace.go:171","msg":"trace[657950480] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"417.238529ms","start":"2026-02-24T04:34:40.470883Z","end":"2026-02-24T04:34:40.888121Z","steps":["trace[657950480] 'process raft request' (duration: 417.040193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:40.88835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:40.470877Z","time spent":"417.367242ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:40.888386Z","caller":"traceutil/trace.go:171","msg":"trace[1023512897] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"418.922645ms","start":"2026-02-24T04:34:40.469418Z","end":"2026-02-24T04:34:40.88834Z","steps":["trace[1023512897] 'process raft request' (duration: 417.352752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:40.888561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:40.469414Z","time spent":"419.055628ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:40.888599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.057044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:34:40.88864Z","caller":"traceutil/trace.go:171","msg":"trace[45158324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"414.113005ms","start":"2026-02-24T04:34:40.474516Z","end":"2026-02-24T04:34:40.888629Z","steps":["trace[45158324] 'agreement among raft nodes before linearized reading' (duration: 413.954411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:40.888673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:40.474511Z","time spent":"414.155776ms","remote":"127.0.0.1:49006","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-02-24T04:34:40.888804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.826477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:40.888831Z","caller":"traceutil/trace.go:171","msg":"trace[1314959540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"223.879418ms","start":"2026-02-24T04:34:40.664943Z","end":"2026-02-24T04:34:40.888823Z","steps":["trace[1314959540] 'agreement among raft nodes before linearized reading' (duration: 223.833407ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:46.243565Z","caller":"traceutil/trace.go:171","msg":"trace[1517919731] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"445.257229ms","start":"2026-02-24T04:34:45.798282Z","end":"2026-02-24T04:34:46.243539Z","steps":["trace[1517919731] 'process raft request' (duration: 445.194218ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:46.243613Z","caller":"traceutil/trace.go:171","msg":"trace[1930825435] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"451.145537ms","start":"2026-02-24T04:34:45.792446Z","end":"2026-02-24T04:34:46.243592Z","steps":["trace[1930825435] 'process raft request' (duration: 450.865029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:46.243734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:45.798249Z","time spent":"445.406203ms","remote":"127.0.0.1:49220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:46.243734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:45.792432Z","time spent":"451.23212ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:48.268247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925719894s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:34:48.268483Z","caller":"traceutil/trace.go:171","msg":"trace[581361466] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"1.693251045s","start":"2026-02-24T04:34:46.575205Z","end":"2026-02-24T04:34:48.268456Z","steps":["trace[581361466] 'read index received' (duration: 36.131µs)","trace[581361466] 'applied index is now lower than readState.Index' (duration: 1.693212104s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:48.268671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.962968ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:48.268782Z","caller":"traceutil/trace.go:171","msg":"trace[360166854] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:948; }","duration":"290.086882ms","start":"2026-02-24T04:34:47.978678Z","end":"2026-02-24T04:34:48.268765Z","steps":["trace[360166854] 'range keys from in-memory index tree' (duration: 289.948208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.268772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636603147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:34:48.268837Z","caller":"traceutil/trace.go:171","msg":"trace[1828513183] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:948; }","duration":"1.636679119s","start":"2026-02-24T04:34:46.632138Z","end":"2026-02-24T04:34:48.268817Z","steps":["trace[1828513183] 'agreement among raft nodes before linearized reading' (duration: 1.636551146s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.268899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.632059Z","time spent":"1.636823283s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:48.268932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353581256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T04:34:48.268986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693749779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:34:48.269047Z","caller":"traceutil/trace.go:171","msg":"trace[1117330456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"1.35372892s","start":"2026-02-24T04:34:46.915291Z","end":"2026-02-24T04:34:48.26902Z","steps":["trace[1117330456] 'agreement among raft nodes before linearized reading' (duration: 1.353394651s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.269078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356668288s","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-02-24T04:34:48.269108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.915285Z","time spent":"1.353812812s","remote":"127.0.0.1:49006","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":"info","ts":"2026-02-24T04:34:48.269103Z","caller":"traceutil/trace.go:171","msg":"trace[314829197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"1.693919063s","start":"2026-02-24T04:34:46.575156Z","end":"2026-02-24T04:34:48.269075Z","steps":["trace[314829197] 'agreement among raft nodes before linearized reading' (duration: 1.693599674s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:48.269126Z","caller":"traceutil/trace.go:171","msg":"trace[241814639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"1.35672588s","start":"2026-02-24T04:34:46.912384Z","end":"2026-02-24T04:34:48.26911Z","steps":["trace[241814639] 'agreement among raft nodes before linearized reading' (duration: 1.356579856s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.269162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.912378Z","time spent":"1.356776831s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:34:48.269166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.575132Z","time spent":"1.694024056s","remote":"127.0.0.1:49006","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-02-24T04:34:48.269195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606194414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:34:48.268999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35709628s","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-02-24T04:34:48.269257Z","caller":"traceutil/trace.go:171","msg":"trace[1147916867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"1.606279746s","start":"2026-02-24T04:34:46.662959Z","end":"2026-02-24T04:34:48.269238Z","steps":["trace[1147916867] 'agreement among raft nodes before linearized reading' (duration: 1.606191334s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.269311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359220507s","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-02-24T04:34:48.269336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.662946Z","time spent":"1.606375879s","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:34:48.269287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445595361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:48.269353Z","caller":"traceutil/trace.go:171","msg":"trace[1799988192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"1.357425209s","start":"2026-02-24T04:34:46.91187Z","end":"2026-02-24T04:34:48.269296Z","steps":["trace[1799988192] 'agreement among raft nodes before linearized reading' (duration: 1.356812213s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:48.26945Z","caller":"traceutil/trace.go:171","msg":"trace[263684675] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:948; }","duration":"1.446190817s","start":"2026-02-24T04:34:46.82323Z","end":"2026-02-24T04:34:48.269421Z","steps":["trace[263684675] 'agreement among raft nodes before linearized reading' (duration: 1.445597971s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.269539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.823211Z","time spent":"1.44631217s","remote":"127.0.0.1:48956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:48.269567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.911848Z","time spent":"1.357652114s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:34:48.269345Z","caller":"traceutil/trace.go:171","msg":"trace[1284634844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"1.359292759s","start":"2026-02-24T04:34:46.910041Z","end":"2026-02-24T04:34:48.269334Z","steps":["trace[1284634844] 'agreement among raft nodes before linearized reading' (duration: 1.359202747s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.26975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:46.91002Z","time spent":"1.35970858s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:34:48.757101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.770965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:34:48.757374Z","caller":"traceutil/trace.go:171","msg":"trace[1732796188] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"480.346439ms","start":"2026-02-24T04:34:48.277007Z","end":"2026-02-24T04:34:48.757353Z","steps":["trace[1732796188] 'process raft request' (duration: 480.275817ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:48.757478Z","caller":"traceutil/trace.go:171","msg":"trace[736629307] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"480.954686ms","start":"2026-02-24T04:34:48.276481Z","end":"2026-02-24T04:34:48.757436Z","steps":["trace[736629307] 'process raft request' (duration: 480.742ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:48.757608Z","caller":"traceutil/trace.go:171","msg":"trace[866357591] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:990; }","duration":"479.026554ms","start":"2026-02-24T04:34:48.27857Z","end":"2026-02-24T04:34:48.757596Z","steps":["trace[866357591] 'read index received' (duration: 16.582464ms)","trace[866357591] 'applied index is now lower than readState.Index' (duration: 462.44238ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:48.75772Z","caller":"traceutil/trace.go:171","msg":"trace[1327712861] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"479.668061ms","start":"2026-02-24T04:34:48.278044Z","end":"2026-02-24T04:34:48.757712Z","steps":["trace[1327712861] 'process raft request' (duration: 479.27151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.757705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:48.276464Z","time spent":"481.1136ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:48.757786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:48.278019Z","time spent":"479.733253ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:48.757946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.376593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:48.757981Z","caller":"traceutil/trace.go:171","msg":"trace[1702547834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"479.437025ms","start":"2026-02-24T04:34:48.278534Z","end":"2026-02-24T04:34:48.757971Z","steps":["trace[1702547834] 'agreement among raft nodes before linearized reading' (duration: 479.378563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.758045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:48.278524Z","time spent":"479.479347ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:34:48.758044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.550013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:34:48.758104Z","caller":"traceutil/trace.go:171","msg":"trace[1241041427] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:953; }","duration":"437.647366ms","start":"2026-02-24T04:34:48.320438Z","end":"2026-02-24T04:34:48.758086Z","steps":["trace[1241041427] 'agreement among raft nodes before linearized reading' (duration: 437.530633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.758162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:48.320414Z","time spent":"437.732738ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-24T04:34:48.757465Z","caller":"traceutil/trace.go:171","msg":"trace[1030437108] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"481.813949ms","start":"2026-02-24T04:34:48.275635Z","end":"2026-02-24T04:34:48.757449Z","steps":["trace[1030437108] 'process raft request' (duration: 129.302754ms)","trace[1030437108] 'compare' (duration: 351.381405ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:48.758452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:48.275507Z","time spent":"482.836966ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:48.75851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.330522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:34:48.758551Z","caller":"traceutil/trace.go:171","msg":"trace[766683453] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:953; }","duration":"211.435045ms","start":"2026-02-24T04:34:48.547103Z","end":"2026-02-24T04:34:48.758538Z","steps":["trace[766683453] 'agreement among raft nodes before linearized reading' (duration: 211.358443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.758613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.062793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-24T04:34:48.758657Z","caller":"traceutil/trace.go:171","msg":"trace[331480948] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:953; }","duration":"115.167996ms","start":"2026-02-24T04:34:48.643482Z","end":"2026-02-24T04:34:48.75865Z","steps":["trace[331480948] 'agreement among raft nodes before linearized reading' (duration: 115.043363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:48.759231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:48.276996Z","time spent":"480.530194ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:50.501866Z","caller":"traceutil/trace.go:171","msg":"trace[2059273239] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"194.859302ms","start":"2026-02-24T04:34:50.306974Z","end":"2026-02-24T04:34:50.501833Z","steps":["trace[2059273239] 'process raft request' (duration: 194.661366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:50.969561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.972557ms","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-02-24T04:34:50.969677Z","caller":"traceutil/trace.go:171","msg":"trace[186785061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"204.07914ms","start":"2026-02-24T04:34:50.765547Z","end":"2026-02-24T04:34:50.969626Z","steps":["trace[186785061] 'range keys from in-memory index tree' (duration: 203.796092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:50.969732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.121963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:34:50.969845Z","caller":"traceutil/trace.go:171","msg":"trace[1226103796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"206.306398ms","start":"2026-02-24T04:34:50.763511Z","end":"2026-02-24T04:34:50.969817Z","steps":["trace[1226103796] 'range keys from in-memory index tree' (duration: 205.811915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:50.969999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.359953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:34:50.970034Z","caller":"traceutil/trace.go:171","msg":"trace[1967971337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"205.456776ms","start":"2026-02-24T04:34:50.764565Z","end":"2026-02-24T04:34:50.970022Z","steps":["trace[1967971337] 'range keys from in-memory index tree' (duration: 205.205729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:50.97017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.429705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:34:50.970194Z","caller":"traceutil/trace.go:171","msg":"trace[1775099702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"205.453035ms","start":"2026-02-24T04:34:50.764734Z","end":"2026-02-24T04:34:50.970187Z","steps":["trace[1775099702] 'range keys from in-memory index tree' (duration: 205.302702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:50.970397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.693507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:50.970439Z","caller":"traceutil/trace.go:171","msg":"trace[1420198164] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:955; }","duration":"209.78499ms","start":"2026-02-24T04:34:50.760638Z","end":"2026-02-24T04:34:50.970423Z","steps":["trace[1420198164] 'count revisions from in-memory index tree' (duration: 209.560533ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:51.165678Z","caller":"traceutil/trace.go:171","msg":"trace[1918629656] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"190.772801ms","start":"2026-02-24T04:34:50.974877Z","end":"2026-02-24T04:34:51.16565Z","steps":["trace[1918629656] 'process raft request' (duration: 190.619577ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:51.280625Z","caller":"traceutil/trace.go:171","msg":"trace[1266463139] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"305.054194ms","start":"2026-02-24T04:34:50.975543Z","end":"2026-02-24T04:34:51.280597Z","steps":["trace[1266463139] 'process raft request' (duration: 304.938211ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:51.280636Z","caller":"traceutil/trace.go:171","msg":"trace[885056463] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"305.552597ms","start":"2026-02-24T04:34:50.975048Z","end":"2026-02-24T04:34:51.2806Z","steps":["trace[885056463] 'process raft request' (duration: 305.154637ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:51.280809Z","caller":"traceutil/trace.go:171","msg":"trace[273612508] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"192.928818ms","start":"2026-02-24T04:34:51.087865Z","end":"2026-02-24T04:34:51.280794Z","steps":["trace[273612508] 'read index received' (duration: 77.791133ms)","trace[273612508] 'applied index is now lower than readState.Index' (duration: 115.136165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:51.280801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:50.975527Z","time spent":"305.178687ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:51.280906Z","caller":"traceutil/trace.go:171","msg":"trace[1513601253] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"304.977092ms","start":"2026-02-24T04:34:50.975878Z","end":"2026-02-24T04:34:51.280855Z","steps":["trace[1513601253] 'process raft request' (duration: 304.676364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:51.28099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.120734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T04:34:51.281017Z","caller":"traceutil/trace.go:171","msg":"trace[1671408063] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:959; }","duration":"193.186165ms","start":"2026-02-24T04:34:51.087822Z","end":"2026-02-24T04:34:51.281009Z","steps":["trace[1671408063] 'agreement among raft nodes before linearized reading' (duration: 193.037731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:51.281142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:50.975851Z","time spent":"305.171657ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:51.281129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:50.975041Z","time spent":"305.856805ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:51.313284Z","caller":"traceutil/trace.go:171","msg":"trace[1786304032] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"100.302095ms","start":"2026-02-24T04:34:51.212947Z","end":"2026-02-24T04:34:51.31325Z","steps":["trace[1786304032] 'process raft request' (duration: 100.088509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:51.313353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.645365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:51.313853Z","caller":"traceutil/trace.go:171","msg":"trace[106464687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"157.191299ms","start":"2026-02-24T04:34:51.156607Z","end":"2026-02-24T04:34:51.313799Z","steps":["trace[106464687] 'agreement among raft nodes before linearized reading' (duration: 156.630214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:52.106987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.571971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:52.107177Z","caller":"traceutil/trace.go:171","msg":"trace[1139083372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"443.851498ms","start":"2026-02-24T04:34:51.663302Z","end":"2026-02-24T04:34:52.107154Z","steps":["trace[1139083372] 'range keys from in-memory index tree' (duration: 443.456578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:52.107266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:51.663271Z","time spent":"443.981702ms","remote":"127.0.0.1:48764","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-02-24T04:34:52.411099Z","caller":"traceutil/trace.go:171","msg":"trace[1968732079] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"309.10534ms","start":"2026-02-24T04:34:52.101963Z","end":"2026-02-24T04:34:52.411068Z","steps":["trace[1968732079] 'process raft request' (duration: 308.924855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:52.4113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:52.101916Z","time spent":"309.277144ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:53.004304Z","caller":"traceutil/trace.go:171","msg":"trace[406476988] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"339.628743ms","start":"2026-02-24T04:34:52.664642Z","end":"2026-02-24T04:34:53.004271Z","steps":["trace[406476988] 'read index received' (duration: 339.247423ms)","trace[406476988] 'applied index is now lower than readState.Index' (duration: 380.3µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:53.00434Z","caller":"traceutil/trace.go:171","msg":"trace[17274711] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"493.961107ms","start":"2026-02-24T04:34:52.510345Z","end":"2026-02-24T04:34:53.004306Z","steps":["trace[17274711] 'process raft request' (duration: 493.599438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:53.00448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.808858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:53.004529Z","caller":"traceutil/trace.go:171","msg":"trace[792095230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"339.92116ms","start":"2026-02-24T04:34:52.664592Z","end":"2026-02-24T04:34:53.004513Z","steps":["trace[792095230] 'agreement among raft nodes before linearized reading' (duration: 339.783277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:53.004575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:52.664569Z","time spent":"339.993983ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:34:53.004574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:52.510311Z","time spent":"494.141082ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:53.657305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.4753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:34:53.657421Z","caller":"traceutil/trace.go:171","msg":"trace[807225415] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"292.374719ms","start":"2026-02-24T04:34:53.365029Z","end":"2026-02-24T04:34:53.657403Z","steps":["trace[807225415] 'process raft request' (duration: 116.691805ms)","trace[807225415] 'compare' (duration: 175.313415ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:53.673465Z","caller":"traceutil/trace.go:171","msg":"trace[265735747] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1008; }","duration":"271.763249ms","start":"2026-02-24T04:34:53.40168Z","end":"2026-02-24T04:34:53.673444Z","steps":["trace[265735747] 'read index received' (duration: 80.035443ms)","trace[265735747] 'applied index is now lower than readState.Index' (duration: 191.726546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:53.673604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.917602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:53.673647Z","caller":"traceutil/trace.go:171","msg":"trace[541985935] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:968; }","duration":"272.019866ms","start":"2026-02-24T04:34:53.401613Z","end":"2026-02-24T04:34:53.673633Z","steps":["trace[541985935] 'agreement among raft nodes before linearized reading' (duration: 271.918753ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:53.673914Z","caller":"traceutil/trace.go:171","msg":"trace[1325535222] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"308.318779ms","start":"2026-02-24T04:34:53.365173Z","end":"2026-02-24T04:34:53.673491Z","steps":["trace[1325535222] 'process raft request' (duration: 308.116734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:53.674049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:53.365136Z","time spent":"308.848693ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:53.674241Z","caller":"traceutil/trace.go:171","msg":"trace[306175277] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"307.850906ms","start":"2026-02-24T04:34:53.366365Z","end":"2026-02-24T04:34:53.674216Z","steps":["trace[306175277] 'process raft request' (duration: 307.046655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:53.674367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:53.366336Z","time spent":"307.968089ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:55.167515Z","caller":"traceutil/trace.go:171","msg":"trace[1301979828] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"153.915103ms","start":"2026-02-24T04:34:55.013567Z","end":"2026-02-24T04:34:55.167482Z","steps":["trace[1301979828] 'process raft request' (duration: 153.463891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:55.564267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.602203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:34:55.564507Z","caller":"traceutil/trace.go:171","msg":"trace[1899254736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"309.849959ms","start":"2026-02-24T04:34:55.254621Z","end":"2026-02-24T04:34:55.56447Z","steps":["trace[1899254736] 'range keys from in-memory index tree' (duration: 309.357246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:55.564787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:55.254546Z","time spent":"310.221769ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:34:55.934385Z","caller":"traceutil/trace.go:171","msg":"trace[914238042] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"271.109231ms","start":"2026-02-24T04:34:55.663247Z","end":"2026-02-24T04:34:55.934356Z","steps":["trace[914238042] 'read index received' (duration: 270.974588ms)","trace[914238042] 'applied index is now lower than readState.Index' (duration: 133.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:55.934537Z","caller":"traceutil/trace.go:171","msg":"trace[925056329] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"364.580148ms","start":"2026-02-24T04:34:55.569895Z","end":"2026-02-24T04:34:55.934475Z","steps":["trace[925056329] 'process raft request' (duration: 364.28597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:55.934681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.399419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T04:34:55.934719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:55.56987Z","time spent":"364.744181ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:34:55.934774Z","caller":"traceutil/trace.go:171","msg":"trace[1713532100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"271.539043ms","start":"2026-02-24T04:34:55.663213Z","end":"2026-02-24T04:34:55.934752Z","steps":["trace[1713532100] 'agreement among raft nodes before linearized reading' (duration: 271.285666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:55.934829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.873441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T04:34:55.934868Z","caller":"traceutil/trace.go:171","msg":"trace[358726032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"255.921933ms","start":"2026-02-24T04:34:55.678932Z","end":"2026-02-24T04:34:55.934854Z","steps":["trace[358726032] 'agreement among raft nodes before linearized reading' (duration: 255.83193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:55.93473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.983074ms","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-02-24T04:34:55.934923Z","caller":"traceutil/trace.go:171","msg":"trace[1413718214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"256.19657ms","start":"2026-02-24T04:34:55.67871Z","end":"2026-02-24T04:34:55.934907Z","steps":["trace[1413718214] 'agreement among raft nodes before linearized reading' (duration: 255.860241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:57.143171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.379047ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:34:57.143518Z","caller":"traceutil/trace.go:171","msg":"trace[1667807076] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"478.767787ms","start":"2026-02-24T04:34:56.664726Z","end":"2026-02-24T04:34:57.143494Z","steps":["trace[1667807076] 'read index received' (duration: 226.48393ms)","trace[1667807076] 'applied index is now lower than readState.Index' (duration: 252.282507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:57.143847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.87435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:57.143856Z","caller":"traceutil/trace.go:171","msg":"trace[1197412442] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"674.56469ms","start":"2026-02-24T04:34:56.469262Z","end":"2026-02-24T04:34:57.143827Z","steps":["trace[1197412442] 'process raft request' (duration: 674.067157ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:57.14389Z","caller":"traceutil/trace.go:171","msg":"trace[502301569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"479.231959ms","start":"2026-02-24T04:34:56.664645Z","end":"2026-02-24T04:34:57.143877Z","steps":["trace[502301569] 'agreement among raft nodes before linearized reading' (duration: 478.906201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:57.143931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:56.664608Z","time spent":"479.313551ms","remote":"127.0.0.1:48764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:34:57.144024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:56.469227Z","time spent":"674.691723ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:57.657615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.575315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:34:57.657742Z","caller":"traceutil/trace.go:171","msg":"trace[1077079363] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"480.132881ms","start":"2026-02-24T04:34:57.177587Z","end":"2026-02-24T04:34:57.65772Z","steps":["trace[1077079363] 'process raft request' (duration: 208.386762ms)","trace[1077079363] 'compare' (duration: 271.319138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:57.657812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:57.177559Z","time spent":"480.219744ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:35:02.403745Z","caller":"traceutil/trace.go:171","msg":"trace[1620213465] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"331.784975ms","start":"2026-02-24T04:35:02.071911Z","end":"2026-02-24T04:35:02.403696Z","steps":["trace[1620213465] 'process raft request' (duration: 331.617101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:02.40393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:02.071898Z","time spent":"331.946979ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:35:02.410033Z","caller":"traceutil/trace.go:171","msg":"trace[1726038791] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"337.129056ms","start":"2026-02-24T04:35:02.072863Z","end":"2026-02-24T04:35:02.409992Z","steps":["trace[1726038791] 'process raft request' (duration: 337.068184ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:02.410116Z","caller":"traceutil/trace.go:171","msg":"trace[1731911720] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"337.599068ms","start":"2026-02-24T04:35:02.072465Z","end":"2026-02-24T04:35:02.410064Z","steps":["trace[1731911720] 'process raft request' (duration: 337.274309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:02.410263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:02.072832Z","time spent":"337.309961ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:35:02.41046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:02.072442Z","time spent":"337.844225ms","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}