{"level":"warn","ts":"2026-03-02T02:06:03.245691Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:06:03.245768Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-02T02:06:03.245827Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:06:03.245836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-02T02:06:03.245854Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T02:06:03.246516Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-03-02T02:06:03.246587Z","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.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-02T02:06:03.252766Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.895238ms"} {"level":"info","ts":"2026-03-02T02:06:03.263251Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-03-02T02:06:03.26332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T02:06:03.263341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-03-02T02:06:03.263351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T02:06:03.263362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-03-02T02:06:03.263407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-03-02T02:06:03.269827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T02:06:03.271001Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T02:06:03.272392Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-02T02:06:03.27507Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T02:06:03.275179Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T02:06:03.275259Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T02:06:03.275276Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T02:06:03.275546Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T02:06:03.279241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-03-02T02:06:03.27962Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-02T02:06:03.28087Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T02:06:03.280978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-02T02:06:03.28101Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-02T02:06:03.281126Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T02:06:03.28115Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T02:06:04.063655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T02:06:04.063783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T02:06:04.063816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-03-02T02:06:04.063841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-03-02T02:06:04.063852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-02T02:06:04.06388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-03-02T02:06:04.0639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-02T02:06:04.064937Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:04.065849Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T02:06:04.065866Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:06:04.0659Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:06:04.066576Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:04.06673Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:04.066781Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:06:04.06979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-03-02T02:06:04.070073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T02:06:04.073376Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T02:06:04.073411Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T02:06:18.959562Z","caller":"traceutil/trace.go:171","msg":"trace[355475991] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"276.58322ms","start":"2026-03-02T02:06:18.682954Z","end":"2026-03-02T02:06:18.959537Z","steps":["trace[355475991] 'process raft request' (duration: 276.459386ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:18.959583Z","caller":"traceutil/trace.go:171","msg":"trace[1740025272] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"290.776747ms","start":"2026-03-02T02:06:18.668764Z","end":"2026-03-02T02:06:18.95954Z","steps":["trace[1740025272] 'process raft request' (duration: 223.875127ms)","trace[1740025272] 'compare' (duration: 66.615322ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:06:19.340958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.817567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4468"} {"level":"info","ts":"2026-03-02T02:06:19.341116Z","caller":"traceutil/trace.go:171","msg":"trace[617885982] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:376; }","duration":"113.050933ms","start":"2026-03-02T02:06:19.228041Z","end":"2026-03-02T02:06:19.341092Z","steps":["trace[617885982] 'range keys from in-memory index tree' (duration: 112.699304ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:20.764892Z","caller":"traceutil/trace.go:171","msg":"trace[1209410945] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"139.982446ms","start":"2026-03-02T02:06:20.624887Z","end":"2026-03-02T02:06:20.76487Z","steps":["trace[1209410945] 'process raft request' (duration: 139.851002ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:21.203053Z","caller":"traceutil/trace.go:171","msg":"trace[1587395211] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"123.006424ms","start":"2026-03-02T02:06:21.080019Z","end":"2026-03-02T02:06:21.203026Z","steps":["trace[1587395211] 'process raft request' (duration: 122.84108ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:21.917091Z","caller":"traceutil/trace.go:171","msg":"trace[82530118] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"128.653108ms","start":"2026-03-02T02:06:21.788397Z","end":"2026-03-02T02:06:21.91705Z","steps":["trace[82530118] 'process raft request' (duration: 55.145989ms)","trace[82530118] 'compare' (duration: 73.312373ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:06:26.439603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995720364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:06:26.940865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995720364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:06:27.441243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995720364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:06:27.942295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995720364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:06:28.442903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995720364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:06:28.561172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999941546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T02:06:28.56128Z","caller":"traceutil/trace.go:171","msg":"trace[970206751] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00010518s","start":"2026-03-02T02:06:26.561144Z","end":"2026-03-02T02:06:28.561249Z","steps":["trace[970206751] 'agreement among raft nodes before linearized reading' (duration: 1.999975927s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:28.561341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:26.561122Z","time spent":"2.000204343s","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 02:06:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T02:06:28.788606Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.109325588s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:06:28.789137Z","caller":"traceutil/trace.go:171","msg":"trace[1448884307] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"2.850002027s","start":"2026-03-02T02:06:25.93911Z","end":"2026-03-02T02:06:28.789112Z","steps":["trace[1448884307] 'read index received' (duration: 2.84972308s)","trace[1448884307] 'applied index is now lower than readState.Index' (duration: 277.857µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:06:28.789196Z","caller":"traceutil/trace.go:171","msg":"trace[98394031] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"3.110052558s","start":"2026-03-02T02:06:25.679109Z","end":"2026-03-02T02:06:28.789162Z","steps":["trace[98394031] 'process raft request' (duration: 3.109848903s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:28.789302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.850171461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:06:28.789367Z","caller":"traceutil/trace.go:171","msg":"trace[2121763847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"2.850284824s","start":"2026-03-02T02:06:25.939067Z","end":"2026-03-02T02:06:28.789352Z","steps":["trace[2121763847] 'agreement among raft nodes before linearized reading' (duration: 2.850150381s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:28.789398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:25.939043Z","time spent":"2.850350277s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:06:28.789432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.866415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:06:28.789443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.568974927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-02T02:06:28.789493Z","caller":"traceutil/trace.go:171","msg":"trace[319908774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"227.968768ms","start":"2026-03-02T02:06:28.561508Z","end":"2026-03-02T02:06:28.789476Z","steps":["trace[319908774] 'agreement among raft nodes before linearized reading' (duration: 227.868335ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:28.790234Z","caller":"traceutil/trace.go:171","msg":"trace[1075769246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"2.5690837s","start":"2026-03-02T02:06:26.22041Z","end":"2026-03-02T02:06:28.789494Z","steps":["trace[1075769246] 'agreement among raft nodes before linearized reading' (duration: 2.568953647s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:28.790522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812138039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-02T02:06:28.79035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.830392875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T02:06:28.790602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:26.220395Z","time spent":"2.5701869s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T02:06:28.790673Z","caller":"traceutil/trace.go:171","msg":"trace[1330889058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"2.830738325s","start":"2026-03-02T02:06:25.959887Z","end":"2026-03-02T02:06:28.790626Z","steps":["trace[1330889058] 'agreement among raft nodes before linearized reading' (duration: 2.829435599s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:28.790672Z","caller":"traceutil/trace.go:171","msg":"trace[1848091501] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:420; }","duration":"1.812335015s","start":"2026-03-02T02:06:26.978278Z","end":"2026-03-02T02:06:28.790613Z","steps":["trace[1848091501] 'agreement among raft nodes before linearized reading' (duration: 1.81105909s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:28.790724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:25.959874Z","time spent":"2.830839067s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T02:06:28.790751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:26.978257Z","time spent":"1.812481849s","remote":"127.0.0.1:45620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-03-02T02:06:28.790753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97766175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T02:06:28.790822Z","caller":"traceutil/trace.go:171","msg":"trace[1653421832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:420; }","duration":"1.978967926s","start":"2026-03-02T02:06:26.811812Z","end":"2026-03-02T02:06:28.79078Z","steps":["trace[1653421832] 'agreement among raft nodes before linearized reading' (duration: 1.977545287s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:28.790862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:26.811792Z","time spent":"1.979055709s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:06:28.792223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:25.679077Z","time spent":"3.110225393s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:06:29.701878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.523184ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-02T02:06:29.70216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.792593Z","time spent":"909.5559ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T02:06:29.702309Z","caller":"traceutil/trace.go:171","msg":"trace[31334939] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"904.528014ms","start":"2026-03-02T02:06:28.797734Z","end":"2026-03-02T02:06:29.702262Z","steps":["trace[31334939] 'process raft request' (duration: 904.359269ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:29.702335Z","caller":"traceutil/trace.go:171","msg":"trace[1210049957] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"904.620745ms","start":"2026-03-02T02:06:28.797675Z","end":"2026-03-02T02:06:29.702296Z","steps":["trace[1210049957] 'process raft request' (duration: 904.310507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:29.702472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.79772Z","time spent":"904.645366ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:06:29.702507Z","caller":"traceutil/trace.go:171","msg":"trace[928154625] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"904.701038ms","start":"2026-03-02T02:06:28.797787Z","end":"2026-03-02T02:06:29.702488Z","steps":["trace[928154625] 'process raft request' (duration: 904.389319ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:29.702721Z","caller":"traceutil/trace.go:171","msg":"trace[853161101] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"903.354731ms","start":"2026-03-02T02:06:28.799157Z","end":"2026-03-02T02:06:29.702511Z","steps":["trace[853161101] 'process raft request' (duration: 903.277039ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:29.70276Z","caller":"traceutil/trace.go:171","msg":"trace[1828496725] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"904.952574ms","start":"2026-03-02T02:06:28.797782Z","end":"2026-03-02T02:06:29.702734Z","steps":["trace[1828496725] 'process raft request' (duration: 904.374059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:29.702806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.797782Z","time spent":"904.950194ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:06:29.702864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.797775Z","time spent":"905.029967ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:06:29.702907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.799151Z","time spent":"903.611628ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:06:29.702932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.32379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:06:29.702989Z","caller":"traceutil/trace.go:171","msg":"trace[1266061236] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:434; }","duration":"902.868558ms","start":"2026-03-02T02:06:28.799596Z","end":"2026-03-02T02:06:29.702465Z","steps":["trace[1266061236] 'read index received' (duration: 126.578242ms)","trace[1266061236] 'applied index is now lower than readState.Index' (duration: 776.289266ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:06:29.703015Z","caller":"traceutil/trace.go:171","msg":"trace[84515629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"903.450553ms","start":"2026-03-02T02:06:28.799538Z","end":"2026-03-02T02:06:29.702989Z","steps":["trace[84515629] 'agreement among raft nodes before linearized reading' (duration: 903.32088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:29.703064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.799518Z","time spent":"903.537576ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:06:29.703059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:28.79766Z","time spent":"904.77322ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:06:30.20447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995720377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:06:30.602403Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.339463458s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T02:06:30.681887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.720095ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:06:30.682031Z","caller":"traceutil/trace.go:171","msg":"trace[59430416] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:425; }","duration":"841.875559ms","start":"2026-03-02T02:06:29.840126Z","end":"2026-03-02T02:06:30.682001Z","steps":["trace[59430416] 'range keys from in-memory index tree' (duration: 841.699025ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:30.682218Z","caller":"traceutil/trace.go:171","msg":"trace[263880419] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"1.524130009s","start":"2026-03-02T02:06:29.158068Z","end":"2026-03-02T02:06:30.682198Z","steps":["trace[263880419] 'process raft request' (duration: 1.444577025s)","trace[263880419] 'compare' (duration: 79.480822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:06:30.682313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:29.158045Z","time spent":"1.524215761s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:06:30.689077Z","caller":"traceutil/trace.go:171","msg":"trace[1852146535] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:440; }","duration":"985.183315ms","start":"2026-03-02T02:06:29.703869Z","end":"2026-03-02T02:06:30.689052Z","steps":["trace[1852146535] 'read index received' (duration: 898.796056ms)","trace[1852146535] 'applied index is now lower than readState.Index' (duration: 86.386159ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:06:30.689218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.324629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:06:30.689255Z","caller":"traceutil/trace.go:171","msg":"trace[860083990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"985.410921ms","start":"2026-03-02T02:06:29.703834Z","end":"2026-03-02T02:06:30.689245Z","steps":["trace[860083990] 'agreement among raft nodes before linearized reading' (duration: 985.304698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:30.68929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:29.703818Z","time spent":"985.464673ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T02:06:30.689097Z","caller":"traceutil/trace.go:171","msg":"trace[1492732191] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"985.312779ms","start":"2026-03-02T02:06:29.703764Z","end":"2026-03-02T02:06:30.689077Z","steps":["trace[1492732191] 'process raft request' (duration: 985.158975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:30.689604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:29.70371Z","time spent":"985.820313ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:06:34.136758Z","caller":"traceutil/trace.go:171","msg":"trace[100370633] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"415.026284ms","start":"2026-03-02T02:06:33.721709Z","end":"2026-03-02T02:06:34.136736Z","steps":["trace[100370633] 'process raft request' (duration: 414.87147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:34.1369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:33.721692Z","time spent":"415.131846ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:06:34.137664Z","caller":"traceutil/trace.go:171","msg":"trace[572092746] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"414.321965ms","start":"2026-03-02T02:06:33.723316Z","end":"2026-03-02T02:06:34.137638Z","steps":["trace[572092746] 'process raft request' (duration: 414.273994ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:34.137735Z","caller":"traceutil/trace.go:171","msg":"trace[982614303] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"415.691992ms","start":"2026-03-02T02:06:33.722031Z","end":"2026-03-02T02:06:34.137723Z","steps":["trace[982614303] 'process raft request' (duration: 415.392203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:34.137784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:33.721999Z","time spent":"415.760554ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:06:34.137807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:33.723288Z","time spent":"414.441458ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:06:34.13792Z","caller":"traceutil/trace.go:171","msg":"trace[385667189] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"415.282861ms","start":"2026-03-02T02:06:33.72263Z","end":"2026-03-02T02:06:34.137913Z","steps":["trace[385667189] 'process raft request' (duration: 414.922811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:34.137954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:33.722612Z","time spent":"415.324002ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:06:43.750425Z","caller":"traceutil/trace.go:171","msg":"trace[150200012] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"127.499632ms","start":"2026-03-02T02:06:43.622898Z","end":"2026-03-02T02:06:43.750397Z","steps":["trace[150200012] 'process raft request' (duration: 127.309638ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:44.292441Z","caller":"traceutil/trace.go:171","msg":"trace[1333886546] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"109.699557ms","start":"2026-03-02T02:06:44.182705Z","end":"2026-03-02T02:06:44.292405Z","steps":["trace[1333886546] 'process raft request' (duration: 109.474631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:44.951342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.018312ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T02:06:44.95142Z","caller":"traceutil/trace.go:171","msg":"trace[662227288] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"766.750611ms","start":"2026-03-02T02:06:44.184655Z","end":"2026-03-02T02:06:44.951406Z","steps":["trace[662227288] 'read index received' (duration: 94.321769ms)","trace[662227288] 'applied index is now lower than readState.Index' (duration: 672.427822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:06:44.951588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.854524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T02:06:44.951615Z","caller":"traceutil/trace.go:171","msg":"trace[1947483220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"766.914665ms","start":"2026-03-02T02:06:44.184692Z","end":"2026-03-02T02:06:44.951607Z","steps":["trace[1947483220] 'agreement among raft nodes before linearized reading' (duration: 766.784242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:44.951638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:44.184676Z","time spent":"766.956787ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T02:06:44.951646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.16555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:06:44.951636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.956336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-02T02:06:44.951762Z","caller":"traceutil/trace.go:171","msg":"trace[1532555725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"767.09678ms","start":"2026-03-02T02:06:44.184621Z","end":"2026-03-02T02:06:44.951718Z","steps":["trace[1532555725] 'agreement among raft nodes before linearized reading' (duration: 766.839083ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:44.951767Z","caller":"traceutil/trace.go:171","msg":"trace[643387886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"388.266392ms","start":"2026-03-02T02:06:44.563432Z","end":"2026-03-02T02:06:44.951698Z","steps":["trace[643387886] 'agreement among raft nodes before linearized reading' (duration: 388.17043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:44.951842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:44.563412Z","time spent":"388.417117ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:06:44.951842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:44.184609Z","time spent":"767.218523ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T02:06:45.081379Z","caller":"traceutil/trace.go:171","msg":"trace[494949357] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"124.405959ms","start":"2026-03-02T02:06:44.956952Z","end":"2026-03-02T02:06:45.081358Z","steps":["trace[494949357] 'process raft request' (duration: 124.246095ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:06:45.499504Z","caller":"traceutil/trace.go:171","msg":"trace[1217874423] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"513.729026ms","start":"2026-03-02T02:06:44.985754Z","end":"2026-03-02T02:06:45.499483Z","steps":["trace[1217874423] 'process raft request' (duration: 513.609213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:06:45.49963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:06:44.985744Z","time spent":"513.823548ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:06:49.968854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.892691ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:06:49.969041Z","caller":"traceutil/trace.go:171","msg":"trace[1919889586] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:562; }","duration":"128.102397ms","start":"2026-03-02T02:06:49.840918Z","end":"2026-03-02T02:06:49.969021Z","steps":["trace[1919889586] 'range keys from in-memory index tree' (duration: 127.870109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:04.979524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.295188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:04.979632Z","caller":"traceutil/trace.go:171","msg":"trace[1772688493] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"416.471226ms","start":"2026-03-02T02:07:04.563149Z","end":"2026-03-02T02:07:04.97962Z","steps":["trace[1772688493] 'read index received' (duration: 23.81µs)","trace[1772688493] 'applied index is now lower than readState.Index' (duration: 416.446536ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:04.979696Z","caller":"traceutil/trace.go:171","msg":"trace[2089429566] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"594.189325ms","start":"2026-03-02T02:07:04.385499Z","end":"2026-03-02T02:07:04.979688Z","steps":["trace[2089429566] 'process raft request' (duration: 165.624931ms)","trace[2089429566] 'compare' (duration: 427.95795ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:04.979819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:04.385478Z","time spent":"594.234976ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:04.979826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.66797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:04.979877Z","caller":"traceutil/trace.go:171","msg":"trace[1373951486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"416.745482ms","start":"2026-03-02T02:07:04.563116Z","end":"2026-03-02T02:07:04.979861Z","steps":["trace[1373951486] 'agreement among raft nodes before linearized reading' (duration: 416.671691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:04.979926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:04.563102Z","time spent":"416.817494ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:07:04.9802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.472621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:07:04.980354Z","caller":"traceutil/trace.go:171","msg":"trace[1870766760] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:687; }","duration":"158.678066ms","start":"2026-03-02T02:07:04.821661Z","end":"2026-03-02T02:07:04.980339Z","steps":["trace[1870766760] 'agreement among raft nodes before linearized reading' (duration: 158.034781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:05.310603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.082955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:05.310668Z","caller":"traceutil/trace.go:171","msg":"trace[1469813081] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:687; }","duration":"164.205758ms","start":"2026-03-02T02:07:05.146449Z","end":"2026-03-02T02:07:05.310654Z","steps":["trace[1469813081] 'count revisions from in-memory index tree' (duration: 164.002304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:05.310807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.800018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T02:07:05.310885Z","caller":"traceutil/trace.go:171","msg":"trace[653653650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"111.945181ms","start":"2026-03-02T02:07:05.198922Z","end":"2026-03-02T02:07:05.310868Z","steps":["trace[653653650] 'range keys from in-memory index tree' (duration: 111.635343ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:05.621725Z","caller":"traceutil/trace.go:171","msg":"trace[1078678864] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"307.044035ms","start":"2026-03-02T02:07:05.314657Z","end":"2026-03-02T02:07:05.621701Z","steps":["trace[1078678864] 'process raft request' (duration: 306.909922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:05.621875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:05.314642Z","time spent":"307.156147ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:07.882759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.956501774s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:07.882876Z","caller":"traceutil/trace.go:171","msg":"trace[1428820061] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"2.266317746s","start":"2026-03-02T02:07:05.61654Z","end":"2026-03-02T02:07:07.882858Z","steps":["trace[1428820061] 'read index received' (duration: 5.03016ms)","trace[1428820061] 'applied index is now lower than readState.Index' (duration: 2.261286276s)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:07.882885Z","caller":"traceutil/trace.go:171","msg":"trace[72203449] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"2.568089735s","start":"2026-03-02T02:07:05.314773Z","end":"2026-03-02T02:07:07.882862Z","steps":["trace[72203449] 'process raft request' (duration: 611.365445ms)","trace[72203449] 'compare' (duration: 1.956333391s)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:07.882986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:05.314706Z","time spent":"2.568222398s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:07.883071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.26653841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-02T02:07:07.883109Z","caller":"traceutil/trace.go:171","msg":"trace[993609462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"2.266604182s","start":"2026-03-02T02:07:05.616489Z","end":"2026-03-02T02:07:07.883093Z","steps":["trace[993609462] 'agreement among raft nodes before linearized reading' (duration: 2.266459939s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:07.883143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:05.616468Z","time spent":"2.266665924s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:07:07.883215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421722697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:07:07.883238Z","caller":"traceutil/trace.go:171","msg":"trace[1326696612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"1.421769467s","start":"2026-03-02T02:07:06.461461Z","end":"2026-03-02T02:07:07.88323Z","steps":["trace[1326696612] 'agreement among raft nodes before linearized reading' (duration: 1.421689495s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:07.883253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:06.46144Z","time spent":"1.421809539s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:07:07.883316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.572409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-02T02:07:07.883327Z","caller":"traceutil/trace.go:171","msg":"trace[538480131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"256.644682ms","start":"2026-03-02T02:07:07.626679Z","end":"2026-03-02T02:07:07.883323Z","steps":["trace[538480131] 'agreement among raft nodes before linearized reading' (duration: 256.610041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:07.88339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.663796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T02:07:07.883406Z","caller":"traceutil/trace.go:171","msg":"trace[1591709250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"900.690016ms","start":"2026-03-02T02:07:06.982708Z","end":"2026-03-02T02:07:07.883398Z","steps":["trace[1591709250] 'agreement among raft nodes before linearized reading' (duration: 900.654435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:07.883432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:06.982701Z","time spent":"900.727028ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:07:07.88349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.271618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T02:07:07.883502Z","caller":"traceutil/trace.go:171","msg":"trace[363387614] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:689; }","duration":"903.32438ms","start":"2026-03-02T02:07:06.980173Z","end":"2026-03-02T02:07:07.883497Z","steps":["trace[363387614] 'agreement among raft nodes before linearized reading' (duration: 903.289299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:07.883513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:06.980142Z","time spent":"903.368201ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-03-02T02:07:07.883601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320558054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:07.883613Z","caller":"traceutil/trace.go:171","msg":"trace[401469607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"1.320603524s","start":"2026-03-02T02:07:06.563006Z","end":"2026-03-02T02:07:07.883609Z","steps":["trace[401469607] 'agreement among raft nodes before linearized reading' (duration: 1.320580434s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:07.883626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:06.56298Z","time spent":"1.320643276s","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:07:08.282243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.766569ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-02T02:07:08.282322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:07.884999Z","time spent":"397.319139ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T02:07:08.317155Z","caller":"traceutil/trace.go:171","msg":"trace[1077164280] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"430.07233ms","start":"2026-03-02T02:07:07.887053Z","end":"2026-03-02T02:07:08.317125Z","steps":["trace[1077164280] 'process raft request' (duration: 430.007948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:08.317306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:07.887047Z","time spent":"430.185732ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:08.317314Z","caller":"traceutil/trace.go:171","msg":"trace[23168339] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:713; }","duration":"427.865886ms","start":"2026-03-02T02:07:07.889406Z","end":"2026-03-02T02:07:08.317272Z","steps":["trace[23168339] 'read index received' (duration: 138.126054ms)","trace[23168339] 'applied index is now lower than readState.Index' (duration: 289.738212ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:08.317355Z","caller":"traceutil/trace.go:171","msg":"trace[94320203] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"430.806008ms","start":"2026-03-02T02:07:07.886497Z","end":"2026-03-02T02:07:08.317303Z","steps":["trace[94320203] 'process raft request' (duration: 430.385018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:08.317462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.043621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:08.317509Z","caller":"traceutil/trace.go:171","msg":"trace[1988417051] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"430.554731ms","start":"2026-03-02T02:07:07.886949Z","end":"2026-03-02T02:07:08.317503Z","steps":["trace[1988417051] 'process raft request' (duration: 430.073799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:08.317547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:07.886938Z","time spent":"430.594372ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:08.317523Z","caller":"traceutil/trace.go:171","msg":"trace[1776164859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"428.118153ms","start":"2026-03-02T02:07:07.889386Z","end":"2026-03-02T02:07:08.317504Z","steps":["trace[1776164859] 'agreement among raft nodes before linearized reading' (duration: 427.99781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:08.317631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:07.889378Z","time spent":"428.247806ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T02:07:08.317654Z","caller":"traceutil/trace.go:171","msg":"trace[1929509905] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"429.763292ms","start":"2026-03-02T02:07:07.887879Z","end":"2026-03-02T02:07:08.317643Z","steps":["trace[1929509905] 'process raft request' (duration: 429.214639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:08.317471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:07.886474Z","time spent":"430.932181ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:08.317723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:07.887872Z","time spent":"429.811544ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:09.90676Z","caller":"traceutil/trace.go:171","msg":"trace[700659422] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"298.775069ms","start":"2026-03-02T02:07:09.607962Z","end":"2026-03-02T02:07:09.906737Z","steps":["trace[700659422] 'read index received' (duration: 298.631885ms)","trace[700659422] 'applied index is now lower than readState.Index' (duration: 142.294µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:09.906794Z","caller":"traceutil/trace.go:171","msg":"trace[556717946] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"507.916437ms","start":"2026-03-02T02:07:09.398858Z","end":"2026-03-02T02:07:09.906775Z","steps":["trace[556717946] 'process raft request' (duration: 507.753874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:09.90688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.904022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:07:09.906914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:09.398834Z","time spent":"508.00663ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:09.90691Z","caller":"traceutil/trace.go:171","msg":"trace[1522409397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"298.972693ms","start":"2026-03-02T02:07:09.607929Z","end":"2026-03-02T02:07:09.906901Z","steps":["trace[1522409397] 'agreement among raft nodes before linearized reading' (duration: 298.913562ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:09.966922Z","caller":"traceutil/trace.go:171","msg":"trace[962707583] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"355.568714ms","start":"2026-03-02T02:07:09.611327Z","end":"2026-03-02T02:07:09.966895Z","steps":["trace[962707583] 'process raft request' (duration: 354.210602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:09.967075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:09.61131Z","time spent":"355.693826ms","remote":"127.0.0.1:45726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:10.932102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.922664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-02T02:07:10.93216Z","caller":"traceutil/trace.go:171","msg":"trace[678722262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:697; }","duration":"610.045227ms","start":"2026-03-02T02:07:10.322102Z","end":"2026-03-02T02:07:10.932147Z","steps":["trace[678722262] 'range keys from in-memory index tree' (duration: 609.793411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:10.932193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.322092Z","time spent":"610.094438ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T02:07:10.932232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.473753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-02T02:07:10.932293Z","caller":"traceutil/trace.go:171","msg":"trace[1698808264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"609.560886ms","start":"2026-03-02T02:07:10.322716Z","end":"2026-03-02T02:07:10.932277Z","steps":["trace[1698808264] 'range keys from in-memory index tree' (duration: 609.32358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:10.932331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.322709Z","time spent":"609.614037ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T02:07:10.932427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.350647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:07:10.932459Z","caller":"traceutil/trace.go:171","msg":"trace[816254309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"611.421449ms","start":"2026-03-02T02:07:10.321029Z","end":"2026-03-02T02:07:10.93245Z","steps":["trace[816254309] 'range keys from in-memory index tree' (duration: 611.248525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:10.932485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.32101Z","time spent":"611.468231ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:07:10.932519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.05183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T02:07:10.932583Z","caller":"traceutil/trace.go:171","msg":"trace[1852329054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"611.142473ms","start":"2026-03-02T02:07:10.321423Z","end":"2026-03-02T02:07:10.932566Z","steps":["trace[1852329054] 'range keys from in-memory index tree' (duration: 610.927308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:10.932628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.321414Z","time spent":"611.204244ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T02:07:11.52465Z","caller":"traceutil/trace.go:171","msg":"trace[1498467433] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"587.754938ms","start":"2026-03-02T02:07:10.936878Z","end":"2026-03-02T02:07:11.524633Z","steps":["trace[1498467433] 'process raft request' (duration: 587.621695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:11.524786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.936857Z","time spent":"587.85815ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:11.524973Z","caller":"traceutil/trace.go:171","msg":"trace[1008638565] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"588.078546ms","start":"2026-03-02T02:07:10.936879Z","end":"2026-03-02T02:07:11.524957Z","steps":["trace[1008638565] 'process raft request' (duration: 587.723037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:11.52502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.93687Z","time spent":"588.126637ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:11.525018Z","caller":"traceutil/trace.go:171","msg":"trace[1711145218] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"588.042534ms","start":"2026-03-02T02:07:10.936946Z","end":"2026-03-02T02:07:11.524989Z","steps":["trace[1711145218] 'process raft request' (duration: 587.919762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:11.52521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:10.936939Z","time spent":"588.168007ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:11.526843Z","caller":"traceutil/trace.go:171","msg":"trace[2007945673] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"288.630113ms","start":"2026-03-02T02:07:11.238191Z","end":"2026-03-02T02:07:11.526821Z","steps":["trace[2007945673] 'process raft request' (duration: 288.52816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:37.76133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.610898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:37.761445Z","caller":"traceutil/trace.go:171","msg":"trace[910644746] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"590.535886ms","start":"2026-03-02T02:07:37.17089Z","end":"2026-03-02T02:07:37.761426Z","steps":["trace[910644746] 'process raft request' (duration: 423.713283ms)","trace[910644746] 'compare' (duration: 166.078326ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:37.761516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:37.170874Z","time spent":"590.601148ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:37.807263Z","caller":"traceutil/trace.go:171","msg":"trace[326546056] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"242.833021ms","start":"2026-03-02T02:07:37.564406Z","end":"2026-03-02T02:07:37.807239Z","steps":["trace[326546056] 'read index received' (duration: 30.222617ms)","trace[326546056] 'applied index is now lower than readState.Index' (duration: 212.609314ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:37.807334Z","caller":"traceutil/trace.go:171","msg":"trace[1249551759] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"634.255367ms","start":"2026-03-02T02:07:37.173025Z","end":"2026-03-02T02:07:37.807281Z","steps":["trace[1249551759] 'process raft request' (duration: 634.078423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:37.808322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.535934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:07:37.808722Z","caller":"traceutil/trace.go:171","msg":"trace[590907807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"175.952373ms","start":"2026-03-02T02:07:37.632743Z","end":"2026-03-02T02:07:37.808695Z","steps":["trace[590907807] 'agreement among raft nodes before linearized reading' (duration: 174.62627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:37.808734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:37.173009Z","time spent":"635.244382ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:37.808954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.185445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T02:07:37.808989Z","caller":"traceutil/trace.go:171","msg":"trace[345454835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"148.254717ms","start":"2026-03-02T02:07:37.660723Z","end":"2026-03-02T02:07:37.808977Z","steps":["trace[345454835] 'agreement among raft nodes before linearized reading' (duration: 146.708729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:37.811713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.292879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:37.811781Z","caller":"traceutil/trace.go:171","msg":"trace[438877225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"247.415073ms","start":"2026-03-02T02:07:37.564352Z","end":"2026-03-02T02:07:37.811767Z","steps":["trace[438877225] 'agreement among raft nodes before linearized reading' (duration: 243.013626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:37.995409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.732773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:07:37.995636Z","caller":"traceutil/trace.go:171","msg":"trace[438445856] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:807; }","duration":"180.212015ms","start":"2026-03-02T02:07:37.815403Z","end":"2026-03-02T02:07:37.995615Z","steps":["trace[438445856] 'read index received' (duration: 26.491978ms)","trace[438445856] 'applied index is now lower than readState.Index' (duration: 153.718827ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:37.995834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.418471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-02T02:07:37.995891Z","caller":"traceutil/trace.go:171","msg":"trace[322012766] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:779; }","duration":"180.486272ms","start":"2026-03-02T02:07:37.815388Z","end":"2026-03-02T02:07:37.995874Z","steps":["trace[322012766] 'agreement among raft nodes before linearized reading' (duration: 180.297087ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:37.995971Z","caller":"traceutil/trace.go:171","msg":"trace[496812233] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"181.62646ms","start":"2026-03-02T02:07:37.814235Z","end":"2026-03-02T02:07:37.995861Z","steps":["trace[496812233] 'process raft request' (duration: 75.340583ms)","trace[496812233] 'compare' (duration: 105.17605ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:37.99602Z","caller":"traceutil/trace.go:171","msg":"trace[1813483585] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"181.591219ms","start":"2026-03-02T02:07:37.814299Z","end":"2026-03-02T02:07:37.99589Z","steps":["trace[1813483585] 'process raft request' (duration: 181.237459ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:40.04601Z","caller":"traceutil/trace.go:171","msg":"trace[2103726324] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"147.839586ms","start":"2026-03-02T02:07:39.898135Z","end":"2026-03-02T02:07:40.045975Z","steps":["trace[2103726324] 'process raft request' (duration: 147.479247ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.236129Z","caller":"traceutil/trace.go:171","msg":"trace[1153903906] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"355.81473ms","start":"2026-03-02T02:07:41.88029Z","end":"2026-03-02T02:07:42.236104Z","steps":["trace[1153903906] 'read index received' (duration: 355.623536ms)","trace[1153903906] 'applied index is now lower than readState.Index' (duration: 190.384µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:07:42.236297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.987284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:07:42.236344Z","caller":"traceutil/trace.go:171","msg":"trace[2077997718] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:787; }","duration":"356.092257ms","start":"2026-03-02T02:07:41.88024Z","end":"2026-03-02T02:07:42.236332Z","steps":["trace[2077997718] 'agreement among raft nodes before linearized reading' (duration: 355.969064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.236385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:41.880222Z","time spent":"356.152218ms","remote":"127.0.0.1:45876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-02T02:07:42.236243Z","caller":"traceutil/trace.go:171","msg":"trace[2117323711] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"395.078725ms","start":"2026-03-02T02:07:41.841126Z","end":"2026-03-02T02:07:42.236204Z","steps":["trace[2117323711] 'process raft request' (duration: 394.826779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.236828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:41.841114Z","time spent":"395.465524ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:07:42.424947Z","caller":"traceutil/trace.go:171","msg":"trace[1990047266] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"372.224835ms","start":"2026-03-02T02:07:42.052708Z","end":"2026-03-02T02:07:42.424933Z","steps":["trace[1990047266] 'process raft request' (duration: 372.070491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.42505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:42.052687Z","time spent":"372.305067ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:07:42.425143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.226363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T02:07:42.425193Z","caller":"traceutil/trace.go:171","msg":"trace[1005118387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"374.320786ms","start":"2026-03-02T02:07:42.050862Z","end":"2026-03-02T02:07:42.425182Z","steps":["trace[1005118387] 'agreement among raft nodes before linearized reading' (duration: 374.124681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.425236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:42.050836Z","time spent":"374.390878ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T02:07:42.42527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.417379ms","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-03-02T02:07:42.424928Z","caller":"traceutil/trace.go:171","msg":"trace[687645599] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"188.716311ms","start":"2026-03-02T02:07:42.236189Z","end":"2026-03-02T02:07:42.424905Z","steps":["trace[687645599] 'read index received' (duration: 176.829714ms)","trace[687645599] 'applied index is now lower than readState.Index' (duration: 11.885607ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:42.425414Z","caller":"traceutil/trace.go:171","msg":"trace[403375194] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:788; }","duration":"416.615874ms","start":"2026-03-02T02:07:42.008776Z","end":"2026-03-02T02:07:42.425392Z","steps":["trace[403375194] 'agreement among raft nodes before linearized reading' (duration: 416.359818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.425509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:42.008755Z","time spent":"416.735596ms","remote":"127.0.0.1:45778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-02T02:07:42.425398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.698435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-02T02:07:42.425545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.250515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:07:42.425594Z","caller":"traceutil/trace.go:171","msg":"trace[639748939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"347.90867ms","start":"2026-03-02T02:07:42.077672Z","end":"2026-03-02T02:07:42.425581Z","steps":["trace[639748939] 'agreement among raft nodes before linearized reading' (duration: 347.661454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.425636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:42.077663Z","time spent":"347.958991ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T02:07:42.425614Z","caller":"traceutil/trace.go:171","msg":"trace[743729837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"352.379967ms","start":"2026-03-02T02:07:42.073216Z","end":"2026-03-02T02:07:42.425596Z","steps":["trace[743729837] 'agreement among raft nodes before linearized reading' (duration: 351.744802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:07:42.425801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.769757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-02T02:07:42.425808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:07:42.073205Z","time spent":"352.590083ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T02:07:42.425836Z","caller":"traceutil/trace.go:171","msg":"trace[2024460082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"182.839508ms","start":"2026-03-02T02:07:42.242984Z","end":"2026-03-02T02:07:42.425823Z","steps":["trace[2024460082] 'agreement among raft nodes before linearized reading' (duration: 182.532761ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.592897Z","caller":"traceutil/trace.go:171","msg":"trace[715055661] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"161.602308ms","start":"2026-03-02T02:07:42.43127Z","end":"2026-03-02T02:07:42.592873Z","steps":["trace[715055661] 'process raft request' (duration: 111.579884ms)","trace[715055661] 'compare' (duration: 49.775877ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:07:42.593205Z","caller":"traceutil/trace.go:171","msg":"trace[1258271251] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"161.824214ms","start":"2026-03-02T02:07:42.431367Z","end":"2026-03-02T02:07:42.593191Z","steps":["trace[1258271251] 'process raft request' (duration: 161.465615ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.593567Z","caller":"traceutil/trace.go:171","msg":"trace[1305087880] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"162.064769ms","start":"2026-03-02T02:07:42.431475Z","end":"2026-03-02T02:07:42.59354Z","steps":["trace[1305087880] 'process raft request' (duration: 161.917305ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:07:42.593537Z","caller":"traceutil/trace.go:171","msg":"trace[2121680050] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"162.090319ms","start":"2026-03-02T02:07:42.431428Z","end":"2026-03-02T02:07:42.593518Z","steps":["trace[2121680050] 'process raft request' (duration: 161.667699ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.083957Z","caller":"traceutil/trace.go:171","msg":"trace[1154571298] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"303.892601ms","start":"2026-03-02T02:08:02.780045Z","end":"2026-03-02T02:08:03.083938Z","steps":["trace[1154571298] 'read index received' (duration: 303.714226ms)","trace[1154571298] 'applied index is now lower than readState.Index' (duration: 177.655µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:03.084031Z","caller":"traceutil/trace.go:171","msg":"trace[1786642921] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"354.471907ms","start":"2026-03-02T02:08:02.729529Z","end":"2026-03-02T02:08:03.084001Z","steps":["trace[1786642921] 'process raft request' (duration: 354.245582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.084141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.074706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:08:03.084166Z","caller":"traceutil/trace.go:171","msg":"trace[838402106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"304.160477ms","start":"2026-03-02T02:08:02.779999Z","end":"2026-03-02T02:08:03.084159Z","steps":["trace[838402106] 'agreement among raft nodes before linearized reading' (duration: 304.039514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.084192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.779978Z","time spent":"304.207188ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:08:03.084191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.729503Z","time spent":"354.602631ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:03.120694Z","caller":"traceutil/trace.go:171","msg":"trace[1420095980] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"138.687866ms","start":"2026-03-02T02:08:02.981959Z","end":"2026-03-02T02:08:03.120647Z","steps":["trace[1420095980] 'process raft request' (duration: 138.536573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.120858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.323087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-02T02:08:03.120928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.288486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:08:03.120976Z","caller":"traceutil/trace.go:171","msg":"trace[1210874090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"340.468991ms","start":"2026-03-02T02:08:02.780483Z","end":"2026-03-02T02:08:03.120951Z","steps":["trace[1210874090] 'agreement among raft nodes before linearized reading' (duration: 340.140113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.121304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.780471Z","time spent":"340.809148ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T02:08:03.121025Z","caller":"traceutil/trace.go:171","msg":"trace[410649555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"340.418729ms","start":"2026-03-02T02:08:02.780569Z","end":"2026-03-02T02:08:03.120988Z","steps":["trace[410649555] 'agreement among raft nodes before linearized reading' (duration: 340.130853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:03.121677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:02.780551Z","time spent":"341.110236ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T02:08:03.335084Z","caller":"traceutil/trace.go:171","msg":"trace[1046702293] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"247.207447ms","start":"2026-03-02T02:08:03.08785Z","end":"2026-03-02T02:08:03.335057Z","steps":["trace[1046702293] 'process raft request' (duration: 234.154513ms)","trace[1046702293] 'compare' (duration: 12.864899ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:03.335367Z","caller":"traceutil/trace.go:171","msg":"trace[309662227] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"210.116195ms","start":"2026-03-02T02:08:03.125241Z","end":"2026-03-02T02:08:03.335357Z","steps":["trace[309662227] 'process raft request' (duration: 209.748796ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:03.335447Z","caller":"traceutil/trace.go:171","msg":"trace[1090853809] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"209.354386ms","start":"2026-03-02T02:08:03.126052Z","end":"2026-03-02T02:08:03.335407Z","steps":["trace[1090853809] 'process raft request' (duration: 209.264704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:05.839051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995721453,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:06.339464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995721453,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T02:08:06.388798Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.298687354s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T02:08:06.389056Z","caller":"traceutil/trace.go:171","msg":"trace[2056800840] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"1.050905362s","start":"2026-03-02T02:08:05.338135Z","end":"2026-03-02T02:08:06.389041Z","steps":["trace[2056800840] 'read index received' (duration: 1.050750358s)","trace[2056800840] 'applied index is now lower than readState.Index' (duration: 154.434µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:06.389099Z","caller":"traceutil/trace.go:171","msg":"trace[122442298] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"1.299068692s","start":"2026-03-02T02:08:05.090025Z","end":"2026-03-02T02:08:06.389094Z","steps":["trace[122442298] 'process raft request' (duration: 1.298904068s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:06.389203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:05.090014Z","time spent":"1.299133085s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:06.389268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.025372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:08:06.389361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050054192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T02:08:06.389394Z","caller":"traceutil/trace.go:171","msg":"trace[1331413741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"1.050133913s","start":"2026-03-02T02:08:05.339252Z","end":"2026-03-02T02:08:06.389386Z","steps":["trace[1331413741] 'agreement among raft nodes before linearized reading' (duration: 1.05000195s)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:06.389404Z","caller":"traceutil/trace.go:171","msg":"trace[610722479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"826.146005ms","start":"2026-03-02T02:08:05.563213Z","end":"2026-03-02T02:08:06.389359Z","steps":["trace[610722479] 'agreement among raft nodes before linearized reading' (duration: 826.027212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:06.389422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:05.339239Z","time spent":"1.050178115s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:08:06.389453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051270601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-02T02:08:06.389507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:05.563199Z","time spent":"826.259298ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T02:08:06.389559Z","caller":"traceutil/trace.go:171","msg":"trace[895102998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"1.051419734s","start":"2026-03-02T02:08:05.338114Z","end":"2026-03-02T02:08:06.389534Z","steps":["trace[895102998] 'agreement among raft nodes before linearized reading' (duration: 1.051081836s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:06.389625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:05.338099Z","time spent":"1.051517447s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:06.38988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050980864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T02:08:06.389997Z","caller":"traceutil/trace.go:171","msg":"trace[957676645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"1.051106977s","start":"2026-03-02T02:08:05.338853Z","end":"2026-03-02T02:08:06.38996Z","steps":["trace[957676645] 'agreement among raft nodes before linearized reading' (duration: 1.050332828s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:06.390033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:05.33885Z","time spent":"1.051174828s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T02:08:19.024947Z","caller":"traceutil/trace.go:171","msg":"trace[960801305] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:977; }","duration":"461.83723ms","start":"2026-03-02T02:08:18.563084Z","end":"2026-03-02T02:08:19.024921Z","steps":["trace[960801305] 'read index received' (duration: 461.681257ms)","trace[960801305] 'applied index is now lower than readState.Index' (duration: 155.483µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:19.025009Z","caller":"traceutil/trace.go:171","msg":"trace[133352744] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"538.765122ms","start":"2026-03-02T02:08:18.486211Z","end":"2026-03-02T02:08:19.024976Z","steps":["trace[133352744] 'process raft request' (duration: 538.598178ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:19.025052Z","caller":"traceutil/trace.go:171","msg":"trace[2088396085] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"538.774292ms","start":"2026-03-02T02:08:18.486252Z","end":"2026-03-02T02:08:19.025027Z","steps":["trace[2088396085] 'process raft request' (duration: 538.633909ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:19.025071Z","caller":"traceutil/trace.go:171","msg":"trace[1480850870] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"538.777913ms","start":"2026-03-02T02:08:18.486286Z","end":"2026-03-02T02:08:19.025064Z","steps":["trace[1480850870] 'process raft request' (duration: 538.617379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:19.025151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:18.486262Z","time spent":"538.844004ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:19.025156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:18.486147Z","time spent":"538.959037ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:19.025156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:18.4862Z","time spent":"538.874585ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:19.025042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.941054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:19.025259Z","caller":"traceutil/trace.go:171","msg":"trace[903956982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"462.193609ms","start":"2026-03-02T02:08:18.563041Z","end":"2026-03-02T02:08:19.025235Z","steps":["trace[903956982] 'agreement among raft nodes before linearized reading' (duration: 461.953583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:19.025326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:18.563021Z","time spent":"462.287432ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T02:08:21.428353Z","caller":"traceutil/trace.go:171","msg":"trace[487519203] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"314.306823ms","start":"2026-03-02T02:08:21.114022Z","end":"2026-03-02T02:08:21.428329Z","steps":["trace[487519203] 'process raft request' (duration: 314.135918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:21.428699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:21.114008Z","time spent":"314.419705ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:21.428869Z","caller":"traceutil/trace.go:171","msg":"trace[1471609045] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"314.742303ms","start":"2026-03-02T02:08:21.114113Z","end":"2026-03-02T02:08:21.428856Z","steps":["trace[1471609045] 'process raft request' (duration: 314.499317ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:21.428841Z","caller":"traceutil/trace.go:171","msg":"trace[911790795] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"314.700141ms","start":"2026-03-02T02:08:21.114081Z","end":"2026-03-02T02:08:21.428781Z","steps":["trace[911790795] 'process raft request' (duration: 314.207189ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:21.428942Z","caller":"traceutil/trace.go:171","msg":"trace[617368878] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"310.144652ms","start":"2026-03-02T02:08:21.118763Z","end":"2026-03-02T02:08:21.428908Z","steps":["trace[617368878] 'process raft request' (duration: 310.074041ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:21.428986Z","caller":"traceutil/trace.go:171","msg":"trace[1240497995] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"314.057716ms","start":"2026-03-02T02:08:21.114864Z","end":"2026-03-02T02:08:21.428921Z","steps":["trace[1240497995] 'process raft request' (duration: 313.910782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:21.42903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:21.114077Z","time spent":"314.851725ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:21.429154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:21.114857Z","time spent":"314.220169ms","remote":"127.0.0.1:45712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:21.429002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:21.114108Z","time spent":"314.798324ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:21.429187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:21.118756Z","time spent":"310.315076ms","remote":"127.0.0.1:46036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:21.868805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.943105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:21.868959Z","caller":"traceutil/trace.go:171","msg":"trace[1256236022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"307.15218ms","start":"2026-03-02T02:08:21.561765Z","end":"2026-03-02T02:08:21.868917Z","steps":["trace[1256236022] 'range keys from in-memory index tree' (duration: 306.855212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:21.869059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:21.561743Z","time spent":"307.296104ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T02:08:21.869799Z","caller":"traceutil/trace.go:171","msg":"trace[1821935536] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"114.071115ms","start":"2026-03-02T02:08:21.755693Z","end":"2026-03-02T02:08:21.869764Z","steps":["trace[1821935536] 'process raft request' (duration: 103.47823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:24.360376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.047878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:24.360528Z","caller":"traceutil/trace.go:171","msg":"trace[1643291749] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"532.434049ms","start":"2026-03-02T02:08:23.828069Z","end":"2026-03-02T02:08:24.360503Z","steps":["trace[1643291749] 'process raft request' (duration: 311.159405ms)","trace[1643291749] 'compare' (duration: 220.842173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:24.360591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:23.828033Z","time spent":"532.534221ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:08:24.700561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.966721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:24.700769Z","caller":"traceutil/trace.go:171","msg":"trace[462196309] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1025; }","duration":"195.34132ms","start":"2026-03-02T02:08:24.505398Z","end":"2026-03-02T02:08:24.700739Z","steps":["trace[462196309] 'read index received' (duration: 193.684529ms)","trace[462196309] 'applied index is now lower than readState.Index' (duration: 1.655841ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:24.700817Z","caller":"traceutil/trace.go:171","msg":"trace[618496449] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"333.189566ms","start":"2026-03-02T02:08:24.367598Z","end":"2026-03-02T02:08:24.700787Z","steps":["trace[618496449] 'process raft request' (duration: 127.014035ms)","trace[618496449] 'compare' (duration: 204.699065ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:24.700879Z","caller":"traceutil/trace.go:171","msg":"trace[137642705] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"328.824952ms","start":"2026-03-02T02:08:24.372044Z","end":"2026-03-02T02:08:24.700869Z","steps":["trace[137642705] 'process raft request' (duration: 328.626067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:24.700954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:24.367571Z","time spent":"333.311969ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T02:08:24.700956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:24.372025Z","time spent":"328.883003ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:24.701263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.861532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-02T02:08:24.701304Z","caller":"traceutil/trace.go:171","msg":"trace[1748992635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"195.945074ms","start":"2026-03-02T02:08:24.505349Z","end":"2026-03-02T02:08:24.701294Z","steps":["trace[1748992635] 'agreement among raft nodes before linearized reading' (duration: 195.818931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:24.70131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.698803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:24.701346Z","caller":"traceutil/trace.go:171","msg":"trace[103517678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"137.813836ms","start":"2026-03-02T02:08:24.563522Z","end":"2026-03-02T02:08:24.701335Z","steps":["trace[103517678] 'agreement among raft nodes before linearized reading' (duration: 137.739084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:24.701438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.825285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:08:24.701464Z","caller":"traceutil/trace.go:171","msg":"trace[1858810735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"122.899267ms","start":"2026-03-02T02:08:24.578557Z","end":"2026-03-02T02:08:24.701456Z","steps":["trace[1858810735] 'agreement among raft nodes before linearized reading' (duration: 122.824345ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:25.544461Z","caller":"traceutil/trace.go:171","msg":"trace[587915541] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"100.772155ms","start":"2026-03-02T02:08:25.443617Z","end":"2026-03-02T02:08:25.544389Z","steps":["trace[587915541] 'process raft request' (duration: 100.625031ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:25.607594Z","caller":"traceutil/trace.go:171","msg":"trace[1457204406] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"162.247463ms","start":"2026-03-02T02:08:25.445322Z","end":"2026-03-02T02:08:25.60757Z","steps":["trace[1457204406] 'process raft request' (duration: 162.190972ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:25.607779Z","caller":"traceutil/trace.go:171","msg":"trace[182404620] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"163.716169ms","start":"2026-03-02T02:08:25.44404Z","end":"2026-03-02T02:08:25.607756Z","steps":["trace[182404620] 'process raft request' (duration: 163.34109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.288778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810695995721645,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T02:08:27.550318Z","caller":"traceutil/trace.go:171","msg":"trace[84917125] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"762.291179ms","start":"2026-03-02T02:08:26.78801Z","end":"2026-03-02T02:08:27.550301Z","steps":["trace[84917125] 'read index received' (duration: 762.061673ms)","trace[84917125] 'applied index is now lower than readState.Index' (duration: 228.696µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:27.550411Z","caller":"traceutil/trace.go:171","msg":"trace[1244591696] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"772.853623ms","start":"2026-03-02T02:08:26.777513Z","end":"2026-03-02T02:08:27.550367Z","steps":["trace[1244591696] 'process raft request' (duration: 772.653029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.550496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.478654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-02T02:08:27.550527Z","caller":"traceutil/trace.go:171","msg":"trace[221649899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"762.546625ms","start":"2026-03-02T02:08:26.787972Z","end":"2026-03-02T02:08:27.550518Z","steps":["trace[221649899] 'agreement among raft nodes before linearized reading' (duration: 762.437292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.550552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:26.787948Z","time spent":"762.596967ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T02:08:27.550622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.37086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-02T02:08:27.55065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:26.777497Z","time spent":"773.035657ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:27.550686Z","caller":"traceutil/trace.go:171","msg":"trace[1669469270] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:991; }","duration":"567.435363ms","start":"2026-03-02T02:08:26.983235Z","end":"2026-03-02T02:08:27.55067Z","steps":["trace[1669469270] 'agreement among raft nodes before linearized reading' (duration: 567.288769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.550732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:26.983194Z","time spent":"567.526394ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-03-02T02:08:27.953235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.550235ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-02T02:08:27.953533Z","caller":"traceutil/trace.go:171","msg":"trace[334915409] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1033; }","duration":"342.11195ms","start":"2026-03-02T02:08:27.611401Z","end":"2026-03-02T02:08:27.953513Z","steps":["trace[334915409] 'read index received' (duration: 119.266709ms)","trace[334915409] 'applied index is now lower than readState.Index' (duration: 222.844211ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:27.95365Z","caller":"traceutil/trace.go:171","msg":"trace[69631628] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"399.520932ms","start":"2026-03-02T02:08:27.55412Z","end":"2026-03-02T02:08:27.953641Z","steps":["trace[69631628] 'process raft request' (duration: 399.323257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.953785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:27.55411Z","time spent":"399.571963ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:27.953898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.07247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-02T02:08:27.953969Z","caller":"traceutil/trace.go:171","msg":"trace[1954023403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"342.178442ms","start":"2026-03-02T02:08:27.611776Z","end":"2026-03-02T02:08:27.953954Z","steps":["trace[1954023403] 'agreement among raft nodes before linearized reading' (duration: 341.939846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.954013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:27.611765Z","time spent":"342.238884ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T02:08:27.954292Z","caller":"traceutil/trace.go:171","msg":"trace[1900004310] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"399.43862ms","start":"2026-03-02T02:08:27.554117Z","end":"2026-03-02T02:08:27.953555Z","steps":["trace[1900004310] 'process raft request' (duration: 399.213765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.954382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:27.554099Z","time spent":"400.227768ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:08:27.954603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:27.551715Z","time spent":"402.884093ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T02:08:27.954596Z","caller":"traceutil/trace.go:171","msg":"trace[868637186] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"399.907571ms","start":"2026-03-02T02:08:27.554656Z","end":"2026-03-02T02:08:27.954564Z","steps":["trace[868637186] 'process raft request' (duration: 398.823525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.954706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.264817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-02T02:08:27.954744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:27.554648Z","time spent":"400.023433ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:08:27.954773Z","caller":"traceutil/trace.go:171","msg":"trace[866319927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"343.40246ms","start":"2026-03-02T02:08:27.611354Z","end":"2026-03-02T02:08:27.954756Z","steps":["trace[866319927] 'agreement among raft nodes before linearized reading' (duration: 343.124474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:27.954817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:08:27.611334Z","time spent":"343.471143ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T02:08:28.236513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.406127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:28.236806Z","caller":"traceutil/trace.go:171","msg":"trace[473399575] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"278.360127ms","start":"2026-03-02T02:08:27.958413Z","end":"2026-03-02T02:08:28.236773Z","steps":["trace[473399575] 'process raft request' (duration: 157.588901ms)","trace[473399575] 'compare' (duration: 120.218163ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:28.236846Z","caller":"traceutil/trace.go:171","msg":"trace[2083657553] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"250.759053ms","start":"2026-03-02T02:08:27.986064Z","end":"2026-03-02T02:08:28.236824Z","steps":["trace[2083657553] 'read index received' (duration: 129.897495ms)","trace[2083657553] 'applied index is now lower than readState.Index' (duration: 120.860338ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:28.237029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.943358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-02T02:08:28.23708Z","caller":"traceutil/trace.go:171","msg":"trace[861362053] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:997; }","duration":"251.03928ms","start":"2026-03-02T02:08:27.986029Z","end":"2026-03-02T02:08:28.237068Z","steps":["trace[861362053] 'agreement among raft nodes before linearized reading' (duration: 250.891936ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:28.236873Z","caller":"traceutil/trace.go:171","msg":"trace[16009399] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"278.436238ms","start":"2026-03-02T02:08:27.958413Z","end":"2026-03-02T02:08:28.236849Z","steps":["trace[16009399] 'process raft request' (duration: 278.314146ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:29.6907Z","caller":"traceutil/trace.go:171","msg":"trace[610703174] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"125.336486ms","start":"2026-03-02T02:08:29.565334Z","end":"2026-03-02T02:08:29.690671Z","steps":["trace[610703174] 'read index received' (duration: 91.313µs)","trace[610703174] 'applied index is now lower than readState.Index' (duration: 125.243643ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:29.69076Z","caller":"traceutil/trace.go:171","msg":"trace[60866332] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"125.439698ms","start":"2026-03-02T02:08:29.565282Z","end":"2026-03-02T02:08:29.690722Z","steps":["trace[60866332] 'process raft request' (duration: 125.170882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:29.691043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.676094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:08:29.691175Z","caller":"traceutil/trace.go:171","msg":"trace[1619134339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"125.869978ms","start":"2026-03-02T02:08:29.565284Z","end":"2026-03-02T02:08:29.691154Z","steps":["trace[1619134339] 'agreement among raft nodes before linearized reading' (duration: 125.615142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:29.8962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.774126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T02:08:29.896282Z","caller":"traceutil/trace.go:171","msg":"trace[475743052] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:998; }","duration":"140.90645ms","start":"2026-03-02T02:08:29.755355Z","end":"2026-03-02T02:08:29.896261Z","steps":["trace[475743052] 'range keys from in-memory index tree' (duration: 140.622033ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:30.145246Z","caller":"traceutil/trace.go:171","msg":"trace[220720624] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"182.08157ms","start":"2026-03-02T02:08:29.963138Z","end":"2026-03-02T02:08:30.14522Z","steps":["trace[220720624] 'process raft request' (duration: 126.956443ms)","trace[220720624] 'compare' (duration: 55.042045ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:30.185003Z","caller":"traceutil/trace.go:171","msg":"trace[1625204339] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"221.334995ms","start":"2026-03-02T02:08:29.963639Z","end":"2026-03-02T02:08:30.184974Z","steps":["trace[1625204339] 'process raft request' (duration: 221.188482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:30.393314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.073462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:30.393469Z","caller":"traceutil/trace.go:171","msg":"trace[1515221732] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"148.230596ms","start":"2026-03-02T02:08:30.245223Z","end":"2026-03-02T02:08:30.393454Z","steps":["trace[1515221732] 'process raft request' (duration: 148.178035ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:30.393602Z","caller":"traceutil/trace.go:171","msg":"trace[1595654100] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"148.492192ms","start":"2026-03-02T02:08:30.245096Z","end":"2026-03-02T02:08:30.393588Z","steps":["trace[1595654100] 'process raft request' (duration: 15.066422ms)","trace[1595654100] 'compare' (duration: 132.964659ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:33.998944Z","caller":"traceutil/trace.go:171","msg":"trace[1249306137] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"281.490402ms","start":"2026-03-02T02:08:33.717421Z","end":"2026-03-02T02:08:33.998911Z","steps":["trace[1249306137] 'process raft request' (duration: 251.729046ms)","trace[1249306137] 'compare' (duration: 29.51705ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:34.006461Z","caller":"traceutil/trace.go:171","msg":"trace[1202261607] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"287.549128ms","start":"2026-03-02T02:08:33.718889Z","end":"2026-03-02T02:08:34.006438Z","steps":["trace[1202261607] 'process raft request' (duration: 287.090887ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:34.006532Z","caller":"traceutil/trace.go:171","msg":"trace[1706990364] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"233.479697ms","start":"2026-03-02T02:08:33.77301Z","end":"2026-03-02T02:08:34.00649Z","steps":["trace[1706990364] 'process raft request' (duration: 233.18321ms)"],"step_count":1}