{"level":"warn","ts":"2026-02-25T07:40:19.938609Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T07:40:19.938703Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T07:40:19.938772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T07:40:19.938786Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-25T07:40:19.938803Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T07:40:19.939479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-25T07:40:19.939639Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T07:40:19.945883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.909505ms"} {"level":"info","ts":"2026-02-25T07:40:19.953771Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-25T07:40:19.953884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T07:40:19.95392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-25T07:40:19.954082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T07:40:19.954125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-25T07:40:19.954186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-25T07:40:19.963588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T07:40:19.965142Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T07:40:19.966166Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T07:40:19.96913Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T07:40:19.969777Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T07:40:19.969906Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T07:40:19.969942Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T07:40:19.969953Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T07:40:19.970301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-25T07:40:19.970396Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-25T07:40:19.974463Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T07:40:19.974659Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-25T07:40:19.974676Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-25T07:40:19.974984Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T07:40:19.975017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T07:40:20.454963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T07:40:20.455087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T07:40:20.455122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-25T07:40:20.455188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-25T07:40:20.455236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-25T07:40:20.45528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-25T07:40:20.455305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-25T07:40:20.458171Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T07:40:20.458871Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T07:40:20.45903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T07:40:20.459279Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T07:40:20.459203Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T07:40:20.4594Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T07:40:20.459449Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T07:40:20.461366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T07:40:20.461748Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T07:40:20.461771Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T07:40:20.463475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-25T07:40:33.357095Z","caller":"traceutil/trace.go:171","msg":"trace[1151193766] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"312.052005ms","start":"2026-02-25T07:40:33.044973Z","end":"2026-02-25T07:40:33.357025Z","steps":["trace[1151193766] 'read index received' (duration: 312.002634ms)","trace[1151193766] 'applied index is now lower than readState.Index' (duration: 48.401µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:40:33.357314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.323903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T07:40:33.357355Z","caller":"traceutil/trace.go:171","msg":"trace[1909381500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"312.424315ms","start":"2026-02-25T07:40:33.04492Z","end":"2026-02-25T07:40:33.357345Z","steps":["trace[1909381500] 'agreement among raft nodes before linearized reading' (duration: 312.264981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:33.357391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:33.044896Z","time spent":"312.485756ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T07:40:33.357651Z","caller":"traceutil/trace.go:171","msg":"trace[1375057577] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"416.076682ms","start":"2026-02-25T07:40:32.94152Z","end":"2026-02-25T07:40:33.357597Z","steps":["trace[1375057577] 'process raft request' (duration: 415.372162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:33.358448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:32.941494Z","time spent":"416.255177ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:40:36.346333Z","caller":"traceutil/trace.go:171","msg":"trace[317541956] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"116.311787ms","start":"2026-02-25T07:40:36.229953Z","end":"2026-02-25T07:40:36.346265Z","steps":["trace[317541956] 'process raft request' (duration: 116.114122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:40.251485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.095912ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:40:40.251628Z","caller":"traceutil/trace.go:171","msg":"trace[329449567] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:452; }","duration":"379.275907ms","start":"2026-02-25T07:40:39.872332Z","end":"2026-02-25T07:40:40.251608Z","steps":["trace[329449567] 'range keys from in-memory index tree' (duration: 379.048871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:40.251661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.76884ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T07:40:40.251752Z","caller":"traceutil/trace.go:171","msg":"trace[1440264852] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"820.346063ms","start":"2026-02-25T07:40:39.431384Z","end":"2026-02-25T07:40:40.25173Z","steps":["trace[1440264852] 'read index received' (duration: 24.324917ms)","trace[1440264852] 'applied index is now lower than readState.Index' (duration: 796.020146ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:40:40.251906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.516208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T07:40:40.251914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.686638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:40:40.251948Z","caller":"traceutil/trace.go:171","msg":"trace[2105337375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"820.58714ms","start":"2026-02-25T07:40:39.431349Z","end":"2026-02-25T07:40:40.251936Z","steps":["trace[2105337375] 'agreement among raft nodes before linearized reading' (duration: 820.492458ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:40.251986Z","caller":"traceutil/trace.go:171","msg":"trace[1995153504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"487.791531ms","start":"2026-02-25T07:40:39.764177Z","end":"2026-02-25T07:40:40.251969Z","steps":["trace[1995153504] 'agreement among raft nodes before linearized reading' (duration: 487.684838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:40.251995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:39.431333Z","time spent":"820.649751ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T07:40:40.252027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:39.76416Z","time spent":"487.858193ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:40:40.383426Z","caller":"traceutil/trace.go:171","msg":"trace[334555123] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"127.692606ms","start":"2026-02-25T07:40:40.255717Z","end":"2026-02-25T07:40:40.38341Z","steps":["trace[334555123] 'process raft request' (duration: 127.436799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:46.936217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:40:47.391855Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019110505s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T07:40:47.392167Z","caller":"traceutil/trace.go:171","msg":"trace[735090317] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"956.858008ms","start":"2026-02-25T07:40:46.435292Z","end":"2026-02-25T07:40:47.39215Z","steps":["trace[735090317] 'read index received' (duration: 956.696574ms)","trace[735090317] 'applied index is now lower than readState.Index' (duration: 160.734µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:40:47.392254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.95903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:40:47.392282Z","caller":"traceutil/trace.go:171","msg":"trace[890793534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"957.061613ms","start":"2026-02-25T07:40:46.435207Z","end":"2026-02-25T07:40:47.392268Z","steps":["trace[890793534] 'agreement among raft nodes before linearized reading' (duration: 957.006021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:47.392305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:46.435185Z","time spent":"957.115225ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T07:40:47.392357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.218138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T07:40:47.392398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.107422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:40:47.392432Z","caller":"traceutil/trace.go:171","msg":"trace[876135785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"630.353971ms","start":"2026-02-25T07:40:46.762053Z","end":"2026-02-25T07:40:47.392407Z","steps":["trace[876135785] 'agreement among raft nodes before linearized reading' (duration: 630.228418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:47.392468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:46.762025Z","time spent":"630.438205ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:40:47.392425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.178434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:40:47.392472Z","caller":"traceutil/trace.go:171","msg":"trace[2115625431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"893.298246ms","start":"2026-02-25T07:40:46.499156Z","end":"2026-02-25T07:40:47.392454Z","steps":["trace[2115625431] 'agreement among raft nodes before linearized reading' (duration: 893.113342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:47.392515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:46.499123Z","time spent":"893.38665ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T07:40:47.392516Z","caller":"traceutil/trace.go:171","msg":"trace[1453070656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"893.343659ms","start":"2026-02-25T07:40:46.499156Z","end":"2026-02-25T07:40:47.392499Z","steps":["trace[1453070656] 'agreement among raft nodes before linearized reading' (duration: 893.141603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:47.392562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:46.499123Z","time spent":"893.429791ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:40:47.392736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.704208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:40:47.392762Z","caller":"traceutil/trace.go:171","msg":"trace[2083685090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"893.745339ms","start":"2026-02-25T07:40:46.499008Z","end":"2026-02-25T07:40:47.392753Z","steps":["trace[2083685090] 'agreement among raft nodes before linearized reading' (duration: 893.234085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:47.392781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:46.498999Z","time spent":"893.77769ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T07:40:47.393052Z","caller":"traceutil/trace.go:171","msg":"trace[754458742] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.019640569s","start":"2026-02-25T07:40:46.372606Z","end":"2026-02-25T07:40:47.392247Z","steps":["trace[754458742] 'process raft request' (duration: 1.019433004s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:47.393283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:46.372564Z","time spent":"1.020530963s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:40:47.649241Z","caller":"traceutil/trace.go:171","msg":"trace[1399695736] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"253.224326ms","start":"2026-02-25T07:40:47.395985Z","end":"2026-02-25T07:40:47.64921Z","steps":["trace[1399695736] 'process raft request' (duration: 243.038865ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:47.649507Z","caller":"traceutil/trace.go:171","msg":"trace[626654922] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"252.765955ms","start":"2026-02-25T07:40:47.396715Z","end":"2026-02-25T07:40:47.649481Z","steps":["trace[626654922] 'process raft request' (duration: 252.502278ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:47.649503Z","caller":"traceutil/trace.go:171","msg":"trace[557225016] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"252.745774ms","start":"2026-02-25T07:40:47.396731Z","end":"2026-02-25T07:40:47.649476Z","steps":["trace[557225016] 'process raft request' (duration: 252.612241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:47.649282Z","caller":"traceutil/trace.go:171","msg":"trace[206235386] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"253.168775ms","start":"2026-02-25T07:40:47.396089Z","end":"2026-02-25T07:40:47.649258Z","steps":["trace[206235386] 'process raft request' (duration: 253.023902ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:49.970906Z","caller":"traceutil/trace.go:171","msg":"trace[1904742660] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"102.970722ms","start":"2026-02-25T07:40:49.867886Z","end":"2026-02-25T07:40:49.970857Z","steps":["trace[1904742660] 'process raft request' (duration: 30.108863ms)","trace[1904742660] 'compare' (duration: 72.674614ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:40:49.982076Z","caller":"traceutil/trace.go:171","msg":"trace[132323510] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"112.804933ms","start":"2026-02-25T07:40:49.869248Z","end":"2026-02-25T07:40:49.982053Z","steps":["trace[132323510] 'process raft request' (duration: 112.698431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:52.264566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T07:40:52.559131Z","caller":"traceutil/trace.go:171","msg":"trace[1496453308] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"889.205044ms","start":"2026-02-25T07:40:51.669898Z","end":"2026-02-25T07:40:52.559103Z","steps":["trace[1496453308] 'process raft request' (duration: 889.04435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:52.55937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:51.669881Z","time spent":"889.400178ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:40:53.764052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999906898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T07:40:53.764157Z","caller":"traceutil/trace.go:171","msg":"trace[1503083965] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000057723s","start":"2026-02-25T07:40:51.764082Z","end":"2026-02-25T07:40:53.764139Z","steps":["trace[1503083965] 'agreement among raft nodes before linearized reading' (duration: 1.999934259s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:53.764206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:51.764067Z","time spent":"2.000127425s","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:40:55.005367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:53.572349Z","time spent":"1.433009284s","remote":"127.0.0.1:58642","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T07:40:55.41769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.83297095s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T07:40:55.418374Z","caller":"traceutil/trace.go:171","msg":"trace[1287338631] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"3.747261217s","start":"2026-02-25T07:40:51.671084Z","end":"2026-02-25T07:40:55.418345Z","steps":["trace[1287338631] 'process raft request' (duration: 3.747154434s)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.418375Z","caller":"traceutil/trace.go:171","msg":"trace[771928906] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"3.747333319s","start":"2026-02-25T07:40:51.671013Z","end":"2026-02-25T07:40:55.418346Z","steps":["trace[771928906] 'process raft request' (duration: 3.74698903s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:55.418555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:51.671001Z","time spent":"3.747449773s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:40:55.418554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:51.671068Z","time spent":"3.747382561s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:40:55.418601Z","caller":"traceutil/trace.go:171","msg":"trace[1581765716] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:529; }","duration":"3.654391645s","start":"2026-02-25T07:40:51.764117Z","end":"2026-02-25T07:40:55.418509Z","steps":["trace[1581765716] 'read index received' (duration: 794.833232ms)","trace[1581765716] 'applied index is now lower than readState.Index' (duration: 2.859554813s)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:40:55.418715Z","caller":"traceutil/trace.go:171","msg":"trace[738881162] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"3.747473823s","start":"2026-02-25T07:40:51.671225Z","end":"2026-02-25T07:40:55.418699Z","steps":["trace[738881162] 'process raft request' (duration: 3.747071482s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:55.418795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:51.671209Z","time spent":"3.747543895s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:40:55.482894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60585234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T07:40:55.482896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710852373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T07:40:55.482975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78402474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:40:55.48297Z","caller":"traceutil/trace.go:171","msg":"trace[146479691] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:515; }","duration":"1.605966073s","start":"2026-02-25T07:40:53.876988Z","end":"2026-02-25T07:40:55.482954Z","steps":["trace[146479691] 'agreement among raft nodes before linearized reading' (duration: 1.605845999s)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.482979Z","caller":"traceutil/trace.go:171","msg":"trace[1569688840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.710969146s","start":"2026-02-25T07:40:53.771995Z","end":"2026-02-25T07:40:55.482964Z","steps":["trace[1569688840] 'agreement among raft nodes before linearized reading' (duration: 1.710825011s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:55.483021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:53.876966Z","time spent":"1.606046374s","remote":"127.0.0.1:41920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-02-25T07:40:55.483021Z","caller":"traceutil/trace.go:171","msg":"trace[838807183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.784084242s","start":"2026-02-25T07:40:53.698915Z","end":"2026-02-25T07:40:55.483Z","steps":["trace[838807183] 'agreement among raft nodes before linearized reading' (duration: 1.784034781s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:55.483028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:53.771986Z","time spent":"1.711031967s","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:40:55.483068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:53.698894Z","time spent":"1.784166263s","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:40:55.483169Z","caller":"traceutil/trace.go:171","msg":"trace[1129368664] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.714809015s","start":"2026-02-25T07:40:53.768346Z","end":"2026-02-25T07:40:55.483155Z","steps":["trace[1129368664] 'process raft request' (duration: 1.714386443s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:55.482899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.876643572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4020"} {"level":"warn","ts":"2026-02-25T07:40:55.483006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.309896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:40:55.483328Z","caller":"traceutil/trace.go:171","msg":"trace[1872107188] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"2.376612804s","start":"2026-02-25T07:40:53.106689Z","end":"2026-02-25T07:40:55.483302Z","steps":["trace[1872107188] 'process raft request' (duration: 2.375908965s)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.483357Z","caller":"traceutil/trace.go:171","msg":"trace[294099005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"919.739488ms","start":"2026-02-25T07:40:54.563605Z","end":"2026-02-25T07:40:55.483344Z","steps":["trace[294099005] 'agreement among raft nodes before linearized reading' (duration: 919.195783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.483344Z","caller":"traceutil/trace.go:171","msg":"trace[70933570] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:515; }","duration":"2.877132524s","start":"2026-02-25T07:40:52.606182Z","end":"2026-02-25T07:40:55.483315Z","steps":["trace[70933570] 'agreement among raft nodes before linearized reading' (duration: 2.87660532s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:55.483409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:54.563584Z","time spent":"919.813679ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:40:55.483431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:52.606158Z","time spent":"2.877240457s","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T07:40:55.483448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:53.106661Z","time spent":"2.376719127s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:40:55.483271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:53.768322Z","time spent":"1.714882487s","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T07:40:55.642706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.106206ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-25T07:40:55.723991Z","caller":"traceutil/trace.go:171","msg":"trace[1414051836] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"235.396369ms","start":"2026-02-25T07:40:55.488574Z","end":"2026-02-25T07:40:55.72397Z","steps":["trace[1414051836] 'process raft request' (duration: 235.137931ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.723954Z","caller":"traceutil/trace.go:171","msg":"trace[95193899] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:535; }","duration":"232.29522ms","start":"2026-02-25T07:40:55.491632Z","end":"2026-02-25T07:40:55.723927Z","steps":["trace[95193899] 'read index received' (duration: 24.954261ms)","trace[95193899] 'applied index is now lower than readState.Index' (duration: 207.339829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:40:55.724105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.451114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:40:55.724167Z","caller":"traceutil/trace.go:171","msg":"trace[123718504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"232.558927ms","start":"2026-02-25T07:40:55.491594Z","end":"2026-02-25T07:40:55.724153Z","steps":["trace[123718504] 'agreement among raft nodes before linearized reading' (duration: 232.430773ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.724182Z","caller":"traceutil/trace.go:171","msg":"trace[2001532953] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"233.44748ms","start":"2026-02-25T07:40:55.490712Z","end":"2026-02-25T07:40:55.72416Z","steps":["trace[2001532953] 'process raft request' (duration: 233.170003ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:40:55.793213Z","caller":"traceutil/trace.go:171","msg":"trace[709530462] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"149.506705ms","start":"2026-02-25T07:40:55.643678Z","end":"2026-02-25T07:40:55.793185Z","steps":["trace[709530462] 'process raft request' (duration: 107.924818ms)","trace[709530462] 'compare' (duration: 41.416663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:40:58.266382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518773,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:40:58.766663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518773,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:40:58.917865Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185372401s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T07:40:58.918281Z","caller":"traceutil/trace.go:171","msg":"trace[1225643402] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.185861243s","start":"2026-02-25T07:40:57.732399Z","end":"2026-02-25T07:40:58.91826Z","steps":["trace[1225643402] 'process raft request' (duration: 1.185674648s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:58.918373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15293974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:40:58.918424Z","caller":"traceutil/trace.go:171","msg":"trace[1489300885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"1.153044423s","start":"2026-02-25T07:40:57.765369Z","end":"2026-02-25T07:40:58.918413Z","steps":["trace[1489300885] 'agreement among raft nodes before linearized reading' (duration: 1.15294018s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:40:58.918389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:57.732382Z","time spent":"1.185936305s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:40:58.918475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:40:57.765356Z","time spent":"1.153111024s","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:40:58.91826Z","caller":"traceutil/trace.go:171","msg":"trace[648778817] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"1.152830897s","start":"2026-02-25T07:40:57.765404Z","end":"2026-02-25T07:40:58.918235Z","steps":["trace[648778817] 'read index received' (duration: 1.152574251s)","trace[648778817] 'applied index is now lower than readState.Index' (duration: 255.376µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:05.424589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.313145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:05.424666Z","caller":"traceutil/trace.go:171","msg":"trace[1253712897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"659.466948ms","start":"2026-02-25T07:41:04.765184Z","end":"2026-02-25T07:41:05.424651Z","steps":["trace[1253712897] 'range keys from in-memory index tree' (duration: 659.201602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:05.424699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:04.765151Z","time spent":"659.54157ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:41:05.424816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.149462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:41:05.424884Z","caller":"traceutil/trace.go:171","msg":"trace[806880125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"440.271936ms","start":"2026-02-25T07:41:04.984595Z","end":"2026-02-25T07:41:05.424867Z","steps":["trace[806880125] 'range keys from in-memory index tree' (duration: 439.956837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:05.424923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:04.984559Z","time spent":"440.355327ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:05.425026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.703669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rslg2\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T07:41:05.425051Z","caller":"traceutil/trace.go:171","msg":"trace[143340535] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rslg2; range_end:; response_count:1; response_revision:538; }","duration":"481.7687ms","start":"2026-02-25T07:41:04.943275Z","end":"2026-02-25T07:41:05.425044Z","steps":["trace[143340535] 'range keys from in-memory index tree' (duration: 481.070491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:05.425068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:04.943209Z","time spent":"481.855282ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-rslg2\" "} {"level":"info","ts":"2026-02-25T07:41:05.629988Z","caller":"traceutil/trace.go:171","msg":"trace[15988623] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"201.293864ms","start":"2026-02-25T07:41:05.428657Z","end":"2026-02-25T07:41:05.629951Z","steps":["trace[15988623] 'process raft request' (duration: 201.064018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:06.388502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.83713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:41:06.388605Z","caller":"traceutil/trace.go:171","msg":"trace[2021110803] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"924.123558ms","start":"2026-02-25T07:41:05.464465Z","end":"2026-02-25T07:41:06.388588Z","steps":["trace[2021110803] 'read index received' (duration: 165.24069ms)","trace[2021110803] 'applied index is now lower than readState.Index' (duration: 758.881388ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:06.388672Z","caller":"traceutil/trace.go:171","msg":"trace[1215682300] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"947.814637ms","start":"2026-02-25T07:41:05.440819Z","end":"2026-02-25T07:41:06.388634Z","steps":["trace[1215682300] 'process raft request' (duration: 338.765332ms)","trace[1215682300] 'compare' (duration: 608.720706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:06.388791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:05.440793Z","time spent":"947.93273ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:06.388881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.454427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:41:06.388916Z","caller":"traceutil/trace.go:171","msg":"trace[450175258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"924.528959ms","start":"2026-02-25T07:41:05.464377Z","end":"2026-02-25T07:41:06.388906Z","steps":["trace[450175258] 'agreement among raft nodes before linearized reading' (duration: 924.281573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:06.388954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:05.464361Z","time spent":"924.58465ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T07:41:06.738543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.477946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:41:06.738753Z","caller":"traceutil/trace.go:171","msg":"trace[306068813] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"342.932257ms","start":"2026-02-25T07:41:06.395803Z","end":"2026-02-25T07:41:06.738735Z","steps":["trace[306068813] 'process raft request' (duration: 342.836955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:06.738819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:06.395772Z","time spent":"343.0157ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:41:06.738874Z","caller":"traceutil/trace.go:171","msg":"trace[1814179885] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.007158799s","start":"2026-02-25T07:41:05.731681Z","end":"2026-02-25T07:41:06.73884Z","steps":["trace[1814179885] 'process raft request' (duration: 892.288702ms)","trace[1814179885] 'compare' (duration: 114.23259ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:06.738959Z","caller":"traceutil/trace.go:171","msg":"trace[1613223541] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"350.309138ms","start":"2026-02-25T07:41:06.388632Z","end":"2026-02-25T07:41:06.738941Z","steps":["trace[1613223541] 'read index received' (duration: 235.349858ms)","trace[1613223541] 'applied index is now lower than readState.Index' (duration: 114.957439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:06.739082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.64481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T07:41:06.73907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:05.731659Z","time spent":"1.007301973s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:06.739109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240824813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:41:06.739116Z","caller":"traceutil/trace.go:171","msg":"trace[546993050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"973.718582ms","start":"2026-02-25T07:41:05.765387Z","end":"2026-02-25T07:41:06.739106Z","steps":["trace[546993050] 'agreement among raft nodes before linearized reading' (duration: 973.66764ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:06.739141Z","caller":"traceutil/trace.go:171","msg":"trace[2096459736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"1.240898075s","start":"2026-02-25T07:41:05.498232Z","end":"2026-02-25T07:41:06.73913Z","steps":["trace[2096459736] 'agreement among raft nodes before linearized reading' (duration: 1.240765252s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:06.739144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:05.765364Z","time spent":"973.774743ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:41:06.739176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:05.498214Z","time spent":"1.240954677s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:06.739178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201862234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:41:06.739258Z","caller":"traceutil/trace.go:171","msg":"trace[1732678455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"1.201928485s","start":"2026-02-25T07:41:05.537281Z","end":"2026-02-25T07:41:06.73921Z","steps":["trace[1732678455] 'agreement among raft nodes before linearized reading' (duration: 1.201783752s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:06.739313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:05.53727Z","time spent":"1.202030439s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:41:08.264943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:08.765428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:09.265806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448518830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:09.327384Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.629588688s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T07:41:09.472811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838719163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:41:09.472894Z","caller":"traceutil/trace.go:171","msg":"trace[737750464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.838852036s","start":"2026-02-25T07:41:07.634025Z","end":"2026-02-25T07:41:09.472877Z","steps":["trace[737750464] 'range keys from in-memory index tree' (duration: 1.838562278s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:09.472934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:07.634003Z","time spent":"1.838921127s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:09.473257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.638086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:41:09.473348Z","caller":"traceutil/trace.go:171","msg":"trace[1777367689] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"1.709099897s","start":"2026-02-25T07:41:07.764212Z","end":"2026-02-25T07:41:09.473312Z","steps":["trace[1777367689] 'read index received' (duration: 1.563303797s)","trace[1777367689] 'applied index is now lower than readState.Index' (duration: 145.79515ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:09.473427Z","caller":"traceutil/trace.go:171","msg":"trace[194688716] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.775713386s","start":"2026-02-25T07:41:07.697689Z","end":"2026-02-25T07:41:09.473403Z","steps":["trace[194688716] 'process raft request' (duration: 1.629876725s)","trace[194688716] 'compare' (duration: 145.457132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:09.473458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709248181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:09.473491Z","caller":"traceutil/trace.go:171","msg":"trace[1849264105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.709315143s","start":"2026-02-25T07:41:07.764167Z","end":"2026-02-25T07:41:09.473482Z","steps":["trace[1849264105] 'agreement among raft nodes before linearized reading' (duration: 1.709242921s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:09.473516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:07.764144Z","time spent":"1.709366414s","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:41:09.47352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:07.697668Z","time spent":"1.775805429s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:09.473722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.334157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:41:09.473746Z","caller":"traceutil/trace.go:171","msg":"trace[1798985838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"730.405529ms","start":"2026-02-25T07:41:08.743335Z","end":"2026-02-25T07:41:09.473741Z","steps":["trace[1798985838] 'agreement among raft nodes before linearized reading' (duration: 730.320077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:09.473764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:08.743309Z","time spent":"730.450569ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T07:41:09.473907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.369857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:41:09.473932Z","caller":"traceutil/trace.go:171","msg":"trace[197852748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"682.405178ms","start":"2026-02-25T07:41:08.791521Z","end":"2026-02-25T07:41:09.473926Z","steps":["trace[197852748] 'agreement among raft nodes before linearized reading' (duration: 682.339236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:09.473962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:08.791513Z","time spent":"682.444058ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:41:09.473976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.651014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:41:09.474013Z","caller":"traceutil/trace.go:171","msg":"trace[1400052839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"705.714435ms","start":"2026-02-25T07:41:08.768288Z","end":"2026-02-25T07:41:09.474002Z","steps":["trace[1400052839] 'agreement among raft nodes before linearized reading' (duration: 705.613803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:09.474058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:08.768273Z","time spent":"705.775587ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T07:41:09.582824Z","caller":"traceutil/trace.go:171","msg":"trace[565831110] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"105.579619ms","start":"2026-02-25T07:41:09.477218Z","end":"2026-02-25T07:41:09.582798Z","steps":["trace[565831110] 'process raft request' (duration: 105.22221ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:09.613161Z","caller":"traceutil/trace.go:171","msg":"trace[784678217] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:568; }","duration":"130.95486ms","start":"2026-02-25T07:41:09.482169Z","end":"2026-02-25T07:41:09.613124Z","steps":["trace[784678217] 'read index received' (duration: 100.323274ms)","trace[784678217] 'applied index is now lower than readState.Index' (duration: 30.630366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:09.61328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.093793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:09.613255Z","caller":"traceutil/trace.go:171","msg":"trace[247349775] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"135.728713ms","start":"2026-02-25T07:41:09.4775Z","end":"2026-02-25T07:41:09.613229Z","steps":["trace[247349775] 'process raft request' (duration: 135.414504ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:09.613318Z","caller":"traceutil/trace.go:171","msg":"trace[844712236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"131.164205ms","start":"2026-02-25T07:41:09.482144Z","end":"2026-02-25T07:41:09.613308Z","steps":["trace[844712236] 'agreement among raft nodes before linearized reading' (duration: 131.085483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:09.613298Z","caller":"traceutil/trace.go:171","msg":"trace[1732075849] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"135.545648ms","start":"2026-02-25T07:41:09.477661Z","end":"2026-02-25T07:41:09.613206Z","steps":["trace[1732075849] 'process raft request' (duration: 135.381054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:09.613311Z","caller":"traceutil/trace.go:171","msg":"trace[577033572] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"135.62557ms","start":"2026-02-25T07:41:09.477663Z","end":"2026-02-25T07:41:09.613288Z","steps":["trace[577033572] 'process raft request' (duration: 135.416295ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:10.138703Z","caller":"traceutil/trace.go:171","msg":"trace[283982500] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"166.100272ms","start":"2026-02-25T07:41:09.972569Z","end":"2026-02-25T07:41:10.138669Z","steps":["trace[283982500] 'process raft request' (duration: 139.10828ms)","trace[283982500] 'compare' (duration: 26.791267ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:13.326408Z","caller":"traceutil/trace.go:171","msg":"trace[967030758] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"318.383599ms","start":"2026-02-25T07:41:13.008004Z","end":"2026-02-25T07:41:13.326387Z","steps":["trace[967030758] 'process raft request' (duration: 316.49163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:13.326698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:13.007986Z","time spent":"318.490921ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17651,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:41:13.333989Z","caller":"traceutil/trace.go:171","msg":"trace[136329288] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"195.725882ms","start":"2026-02-25T07:41:13.138242Z","end":"2026-02-25T07:41:13.333968Z","steps":["trace[136329288] 'process raft request' (duration: 195.64509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.296335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.092471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:41:16.296536Z","caller":"traceutil/trace.go:171","msg":"trace[128861394] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"597.756755ms","start":"2026-02-25T07:41:15.698758Z","end":"2026-02-25T07:41:16.296515Z","steps":["trace[128861394] 'read index received' (duration: 1.907429ms)","trace[128861394] 'applied index is now lower than readState.Index' (duration: 595.848326ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:16.296633Z","caller":"traceutil/trace.go:171","msg":"trace[1150583880] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"643.866599ms","start":"2026-02-25T07:41:15.652757Z","end":"2026-02-25T07:41:16.296623Z","steps":["trace[1150583880] 'process raft request' (duration: 643.682214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.296701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:15.652749Z","time spent":"643.91521ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:16.296729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.95384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T07:41:16.296782Z","caller":"traceutil/trace.go:171","msg":"trace[1227050539] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:622; }","duration":"598.014372ms","start":"2026-02-25T07:41:15.698754Z","end":"2026-02-25T07:41:16.296768Z","steps":["trace[1227050539] 'agreement among raft nodes before linearized reading' (duration: 597.908329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.296809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:15.69872Z","time spent":"598.084044ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T07:41:16.296781Z","caller":"traceutil/trace.go:171","msg":"trace[1231053673] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"644.098064ms","start":"2026-02-25T07:41:15.652652Z","end":"2026-02-25T07:41:16.29675Z","steps":["trace[1231053673] 'process raft request' (duration: 256.503691ms)","trace[1231053673] 'compare' (duration: 386.965037ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:16.296924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:15.652644Z","time spent":"644.205757ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:41:16.364006Z","caller":"traceutil/trace.go:171","msg":"trace[1778414351] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"422.90931ms","start":"2026-02-25T07:41:15.941079Z","end":"2026-02-25T07:41:16.363988Z","steps":["trace[1778414351] 'process raft request' (duration: 422.824218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.364104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:15.941055Z","time spent":"423.002922ms","remote":"127.0.0.1:41936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T07:41:16.364778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.550594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-25T07:41:16.364854Z","caller":"traceutil/trace.go:171","msg":"trace[738375865] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:623; }","duration":"626.663998ms","start":"2026-02-25T07:41:15.738171Z","end":"2026-02-25T07:41:16.364835Z","steps":["trace[738375865] 'agreement among raft nodes before linearized reading' (duration: 626.402041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.364896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:15.73816Z","time spent":"626.727699ms","remote":"127.0.0.1:41936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-25T07:41:16.365164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.750654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-25T07:41:16.365193Z","caller":"traceutil/trace.go:171","msg":"trace[1257636757] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:623; }","duration":"227.780165ms","start":"2026-02-25T07:41:16.137404Z","end":"2026-02-25T07:41:16.365184Z","steps":["trace[1257636757] 'agreement among raft nodes before linearized reading' (duration: 227.695572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.365356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.788727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rslg2\" ","response":"range_response_count:1 size:18071"} {"level":"info","ts":"2026-02-25T07:41:16.365383Z","caller":"traceutil/trace.go:171","msg":"trace[76961946] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rslg2; range_end:; response_count:1; response_revision:623; }","duration":"346.813487ms","start":"2026-02-25T07:41:16.01856Z","end":"2026-02-25T07:41:16.365373Z","steps":["trace[76961946] 'agreement among raft nodes before linearized reading' (duration: 346.740105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.365406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:16.018507Z","time spent":"346.89415ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18093,"request content":"key:\"/registry/pods/kube-system/cilium-rslg2\" "} {"level":"warn","ts":"2026-02-25T07:41:16.365533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.192669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:16.365557Z","caller":"traceutil/trace.go:171","msg":"trace[707956963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"602.227961ms","start":"2026-02-25T07:41:15.763321Z","end":"2026-02-25T07:41:16.365549Z","steps":["trace[707956963] 'agreement among raft nodes before linearized reading' (duration: 602.19366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:16.365579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:15.763313Z","time spent":"602.261251ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:41:16.518059Z","caller":"traceutil/trace.go:171","msg":"trace[1706529130] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"180.374927ms","start":"2026-02-25T07:41:16.337661Z","end":"2026-02-25T07:41:16.518036Z","steps":["trace[1706529130] 'process raft request' (duration: 168.716859ms)","trace[1706529130] 'compare' (duration: 11.472584ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:16.518001Z","caller":"traceutil/trace.go:171","msg":"trace[1845304200] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"149.476845ms","start":"2026-02-25T07:41:16.368499Z","end":"2026-02-25T07:41:16.517976Z","steps":["trace[1845304200] 'read index received' (duration: 137.836387ms)","trace[1845304200] 'applied index is now lower than readState.Index' (duration: 11.639368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:16.518197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.587347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:16.51823Z","caller":"traceutil/trace.go:171","msg":"trace[66388336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"149.749541ms","start":"2026-02-25T07:41:16.368471Z","end":"2026-02-25T07:41:16.518221Z","steps":["trace[66388336] 'agreement among raft nodes before linearized reading' (duration: 149.545356ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:16.531549Z","caller":"traceutil/trace.go:171","msg":"trace[40976110] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"159.224195ms","start":"2026-02-25T07:41:16.372309Z","end":"2026-02-25T07:41:16.531534Z","steps":["trace[40976110] 'process raft request' (duration: 158.565388ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:16.531928Z","caller":"traceutil/trace.go:171","msg":"trace[1667366339] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"152.8514ms","start":"2026-02-25T07:41:16.379057Z","end":"2026-02-25T07:41:16.531909Z","steps":["trace[1667366339] 'process raft request' (duration: 151.908626ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:16.532053Z","caller":"traceutil/trace.go:171","msg":"trace[127478247] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"140.157486ms","start":"2026-02-25T07:41:16.39188Z","end":"2026-02-25T07:41:16.532038Z","steps":["trace[127478247] 'process raft request' (duration: 139.243742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:19.923566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.371107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:41:19.923778Z","caller":"traceutil/trace.go:171","msg":"trace[1689267951] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"152.470381ms","start":"2026-02-25T07:41:19.771268Z","end":"2026-02-25T07:41:19.923739Z","steps":["trace[1689267951] 'process raft request' (duration: 149.546606ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:19.923656Z","caller":"traceutil/trace.go:171","msg":"trace[1619277207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"210.515081ms","start":"2026-02-25T07:41:19.713123Z","end":"2026-02-25T07:41:19.923638Z","steps":["trace[1619277207] 'range keys from in-memory index tree' (duration: 210.182623ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:20.382749Z","caller":"traceutil/trace.go:171","msg":"trace[501577495] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"303.342482ms","start":"2026-02-25T07:41:20.079386Z","end":"2026-02-25T07:41:20.382728Z","steps":["trace[501577495] 'process raft request' (duration: 300.744695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:20.38285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:20.079361Z","time spent":"303.442495ms","remote":"127.0.0.1:33334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T07:41:22.107279Z","caller":"traceutil/trace.go:171","msg":"trace[2112519414] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"123.89034ms","start":"2026-02-25T07:41:21.983359Z","end":"2026-02-25T07:41:22.107212Z","steps":["trace[2112519414] 'process raft request' (duration: 123.704364ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:22.550194Z","caller":"traceutil/trace.go:171","msg":"trace[1062218242] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"137.793926ms","start":"2026-02-25T07:41:22.412372Z","end":"2026-02-25T07:41:22.550166Z","steps":["trace[1062218242] 'process raft request' (duration: 51.775579ms)","trace[1062218242] 'compare' (duration: 85.843542ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:22.550427Z","caller":"traceutil/trace.go:171","msg":"trace[196372817] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"137.859897ms","start":"2026-02-25T07:41:22.412543Z","end":"2026-02-25T07:41:22.550403Z","steps":["trace[196372817] 'process raft request' (duration: 137.57791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:22.550627Z","caller":"traceutil/trace.go:171","msg":"trace[1722182315] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"137.969759ms","start":"2026-02-25T07:41:22.412619Z","end":"2026-02-25T07:41:22.550589Z","steps":["trace[1722182315] 'process raft request' (duration: 137.732923ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:26.674852Z","caller":"traceutil/trace.go:171","msg":"trace[81106628] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"113.138442ms","start":"2026-02-25T07:41:26.561697Z","end":"2026-02-25T07:41:26.674836Z","steps":["trace[81106628] 'read index received' (duration: 112.990119ms)","trace[81106628] 'applied index is now lower than readState.Index' (duration: 147.653µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:26.674936Z","caller":"traceutil/trace.go:171","msg":"trace[30338491] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"552.476824ms","start":"2026-02-25T07:41:26.122423Z","end":"2026-02-25T07:41:26.6749Z","steps":["trace[30338491] 'process raft request' (duration: 552.30527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:26.675002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.302616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:41:26.675027Z","caller":"traceutil/trace.go:171","msg":"trace[1773311863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"113.377108ms","start":"2026-02-25T07:41:26.561642Z","end":"2026-02-25T07:41:26.675019Z","steps":["trace[1773311863] 'agreement among raft nodes before linearized reading' (duration: 113.277156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:26.675086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.122406Z","time spent":"552.604027ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:26.675142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.264973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:41:26.67525Z","caller":"traceutil/trace.go:171","msg":"trace[155853735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"109.377776ms","start":"2026-02-25T07:41:26.565826Z","end":"2026-02-25T07:41:26.675204Z","steps":["trace[155853735] 'agreement among raft nodes before linearized reading' (duration: 109.14624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:27.262459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519171,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T07:41:27.74808Z","caller":"traceutil/trace.go:171","msg":"trace[1634486410] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.06955658s","start":"2026-02-25T07:41:26.678505Z","end":"2026-02-25T07:41:27.748062Z","steps":["trace[1634486410] 'process raft request' (duration: 998.427756ms)","trace[1634486410] 'compare' (duration: 70.786485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:27.748213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.67849Z","time spent":"1.069642152s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:28.762282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999914548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T07:41:28.762362Z","caller":"traceutil/trace.go:171","msg":"trace[849676155] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000051332s","start":"2026-02-25T07:41:26.762296Z","end":"2026-02-25T07:41:28.762347Z","steps":["trace[849676155] 'agreement among raft nodes before linearized reading' (duration: 1.999950989s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:28.762405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.762276Z","time spent":"2.000115023s","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:41:29.288356Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.598956142s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T07:41:29.288672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:28.102275Z","time spent":"1.186393257s","remote":"127.0.0.1:36872","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T07:41:29.28938Z","caller":"traceutil/trace.go:171","msg":"trace[1054961883] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:754; }","duration":"2.527010471s","start":"2026-02-25T07:41:26.762352Z","end":"2026-02-25T07:41:29.289362Z","steps":["trace[1054961883] 'read index received' (duration: 914.559184ms)","trace[1054961883] 'applied index is now lower than readState.Index' (duration: 1.612450347s)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:29.289491Z","caller":"traceutil/trace.go:171","msg":"trace[1803668977] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"2.352829813s","start":"2026-02-25T07:41:26.936641Z","end":"2026-02-25T07:41:29.289471Z","steps":["trace[1803668977] 'process raft request' (duration: 2.352604558s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.289585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.936622Z","time spent":"2.352916265s","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T07:41:29.289616Z","caller":"traceutil/trace.go:171","msg":"trace[1211062537] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"2.040224933s","start":"2026-02-25T07:41:27.249381Z","end":"2026-02-25T07:41:29.289606Z","steps":["trace[1211062537] 'process raft request' (duration: 2.039945806s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.289714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:27.249362Z","time spent":"2.040286234s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:29.289835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.489095328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T07:41:29.289973Z","caller":"traceutil/trace.go:171","msg":"trace[958497890] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"2.291794058s","start":"2026-02-25T07:41:26.998168Z","end":"2026-02-25T07:41:29.289962Z","steps":["trace[958497890] 'process raft request' (duration: 2.29112395s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.290024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.998141Z","time spent":"2.29185732s","remote":"127.0.0.1:42048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:41:29.289371Z","caller":"traceutil/trace.go:171","msg":"trace[1124274878] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"2.609691732s","start":"2026-02-25T07:41:26.679657Z","end":"2026-02-25T07:41:29.289349Z","steps":["trace[1124274878] 'process raft request' (duration: 2.60880206s)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:29.289868Z","caller":"traceutil/trace.go:171","msg":"trace[507977995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"2.489143499s","start":"2026-02-25T07:41:26.800714Z","end":"2026-02-25T07:41:29.289858Z","steps":["trace[507977995] 'agreement among raft nodes before linearized reading' (duration: 2.489033656s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.290152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.679646Z","time spent":"2.610482853s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:29.29017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:26.800707Z","time spent":"2.489438947s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:29.290328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.196847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:29.290378Z","caller":"traceutil/trace.go:171","msg":"trace[1340575353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"527.251948ms","start":"2026-02-25T07:41:28.763103Z","end":"2026-02-25T07:41:29.290355Z","steps":["trace[1340575353] 'agreement among raft nodes before linearized reading' (duration: 527.202806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.290404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:28.763087Z","time spent":"527.31122ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:41:29.290619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.708364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T07:41:29.290623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.049398998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:29.290656Z","caller":"traceutil/trace.go:171","msg":"trace[1170691190] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:730; }","duration":"2.049433939s","start":"2026-02-25T07:41:27.241213Z","end":"2026-02-25T07:41:29.290647Z","steps":["trace[1170691190] 'agreement among raft nodes before linearized reading' (duration: 2.049375507s)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:29.290648Z","caller":"traceutil/trace.go:171","msg":"trace[896656733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"611.765016ms","start":"2026-02-25T07:41:28.678874Z","end":"2026-02-25T07:41:29.290639Z","steps":["trace[896656733] 'agreement among raft nodes before linearized reading' (duration: 611.682794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.290691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:27.241154Z","time spent":"2.049529902s","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:41:29.290704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:28.678857Z","time spent":"611.840917ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:29.290839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075763769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:41:29.290874Z","caller":"traceutil/trace.go:171","msg":"trace[158164839] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:730; }","duration":"1.07582745s","start":"2026-02-25T07:41:28.215039Z","end":"2026-02-25T07:41:29.290866Z","steps":["trace[158164839] 'agreement among raft nodes before linearized reading' (duration: 1.075775349s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.290903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:28.215018Z","time spent":"1.075876392s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:41:29.291064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55838122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:41:29.291087Z","caller":"traceutil/trace.go:171","msg":"trace[162820930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"1.558434242s","start":"2026-02-25T07:41:27.732646Z","end":"2026-02-25T07:41:29.29108Z","steps":["trace[162820930] 'agreement among raft nodes before linearized reading' (duration: 1.55834492s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:29.291109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:27.732629Z","time spent":"1.558475382s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:41:31.83528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:32.33807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:32.83849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:33.339501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:33.76517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000634477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T07:41:33.765278Z","caller":"traceutil/trace.go:171","msg":"trace[171256450] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000794881s","start":"2026-02-25T07:41:31.764466Z","end":"2026-02-25T07:41:33.765261Z","steps":["trace[171256450] 'agreement among raft nodes before linearized reading' (duration: 2.000672268s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:33.765323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:31.764437Z","time spent":"2.000876773s","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 07:41:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T07:41:33.840529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:34.341371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:41:34.550511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.253218493s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T07:41:34.5508Z","caller":"traceutil/trace.go:171","msg":"trace[11936824] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"3.216530461s","start":"2026-02-25T07:41:31.334248Z","end":"2026-02-25T07:41:34.550778Z","steps":["trace[11936824] 'read index received' (duration: 3.216380397s)","trace[11936824] 'applied index is now lower than readState.Index' (duration: 149.564µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:34.550863Z","caller":"traceutil/trace.go:171","msg":"trace[1515881120] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"3.253640233s","start":"2026-02-25T07:41:31.297215Z","end":"2026-02-25T07:41:34.550855Z","steps":["trace[1515881120] 'process raft request' (duration: 3.253460888s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.550941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:31.2972Z","time spent":"3.253681964s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:34.551091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.21685151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:41:34.551115Z","caller":"traceutil/trace.go:171","msg":"trace[1366272523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"3.216902261s","start":"2026-02-25T07:41:31.334207Z","end":"2026-02-25T07:41:34.551109Z","steps":["trace[1366272523] 'agreement among raft nodes before linearized reading' (duration: 3.216820339s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:31.334191Z","time spent":"3.216938682s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:41:34.551325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.79414543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T07:41:34.551344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.837644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:34.551379Z","caller":"traceutil/trace.go:171","msg":"trace[2143156825] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:737; }","duration":"1.794249852s","start":"2026-02-25T07:41:32.757115Z","end":"2026-02-25T07:41:34.551365Z","steps":["trace[2143156825] 'agreement among raft nodes before linearized reading' (duration: 1.79413565s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.55138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.788001377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T07:41:34.551416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:32.757095Z","time spent":"1.794313544s","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-25T07:41:34.551412Z","caller":"traceutil/trace.go:171","msg":"trace[406408469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"851.943097ms","start":"2026-02-25T07:41:33.699452Z","end":"2026-02-25T07:41:34.551395Z","steps":["trace[406408469] 'agreement among raft nodes before linearized reading' (duration: 851.847065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:33.699431Z","time spent":"852.011538ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:41:34.551459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.011201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T07:41:34.551464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.210336442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:41:34.551493Z","caller":"traceutil/trace.go:171","msg":"trace[2053914099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"3.210382093s","start":"2026-02-25T07:41:31.341104Z","end":"2026-02-25T07:41:34.551486Z","steps":["trace[2053914099] 'agreement among raft nodes before linearized reading' (duration: 3.210319282s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.444578487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:41:34.551495Z","caller":"traceutil/trace.go:171","msg":"trace[1790496873] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:737; }","duration":"672.075262ms","start":"2026-02-25T07:41:33.879408Z","end":"2026-02-25T07:41:34.551483Z","steps":["trace[1790496873] 'agreement among raft nodes before linearized reading' (duration: 671.949289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:31.341095Z","time spent":"3.210411954s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T07:41:34.551516Z","caller":"traceutil/trace.go:171","msg":"trace[124962300] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:737; }","duration":"2.444647339s","start":"2026-02-25T07:41:32.106862Z","end":"2026-02-25T07:41:34.55151Z","steps":["trace[124962300] 'agreement among raft nodes before linearized reading' (duration: 2.444570897s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:33.87939Z","time spent":"672.135263ms","remote":"127.0.0.1:41920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-02-25T07:41:34.551538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:32.106829Z","time spent":"2.44470301s","remote":"127.0.0.1:42214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:41:34.551319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.468032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:34.551585Z","caller":"traceutil/trace.go:171","msg":"trace[1668252249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"785.78041ms","start":"2026-02-25T07:41:33.765798Z","end":"2026-02-25T07:41:34.551578Z","steps":["trace[1668252249] 'agreement among raft nodes before linearized reading' (duration: 785.460992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:33.765782Z","time spent":"785.818951ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:41:34.551417Z","caller":"traceutil/trace.go:171","msg":"trace[1997422623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"2.788078549s","start":"2026-02-25T07:41:31.763329Z","end":"2026-02-25T07:41:34.551408Z","steps":["trace[1997422623] 'agreement among raft nodes before linearized reading' (duration: 2.787951756s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:31.763311Z","time spent":"2.788344677s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:34.551871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.211714518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T07:41:34.551898Z","caller":"traceutil/trace.go:171","msg":"trace[1243432111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"3.211802671s","start":"2026-02-25T07:41:31.340088Z","end":"2026-02-25T07:41:34.551891Z","steps":["trace[1243432111] 'agreement among raft nodes before linearized reading' (duration: 3.211148284s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.551919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:31.340073Z","time spent":"3.211839201s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:34.74004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.139337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:41:34.740323Z","caller":"traceutil/trace.go:171","msg":"trace[1092837669] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"185.301044ms","start":"2026-02-25T07:41:34.554993Z","end":"2026-02-25T07:41:34.740294Z","steps":["trace[1092837669] 'process raft request' (duration: 63.814578ms)","trace[1092837669] 'compare' (duration: 120.943742ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:34.740363Z","caller":"traceutil/trace.go:171","msg":"trace[423282327] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"171.257284ms","start":"2026-02-25T07:41:34.569091Z","end":"2026-02-25T07:41:34.740348Z","steps":["trace[423282327] 'process raft request' (duration: 171.205422ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:34.740607Z","caller":"traceutil/trace.go:171","msg":"trace[1072452370] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:767; }","duration":"184.493524ms","start":"2026-02-25T07:41:34.556097Z","end":"2026-02-25T07:41:34.74059Z","steps":["trace[1072452370] 'read index received' (duration: 11.830304ms)","trace[1072452370] 'applied index is now lower than readState.Index' (duration: 172.66172ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:34.740617Z","caller":"traceutil/trace.go:171","msg":"trace[851665943] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"184.916905ms","start":"2026-02-25T07:41:34.55567Z","end":"2026-02-25T07:41:34.740587Z","steps":["trace[851665943] 'process raft request' (duration: 184.581626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:34.740836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.717559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18976d63cad657f1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-25T07:41:34.740923Z","caller":"traceutil/trace.go:171","msg":"trace[586766938] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18976d63cad657f1; range_end:; response_count:1; response_revision:742; }","duration":"184.785741ms","start":"2026-02-25T07:41:34.556092Z","end":"2026-02-25T07:41:34.740878Z","steps":["trace[586766938] 'agreement among raft nodes before linearized reading' (duration: 184.611027ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:34.740947Z","caller":"traceutil/trace.go:171","msg":"trace[2043911138] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"185.653663ms","start":"2026-02-25T07:41:34.555278Z","end":"2026-02-25T07:41:34.740932Z","steps":["trace[2043911138] 'process raft request' (duration: 184.930785ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:34.741156Z","caller":"traceutil/trace.go:171","msg":"trace[625745447] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"185.90754ms","start":"2026-02-25T07:41:34.555234Z","end":"2026-02-25T07:41:34.741142Z","steps":["trace[625745447] 'process raft request' (duration: 184.901834ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:36.965206Z","caller":"traceutil/trace.go:171","msg":"trace[1860328266] transaction","detail":"{read_only:false; number_of_response:1; response_revision:749; }","duration":"114.123477ms","start":"2026-02-25T07:41:36.851047Z","end":"2026-02-25T07:41:36.96517Z","steps":["trace[1860328266] 'process raft request' (duration: 46.139383ms)","trace[1860328266] 'compare' (duration: 67.691446ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:39.643583Z","caller":"traceutil/trace.go:171","msg":"trace[711757939] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"100.236251ms","start":"2026-02-25T07:41:39.543324Z","end":"2026-02-25T07:41:39.64356Z","steps":["trace[711757939] 'process raft request' (duration: 100.072917ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:47.090259Z","caller":"traceutil/trace.go:171","msg":"trace[267163336] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"155.859679ms","start":"2026-02-25T07:41:46.934376Z","end":"2026-02-25T07:41:47.090235Z","steps":["trace[267163336] 'process raft request' (duration: 76.440341ms)","trace[267163336] 'compare' (duration: 79.233373ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:41:47.090457Z","caller":"traceutil/trace.go:171","msg":"trace[1887065378] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"156.005643ms","start":"2026-02-25T07:41:46.934427Z","end":"2026-02-25T07:41:47.090433Z","steps":["trace[1887065378] 'process raft request' (duration: 155.762097ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:47.090666Z","caller":"traceutil/trace.go:171","msg":"trace[1132637520] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"155.304495ms","start":"2026-02-25T07:41:46.935334Z","end":"2026-02-25T07:41:47.090638Z","steps":["trace[1132637520] 'process raft request' (duration: 155.038198ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:50.820075Z","caller":"traceutil/trace.go:171","msg":"trace[858682240] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"139.448399ms","start":"2026-02-25T07:41:50.680606Z","end":"2026-02-25T07:41:50.820054Z","steps":["trace[858682240] 'process raft request' (duration: 138.758301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:51.096488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.888977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:41:51.096573Z","caller":"traceutil/trace.go:171","msg":"trace[2139562787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"152.02327ms","start":"2026-02-25T07:41:50.944528Z","end":"2026-02-25T07:41:51.096552Z","steps":["trace[2139562787] 'range keys from in-memory index tree' (duration: 151.679931ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:52.972905Z","caller":"traceutil/trace.go:171","msg":"trace[1170267302] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"144.190994ms","start":"2026-02-25T07:41:52.828688Z","end":"2026-02-25T07:41:52.972879Z","steps":["trace[1170267302] 'process raft request' (duration: 144.053839ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:53.36806Z","caller":"traceutil/trace.go:171","msg":"trace[1860870730] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"221.580536ms","start":"2026-02-25T07:41:53.146455Z","end":"2026-02-25T07:41:53.368036Z","steps":["trace[1860870730] 'process raft request' (duration: 221.448922ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:53.402356Z","caller":"traceutil/trace.go:171","msg":"trace[1205447848] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"238.949912ms","start":"2026-02-25T07:41:53.163393Z","end":"2026-02-25T07:41:53.402343Z","steps":["trace[1205447848] 'read index received' (duration: 204.554316ms)","trace[1205447848] 'applied index is now lower than readState.Index' (duration: 34.394836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:53.402633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.216479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T07:41:53.402707Z","caller":"traceutil/trace.go:171","msg":"trace[1250559470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"239.301831ms","start":"2026-02-25T07:41:53.163387Z","end":"2026-02-25T07:41:53.402689Z","steps":["trace[1250559470] 'agreement among raft nodes before linearized reading' (duration: 239.111296ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:53.402798Z","caller":"traceutil/trace.go:171","msg":"trace[1461972416] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"255.112569ms","start":"2026-02-25T07:41:53.147676Z","end":"2026-02-25T07:41:53.402788Z","steps":["trace[1461972416] 'process raft request' (duration: 254.591865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:53.402871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.947222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T07:41:53.402909Z","caller":"traceutil/trace.go:171","msg":"trace[858159332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"239.030485ms","start":"2026-02-25T07:41:53.163869Z","end":"2026-02-25T07:41:53.402899Z","steps":["trace[858159332] 'agreement among raft nodes before linearized reading' (duration: 238.924772ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:53.55044Z","caller":"traceutil/trace.go:171","msg":"trace[1717617371] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"143.556197ms","start":"2026-02-25T07:41:53.406852Z","end":"2026-02-25T07:41:53.550409Z","steps":["trace[1717617371] 'process raft request' (duration: 51.955871ms)","trace[1717617371] 'compare' (duration: 91.480263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:54.612066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.474806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:41:54.612411Z","caller":"traceutil/trace.go:171","msg":"trace[840217136] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:799; }","duration":"303.873916ms","start":"2026-02-25T07:41:54.30851Z","end":"2026-02-25T07:41:54.612384Z","steps":["trace[840217136] 'count revisions from in-memory index tree' (duration: 303.385713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:54.612602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:54.308486Z","time spent":"304.090842ms","remote":"127.0.0.1:42266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-25T07:41:55.231154Z","caller":"traceutil/trace.go:171","msg":"trace[601552138] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"110.253369ms","start":"2026-02-25T07:41:55.120874Z","end":"2026-02-25T07:41:55.231127Z","steps":["trace[601552138] 'read index received' (duration: 110.119646ms)","trace[601552138] 'applied index is now lower than readState.Index' (duration: 132.433µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:55.23136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.461024ms","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-02-25T07:41:55.231402Z","caller":"traceutil/trace.go:171","msg":"trace[1273585886] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:800; }","duration":"110.570327ms","start":"2026-02-25T07:41:55.12082Z","end":"2026-02-25T07:41:55.23139Z","steps":["trace[1273585886] 'agreement among raft nodes before linearized reading' (duration: 110.436924ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:55.231661Z","caller":"traceutil/trace.go:171","msg":"trace[337413917] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"250.792906ms","start":"2026-02-25T07:41:54.98082Z","end":"2026-02-25T07:41:55.231613Z","steps":["trace[337413917] 'process raft request' (duration: 250.149979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:56.354425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.469028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T07:41:56.354494Z","caller":"traceutil/trace.go:171","msg":"trace[1224067619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"799.590611ms","start":"2026-02-25T07:41:55.554888Z","end":"2026-02-25T07:41:56.354479Z","steps":["trace[1224067619] 'range keys from in-memory index tree' (duration: 799.285043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:56.354529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:55.554859Z","time spent":"799.660413ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:56.354559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.674848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T07:41:56.354617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.874973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T07:41:56.35466Z","caller":"traceutil/trace.go:171","msg":"trace[1814097228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"925.933614ms","start":"2026-02-25T07:41:55.428713Z","end":"2026-02-25T07:41:56.354646Z","steps":["trace[1814097228] 'range keys from in-memory index tree' (duration: 925.806021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:56.35469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:55.428706Z","time spent":"925.976836ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:41:56.354737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.197437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T07:41:56.354769Z","caller":"traceutil/trace.go:171","msg":"trace[485252924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"974.272219ms","start":"2026-02-25T07:41:55.380487Z","end":"2026-02-25T07:41:56.35476Z","steps":["trace[485252924] 'range keys from in-memory index tree' (duration: 974.084054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:56.354639Z","caller":"traceutil/trace.go:171","msg":"trace[1793594258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"947.807832ms","start":"2026-02-25T07:41:55.406809Z","end":"2026-02-25T07:41:56.354616Z","steps":["trace[1793594258] 'range keys from in-memory index tree' (duration: 947.503613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:56.354807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:55.380466Z","time spent":"974.332681ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:56.354835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:55.406797Z","time spent":"948.019757ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:41:56.354921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.869903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:41:56.355003Z","caller":"traceutil/trace.go:171","msg":"trace[100049081] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:800; }","duration":"127.967005ms","start":"2026-02-25T07:41:56.227018Z","end":"2026-02-25T07:41:56.354985Z","steps":["trace[100049081] 'count revisions from in-memory index tree' (duration: 127.797492ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:56.756774Z","caller":"traceutil/trace.go:171","msg":"trace[1013218073] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"397.098655ms","start":"2026-02-25T07:41:56.359652Z","end":"2026-02-25T07:41:56.75675Z","steps":["trace[1013218073] 'process raft request' (duration: 396.948121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:56.756921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:56.35963Z","time spent":"397.212687ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:41:56.866825Z","caller":"traceutil/trace.go:171","msg":"trace[525702030] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"507.087405ms","start":"2026-02-25T07:41:56.359718Z","end":"2026-02-25T07:41:56.866805Z","steps":["trace[525702030] 'process raft request' (duration: 474.342651ms)","trace[525702030] 'compare' (duration: 32.388844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:56.866934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:56.359686Z","time spent":"507.194258ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:41:56.86691Z","caller":"traceutil/trace.go:171","msg":"trace[1065858691] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"506.161741ms","start":"2026-02-25T07:41:56.360713Z","end":"2026-02-25T07:41:56.866874Z","steps":["trace[1065858691] 'process raft request' (duration: 506.002166ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:56.866996Z","caller":"traceutil/trace.go:171","msg":"trace[10459326] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"506.129291ms","start":"2026-02-25T07:41:56.360849Z","end":"2026-02-25T07:41:56.866979Z","steps":["trace[10459326] 'process raft request' (duration: 505.950916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:41:56.867072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:56.360704Z","time spent":"506.279934ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:56.867084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:41:56.360842Z","time spent":"506.200172ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:41:56.86714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.301975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:41:56.867328Z","caller":"traceutil/trace.go:171","msg":"trace[406925563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"103.50454ms","start":"2026-02-25T07:41:56.763807Z","end":"2026-02-25T07:41:56.867312Z","steps":["trace[406925563] 'agreement among raft nodes before linearized reading' (duration: 103.311445ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:41:56.86687Z","caller":"traceutil/trace.go:171","msg":"trace[81615643] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:836; }","duration":"103.005067ms","start":"2026-02-25T07:41:56.763836Z","end":"2026-02-25T07:41:56.866841Z","steps":["trace[81615643] 'read index received' (duration: 70.378896ms)","trace[81615643] 'applied index is now lower than readState.Index' (duration: 32.624941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:41:59.604778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.602102ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T07:42:03.345026Z","caller":"traceutil/trace.go:171","msg":"trace[2016700585] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"371.991982ms","start":"2026-02-25T07:42:02.973Z","end":"2026-02-25T07:42:03.344992Z","steps":["trace[2016700585] 'read index received' (duration: 371.814498ms)","trace[2016700585] 'applied index is now lower than readState.Index' (duration: 175.984µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:03.345155Z","caller":"traceutil/trace.go:171","msg":"trace[1947157948] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"379.155171ms","start":"2026-02-25T07:42:02.965937Z","end":"2026-02-25T07:42:03.345092Z","steps":["trace[1947157948] 'process raft request' (duration: 378.856333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:03.34533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:02.96592Z","time spent":"379.322235ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:03.345364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.324181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:42:03.345411Z","caller":"traceutil/trace.go:171","msg":"trace[1206478373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"372.430114ms","start":"2026-02-25T07:42:02.972966Z","end":"2026-02-25T07:42:03.345396Z","steps":["trace[1206478373] 'agreement among raft nodes before linearized reading' (duration: 372.212498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:03.345465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:02.972951Z","time spent":"372.493605ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:03.345556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.686533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:42:03.345625Z","caller":"traceutil/trace.go:171","msg":"trace[450700728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"355.777914ms","start":"2026-02-25T07:42:02.989831Z","end":"2026-02-25T07:42:03.345609Z","steps":["trace[450700728] 'agreement among raft nodes before linearized reading' (duration: 355.544688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:03.345661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:02.989819Z","time spent":"355.834456ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T07:42:03.569731Z","caller":"traceutil/trace.go:171","msg":"trace[1265909049] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:855; }","duration":"169.790728ms","start":"2026-02-25T07:42:03.399908Z","end":"2026-02-25T07:42:03.569698Z","steps":["trace[1265909049] 'read index received' (duration: 86.973724ms)","trace[1265909049] 'applied index is now lower than readState.Index' (duration: 82.815334ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:03.569746Z","caller":"traceutil/trace.go:171","msg":"trace[1723014234] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"216.985763ms","start":"2026-02-25T07:42:03.352721Z","end":"2026-02-25T07:42:03.569707Z","steps":["trace[1723014234] 'process raft request' (duration: 134.20911ms)","trace[1723014234] 'compare' (duration: 82.252399ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:03.569938Z","caller":"traceutil/trace.go:171","msg":"trace[627587730] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"217.061205ms","start":"2026-02-25T07:42:03.352844Z","end":"2026-02-25T07:42:03.569905Z","steps":["trace[627587730] 'process raft request' (duration: 216.785968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:03.570165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.24614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:42:03.570261Z","caller":"traceutil/trace.go:171","msg":"trace[15292715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"170.385723ms","start":"2026-02-25T07:42:03.399852Z","end":"2026-02-25T07:42:03.570238Z","steps":["trace[15292715] 'agreement among raft nodes before linearized reading' (duration: 170.134937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:03.830816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.126772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:42:03.830932Z","caller":"traceutil/trace.go:171","msg":"trace[62980007] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"256.552397ms","start":"2026-02-25T07:42:03.574359Z","end":"2026-02-25T07:42:03.830912Z","steps":["trace[62980007] 'process raft request' (duration: 129.256279ms)","trace[62980007] 'compare' (duration: 126.973959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:04.11801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.900065ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-25T07:42:04.118226Z","caller":"traceutil/trace.go:171","msg":"trace[1310994223] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"143.029143ms","start":"2026-02-25T07:42:03.975177Z","end":"2026-02-25T07:42:04.118206Z","steps":["trace[1310994223] 'read index received' (duration: 78.992µs)","trace[1310994223] 'applied index is now lower than readState.Index' (duration: 142.947541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:04.119092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.139416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:42:04.119295Z","caller":"traceutil/trace.go:171","msg":"trace[1072486666] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:822; }","duration":"144.142542ms","start":"2026-02-25T07:42:03.975117Z","end":"2026-02-25T07:42:04.11926Z","steps":["trace[1072486666] 'agreement among raft nodes before linearized reading' (duration: 143.149626ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:05.56071Z","caller":"traceutil/trace.go:171","msg":"trace[1359525306] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"205.384627ms","start":"2026-02-25T07:42:05.3553Z","end":"2026-02-25T07:42:05.560684Z","steps":["trace[1359525306] 'process raft request' (duration: 205.243874ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:07.807161Z","caller":"traceutil/trace.go:171","msg":"trace[714288612] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"154.401182ms","start":"2026-02-25T07:42:07.652733Z","end":"2026-02-25T07:42:07.807135Z","steps":["trace[714288612] 'process raft request' (duration: 154.339201ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:07.807179Z","caller":"traceutil/trace.go:171","msg":"trace[1261299239] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"154.425474ms","start":"2026-02-25T07:42:07.652723Z","end":"2026-02-25T07:42:07.807148Z","steps":["trace[1261299239] 'process raft request' (duration: 143.70191ms)","trace[1261299239] 'compare' (duration: 10.520678ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:07.959431Z","caller":"traceutil/trace.go:171","msg":"trace[1557744887] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"101.815985ms","start":"2026-02-25T07:42:07.857586Z","end":"2026-02-25T07:42:07.959402Z","steps":["trace[1557744887] 'process raft request' (duration: 68.057295ms)","trace[1557744887] 'compare' (duration: 33.620507ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:09.495829Z","caller":"traceutil/trace.go:171","msg":"trace[2043149807] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"450.999866ms","start":"2026-02-25T07:42:09.044803Z","end":"2026-02-25T07:42:09.495803Z","steps":["trace[2043149807] 'process raft request' (duration: 450.808591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:09.496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:09.044756Z","time spent":"451.15468ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:16.216971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.713482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T07:42:16.217043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.933635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:42:16.217113Z","caller":"traceutil/trace.go:171","msg":"trace[1777539197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"452.901397ms","start":"2026-02-25T07:42:15.764192Z","end":"2026-02-25T07:42:16.217093Z","steps":["trace[1777539197] 'range keys from in-memory index tree' (duration: 452.63511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:16.217139Z","caller":"traceutil/trace.go:171","msg":"trace[919399093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"531.067349ms","start":"2026-02-25T07:42:15.68605Z","end":"2026-02-25T07:42:16.217117Z","steps":["trace[919399093] 'range keys from in-memory index tree' (duration: 530.74506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:16.217164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:15.764178Z","time spent":"452.976408ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:16.217183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:15.686032Z","time spent":"531.140491ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:16.217255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.607974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:42:16.217292Z","caller":"traceutil/trace.go:171","msg":"trace[1500654622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"260.670626ms","start":"2026-02-25T07:42:15.956611Z","end":"2026-02-25T07:42:16.217281Z","steps":["trace[1500654622] 'range keys from in-memory index tree' (duration: 260.534292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:16.217401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.570197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:42:16.217426Z","caller":"traceutil/trace.go:171","msg":"trace[1805669558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"259.602968ms","start":"2026-02-25T07:42:15.957816Z","end":"2026-02-25T07:42:16.217419Z","steps":["trace[1805669558] 'range keys from in-memory index tree' (duration: 259.513836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:16.217619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.621974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:42:16.217653Z","caller":"traceutil/trace.go:171","msg":"trace[783081398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:903; }","duration":"213.700366ms","start":"2026-02-25T07:42:16.003943Z","end":"2026-02-25T07:42:16.217643Z","steps":["trace[783081398] 'range keys from in-memory index tree' (duration: 213.46465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:18.264998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:42:18.765164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:42:19.249805Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.700733422s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T07:42:19.25005Z","caller":"traceutil/trace.go:171","msg":"trace[2029231547] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"1.485555636s","start":"2026-02-25T07:42:17.764478Z","end":"2026-02-25T07:42:19.250034Z","steps":["trace[2029231547] 'read index received' (duration: 1.485418003s)","trace[2029231547] 'applied index is now lower than readState.Index' (duration: 137.103µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:19.250363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485871384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:19.250396Z","caller":"traceutil/trace.go:171","msg":"trace[1315425776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"1.485914205s","start":"2026-02-25T07:42:17.764473Z","end":"2026-02-25T07:42:19.250387Z","steps":["trace[1315425776] 'agreement among raft nodes before linearized reading' (duration: 1.485852424s)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:19.250388Z","caller":"traceutil/trace.go:171","msg":"trace[183892253] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"1.70137335s","start":"2026-02-25T07:42:17.548985Z","end":"2026-02-25T07:42:19.250358Z","steps":["trace[183892253] 'process raft request' (duration: 1.700955099s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.250423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:17.764437Z","time spent":"1.485979197s","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:19.250413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.952671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:42:19.250504Z","caller":"traceutil/trace.go:171","msg":"trace[936408542] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:908; }","duration":"535.100165ms","start":"2026-02-25T07:42:18.715382Z","end":"2026-02-25T07:42:19.250482Z","steps":["trace[936408542] 'agreement among raft nodes before linearized reading' (duration: 534.92728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.250527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:17.548968Z","time spent":"1.701484112s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:19.250559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:18.715363Z","time spent":"535.186137ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:42:19.250706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022185364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:42:19.250744Z","caller":"traceutil/trace.go:171","msg":"trace[1537549862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"1.022241485s","start":"2026-02-25T07:42:18.228494Z","end":"2026-02-25T07:42:19.250736Z","steps":["trace[1537549862] 'agreement among raft nodes before linearized reading' (duration: 1.022129092s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.250771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:18.228486Z","time spent":"1.022278035s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:42:19.250789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.517119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:42:19.250823Z","caller":"traceutil/trace.go:171","msg":"trace[572664219] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:908; }","duration":"203.631542ms","start":"2026-02-25T07:42:19.047183Z","end":"2026-02-25T07:42:19.250814Z","steps":["trace[572664219] 'agreement among raft nodes before linearized reading' (duration: 203.56609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.250884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022224674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T07:42:19.250933Z","caller":"traceutil/trace.go:171","msg":"trace[1435526746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"1.022287466s","start":"2026-02-25T07:42:18.228633Z","end":"2026-02-25T07:42:19.250921Z","steps":["trace[1435526746] 'agreement among raft nodes before linearized reading' (duration: 1.022184064s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.250966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:18.228627Z","time spent":"1.022330387s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T07:42:19.251048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023586199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:42:19.251107Z","caller":"traceutil/trace.go:171","msg":"trace[1989100267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:908; }","duration":"1.023658852s","start":"2026-02-25T07:42:18.227438Z","end":"2026-02-25T07:42:19.251097Z","steps":["trace[1989100267] 'agreement among raft nodes before linearized reading' (duration: 1.023487197s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.251049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02361143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:42:19.251285Z","caller":"traceutil/trace.go:171","msg":"trace[248951859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"1.023881517s","start":"2026-02-25T07:42:18.227383Z","end":"2026-02-25T07:42:19.251264Z","steps":["trace[248951859] 'agreement among raft nodes before linearized reading' (duration: 1.022923002s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:19.251394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:18.227365Z","time spent":"1.024011471s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:19.251156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:18.227433Z","time spent":"1.023703623s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:29.558464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.257803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T07:42:29.558553Z","caller":"traceutil/trace.go:171","msg":"trace[1310960280] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"142.002765ms","start":"2026-02-25T07:42:29.416537Z","end":"2026-02-25T07:42:29.55854Z","steps":["trace[1310960280] 'read index received' (duration: 5.607188ms)","trace[1310960280] 'applied index is now lower than readState.Index' (duration: 136.394597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:29.558702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.755338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:42:29.558721Z","caller":"traceutil/trace.go:171","msg":"trace[1884191985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"141.83202ms","start":"2026-02-25T07:42:29.416882Z","end":"2026-02-25T07:42:29.558714Z","steps":["trace[1884191985] 'agreement among raft nodes before linearized reading' (duration: 141.728567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:29.558757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.2016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T07:42:29.558771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.715142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T07:42:29.558822Z","caller":"traceutil/trace.go:171","msg":"trace[1295062838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:936; }","duration":"142.313913ms","start":"2026-02-25T07:42:29.41649Z","end":"2026-02-25T07:42:29.558804Z","steps":["trace[1295062838] 'agreement among raft nodes before linearized reading' (duration: 142.099698ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:29.558828Z","caller":"traceutil/trace.go:171","msg":"trace[618794769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"137.809705ms","start":"2026-02-25T07:42:29.421003Z","end":"2026-02-25T07:42:29.558813Z","steps":["trace[618794769] 'agreement among raft nodes before linearized reading' (duration: 137.679191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:29.559299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.099793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T07:42:29.559354Z","caller":"traceutil/trace.go:171","msg":"trace[108709025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:936; }","duration":"138.175435ms","start":"2026-02-25T07:42:29.421165Z","end":"2026-02-25T07:42:29.55934Z","steps":["trace[108709025] 'agreement among raft nodes before linearized reading' (duration: 137.465316ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:29.672689Z","caller":"traceutil/trace.go:171","msg":"trace[581359410] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"100.747587ms","start":"2026-02-25T07:42:29.571924Z","end":"2026-02-25T07:42:29.672671Z","steps":["trace[581359410] 'process raft request' (duration: 100.625184ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:29.688084Z","caller":"traceutil/trace.go:171","msg":"trace[2079831909] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"114.551142ms","start":"2026-02-25T07:42:29.573508Z","end":"2026-02-25T07:42:29.688059Z","steps":["trace[2079831909] 'process raft request' (duration: 114.510721ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:29.688326Z","caller":"traceutil/trace.go:171","msg":"trace[1966215230] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"115.040944ms","start":"2026-02-25T07:42:29.573262Z","end":"2026-02-25T07:42:29.688303Z","steps":["trace[1966215230] 'process raft request' (duration: 114.720336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:29.688475Z","caller":"traceutil/trace.go:171","msg":"trace[1151421838] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"116.332939ms","start":"2026-02-25T07:42:29.572131Z","end":"2026-02-25T07:42:29.688464Z","steps":["trace[1151421838] 'process raft request' (duration: 115.714433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:29.864677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.216373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:29.864744Z","caller":"traceutil/trace.go:171","msg":"trace[1065128153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"100.313656ms","start":"2026-02-25T07:42:29.764418Z","end":"2026-02-25T07:42:29.864731Z","steps":["trace[1065128153] 'range keys from in-memory index tree' (duration: 100.169612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:32.052686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.854137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:42:32.052812Z","caller":"traceutil/trace.go:171","msg":"trace[727707603] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"326.76479ms","start":"2026-02-25T07:42:31.72603Z","end":"2026-02-25T07:42:32.052795Z","steps":["trace[727707603] 'process raft request' (duration: 61.694907ms)","trace[727707603] 'compare' (duration: 264.710713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:32.052874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:31.726016Z","time spent":"326.829101ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:42:32.138151Z","caller":"traceutil/trace.go:171","msg":"trace[1603190930] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:987; }","duration":"373.660695ms","start":"2026-02-25T07:42:31.764472Z","end":"2026-02-25T07:42:32.138133Z","steps":["trace[1603190930] 'read index received' (duration: 23.226022ms)","trace[1603190930] 'applied index is now lower than readState.Index' (duration: 350.434033ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:32.138182Z","caller":"traceutil/trace.go:171","msg":"trace[189474418] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"411.688279ms","start":"2026-02-25T07:42:31.726471Z","end":"2026-02-25T07:42:32.13816Z","steps":["trace[189474418] 'process raft request' (duration: 411.521615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:32.138244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.755079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:32.138272Z","caller":"traceutil/trace.go:171","msg":"trace[1490078942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"373.81743ms","start":"2026-02-25T07:42:31.764448Z","end":"2026-02-25T07:42:32.138265Z","steps":["trace[1490078942] 'agreement among raft nodes before linearized reading' (duration: 373.755128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:32.1383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:31.726437Z","time spent":"411.793642ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:42:32.138332Z","caller":"traceutil/trace.go:171","msg":"trace[794504888] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"409.883481ms","start":"2026-02-25T07:42:31.728442Z","end":"2026-02-25T07:42:32.138326Z","steps":["trace[794504888] 'process raft request' (duration: 409.658555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:32.138304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:31.764439Z","time spent":"373.8567ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:32.138394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:31.728433Z","time spent":"409.915692ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:42:33.879438Z","caller":"traceutil/trace.go:171","msg":"trace[1597863310] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"151.987998ms","start":"2026-02-25T07:42:33.727425Z","end":"2026-02-25T07:42:33.879413Z","steps":["trace[1597863310] 'process raft request' (duration: 151.868965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:33.879953Z","caller":"traceutil/trace.go:171","msg":"trace[386728597] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1001; }","duration":"114.416908ms","start":"2026-02-25T07:42:33.765526Z","end":"2026-02-25T07:42:33.879943Z","steps":["trace[386728597] 'read index received' (duration: 114.413178ms)","trace[386728597] 'applied index is now lower than readState.Index' (duration: 2.85µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:33.880031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.49314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:33.880065Z","caller":"traceutil/trace.go:171","msg":"trace[1421807092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"114.561052ms","start":"2026-02-25T07:42:33.765495Z","end":"2026-02-25T07:42:33.880056Z","steps":["trace[1421807092] 'agreement among raft nodes before linearized reading' (duration: 114.49538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:39.960669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519832,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T07:42:39.966768Z","caller":"traceutil/trace.go:171","msg":"trace[1625118495] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"509.300414ms","start":"2026-02-25T07:42:39.457449Z","end":"2026-02-25T07:42:39.966749Z","steps":["trace[1625118495] 'read index received' (duration: 509.109929ms)","trace[1625118495] 'applied index is now lower than readState.Index' (duration: 189.585µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:39.96685Z","caller":"traceutil/trace.go:171","msg":"trace[1394918869] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"509.393127ms","start":"2026-02-25T07:42:39.457431Z","end":"2026-02-25T07:42:39.966824Z","steps":["trace[1394918869] 'process raft request' (duration: 509.188152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:39.966885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.426868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:39.966918Z","caller":"traceutil/trace.go:171","msg":"trace[1633754522] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:991; }","duration":"509.51857ms","start":"2026-02-25T07:42:39.45739Z","end":"2026-02-25T07:42:39.966909Z","steps":["trace[1633754522] 'agreement among raft nodes before linearized reading' (duration: 509.424728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:39.966943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:39.457368Z","time spent":"509.569241ms","remote":"127.0.0.1:42010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:42:39.966979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:39.457409Z","time spent":"509.492509ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:40.648541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T07:42:41.024829Z","caller":"traceutil/trace.go:171","msg":"trace[77462510] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"877.335282ms","start":"2026-02-25T07:42:40.147474Z","end":"2026-02-25T07:42:41.024809Z","steps":["trace[77462510] 'read index received' (duration: 877.137036ms)","trace[77462510] 'applied index is now lower than readState.Index' (duration: 197.526µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:41.024892Z","caller":"traceutil/trace.go:171","msg":"trace[1261394863] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.055200783s","start":"2026-02-25T07:42:39.96966Z","end":"2026-02-25T07:42:41.024861Z","steps":["trace[1261394863] 'process raft request' (duration: 1.055025208s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:41.025048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:39.969644Z","time spent":"1.055308997s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:41.025167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.691983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T07:42:41.025167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.332775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T07:42:41.025256Z","caller":"traceutil/trace.go:171","msg":"trace[246513606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"854.468569ms","start":"2026-02-25T07:42:40.170769Z","end":"2026-02-25T07:42:41.025238Z","steps":["trace[246513606] 'agreement among raft nodes before linearized reading' (duration: 854.253023ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:41.025228Z","caller":"traceutil/trace.go:171","msg":"trace[1391687534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"877.778484ms","start":"2026-02-25T07:42:40.147437Z","end":"2026-02-25T07:42:41.025216Z","steps":["trace[1391687534] 'agreement among raft nodes before linearized reading' (duration: 877.484527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:41.025307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:40.170746Z","time spent":"854.551931ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T07:42:41.025173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.739887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T07:42:41.025387Z","caller":"traceutil/trace.go:171","msg":"trace[1000021136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"855.003504ms","start":"2026-02-25T07:42:40.170367Z","end":"2026-02-25T07:42:41.02537Z","steps":["trace[1000021136] 'agreement among raft nodes before linearized reading' (duration: 854.606474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:41.025439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:40.170331Z","time spent":"855.096926ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:41.025453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.785089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:41.025498Z","caller":"traceutil/trace.go:171","msg":"trace[1377595130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"260.865461ms","start":"2026-02-25T07:42:40.764617Z","end":"2026-02-25T07:42:41.025483Z","steps":["trace[1377595130] 'agreement among raft nodes before linearized reading' (duration: 260.791669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:41.025363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:40.147421Z","time spent":"877.882597ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T07:42:41.240543Z","caller":"traceutil/trace.go:171","msg":"trace[667198432] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"211.434707ms","start":"2026-02-25T07:42:41.029084Z","end":"2026-02-25T07:42:41.240519Z","steps":["trace[667198432] 'process raft request' (duration: 112.134078ms)","trace[667198432] 'compare' (duration: 98.888438ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:41.240584Z","caller":"traceutil/trace.go:171","msg":"trace[1513268694] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"210.513173ms","start":"2026-02-25T07:42:41.030052Z","end":"2026-02-25T07:42:41.240565Z","steps":["trace[1513268694] 'process raft request' (duration: 210.215345ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:41.240605Z","caller":"traceutil/trace.go:171","msg":"trace[1781083237] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"210.186394ms","start":"2026-02-25T07:42:41.030386Z","end":"2026-02-25T07:42:41.240572Z","steps":["trace[1781083237] 'process raft request' (duration: 210.086341ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:41.968118Z","caller":"traceutil/trace.go:171","msg":"trace[106810704] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"104.27478ms","start":"2026-02-25T07:42:41.863822Z","end":"2026-02-25T07:42:41.968097Z","steps":["trace[106810704] 'process raft request' (duration: 104.134727ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:42.688849Z","caller":"traceutil/trace.go:171","msg":"trace[641317766] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"285.49524ms","start":"2026-02-25T07:42:42.403329Z","end":"2026-02-25T07:42:42.688825Z","steps":["trace[641317766] 'read index received' (duration: 285.233533ms)","trace[641317766] 'applied index is now lower than readState.Index' (duration: 260.617µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:42.688942Z","caller":"traceutil/trace.go:171","msg":"trace[1301117489] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"541.599646ms","start":"2026-02-25T07:42:42.147296Z","end":"2026-02-25T07:42:42.688896Z","steps":["trace[1301117489] 'process raft request' (duration: 541.324269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:42.688994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.673106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T07:42:42.689031Z","caller":"traceutil/trace.go:171","msg":"trace[1546855925] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1000; }","duration":"285.952663ms","start":"2026-02-25T07:42:42.403067Z","end":"2026-02-25T07:42:42.68902Z","steps":["trace[1546855925] 'agreement among raft nodes before linearized reading' (duration: 285.85638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:42.689134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:42.147269Z","time spent":"541.760671ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:43.298803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.599376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T07:42:43.298885Z","caller":"traceutil/trace.go:171","msg":"trace[919112359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"268.736859ms","start":"2026-02-25T07:42:43.03013Z","end":"2026-02-25T07:42:43.298867Z","steps":["trace[919112359] 'range keys from in-memory index tree' (duration: 268.372879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:43.299044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.47051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:43.299184Z","caller":"traceutil/trace.go:171","msg":"trace[866756809] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1000; }","duration":"365.689625ms","start":"2026-02-25T07:42:42.933461Z","end":"2026-02-25T07:42:43.299151Z","steps":["trace[866756809] 'count revisions from in-memory index tree' (duration: 365.372067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:43.299325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:42.933441Z","time spent":"365.866941ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:42:44.078565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.148438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:42:44.0787Z","caller":"traceutil/trace.go:171","msg":"trace[1655034832] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"774.512042ms","start":"2026-02-25T07:42:43.304175Z","end":"2026-02-25T07:42:44.078687Z","steps":["trace[1655034832] 'process raft request' (duration: 620.17703ms)","trace[1655034832] 'compare' (duration: 153.923521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:44.078753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:43.304154Z","time spent":"774.580264ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:42:44.079396Z","caller":"traceutil/trace.go:171","msg":"trace[1091840288] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1047; }","duration":"608.693317ms","start":"2026-02-25T07:42:43.470683Z","end":"2026-02-25T07:42:44.079376Z","steps":["trace[1091840288] 'read index received' (duration: 453.589444ms)","trace[1091840288] 'applied index is now lower than readState.Index' (duration: 155.103113ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:44.079456Z","caller":"traceutil/trace.go:171","msg":"trace[683305082] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"774.732148ms","start":"2026-02-25T07:42:43.304696Z","end":"2026-02-25T07:42:44.079429Z","steps":["trace[683305082] 'process raft request' (duration: 773.957907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.079882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.760582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:44.079928Z","caller":"traceutil/trace.go:171","msg":"trace[595325604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"378.857914ms","start":"2026-02-25T07:42:43.701061Z","end":"2026-02-25T07:42:44.079918Z","steps":["trace[595325604] 'agreement among raft nodes before linearized reading' (duration: 378.787522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.079953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:43.701036Z","time spent":"378.912526ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:44.080017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.650548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T07:42:44.080076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.823055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:44.080101Z","caller":"traceutil/trace.go:171","msg":"trace[343832186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"314.896017ms","start":"2026-02-25T07:42:43.765199Z","end":"2026-02-25T07:42:44.080095Z","steps":["trace[343832186] 'agreement among raft nodes before linearized reading' (duration: 314.860396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.080122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:43.765172Z","time spent":"314.946639ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:42:44.080073Z","caller":"traceutil/trace.go:171","msg":"trace[1805570587] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:1004; }","duration":"196.753381ms","start":"2026-02-25T07:42:43.883304Z","end":"2026-02-25T07:42:44.080057Z","steps":["trace[1805570587] 'agreement among raft nodes before linearized reading' (duration: 196.583406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.080244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.002286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:44.080504Z","caller":"traceutil/trace.go:171","msg":"trace[549438859] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1004; }","duration":"609.778275ms","start":"2026-02-25T07:42:43.470609Z","end":"2026-02-25T07:42:44.080387Z","steps":["trace[549438859] 'agreement among raft nodes before linearized reading' (duration: 608.976825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:44.079685Z","caller":"traceutil/trace.go:171","msg":"trace[911174074] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"772.341294ms","start":"2026-02-25T07:42:43.307332Z","end":"2026-02-25T07:42:44.079673Z","steps":["trace[911174074] 'process raft request' (duration: 771.967184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.080592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:43.470569Z","time spent":"610.000391ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:42:44.080762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:43.307321Z","time spent":"773.287829ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:44.080226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:43.304685Z","time spent":"775.18533ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:44.359495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.539254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:42:44.359672Z","caller":"traceutil/trace.go:171","msg":"trace[1101141750] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"117.371487ms","start":"2026-02-25T07:42:44.242284Z","end":"2026-02-25T07:42:44.359655Z","steps":["trace[1101141750] 'read index received' (duration: 36.061µs)","trace[1101141750] 'applied index is now lower than readState.Index' (duration: 117.334356ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:44.359718Z","caller":"traceutil/trace.go:171","msg":"trace[1675616796] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"239.293013ms","start":"2026-02-25T07:42:44.120388Z","end":"2026-02-25T07:42:44.359681Z","steps":["trace[1675616796] 'process raft request' (duration: 108.460721ms)","trace[1675616796] 'compare' (duration: 130.415291ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:44.359759Z","caller":"traceutil/trace.go:171","msg":"trace[425648799] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"209.688962ms","start":"2026-02-25T07:42:44.150004Z","end":"2026-02-25T07:42:44.359693Z","steps":["trace[425648799] 'process raft request' (duration: 209.59381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.359856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.573012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:44.359909Z","caller":"traceutil/trace.go:171","msg":"trace[1098209093] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1007; }","duration":"117.659655ms","start":"2026-02-25T07:42:44.242238Z","end":"2026-02-25T07:42:44.359897Z","steps":["trace[1098209093] 'agreement among raft nodes before linearized reading' (duration: 117.47267ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:44.563323Z","caller":"traceutil/trace.go:171","msg":"trace[501399063] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"176.037073ms","start":"2026-02-25T07:42:44.387257Z","end":"2026-02-25T07:42:44.563294Z","steps":["trace[501399063] 'process raft request' (duration: 155.620344ms)","trace[501399063] 'compare' (duration: 20.251825ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:44.604318Z","caller":"traceutil/trace.go:171","msg":"trace[1289354004] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"216.303325ms","start":"2026-02-25T07:42:44.387988Z","end":"2026-02-25T07:42:44.604291Z","steps":["trace[1289354004] 'process raft request' (duration: 216.161432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:44.842287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.884126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:42:44.842496Z","caller":"traceutil/trace.go:171","msg":"trace[221098931] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"234.008312ms","start":"2026-02-25T07:42:44.608453Z","end":"2026-02-25T07:42:44.842461Z","steps":["trace[221098931] 'process raft request' (duration: 106.860849ms)","trace[221098931] 'compare' (duration: 126.743233ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:45.614802Z","caller":"traceutil/trace.go:171","msg":"trace[1354175032] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"220.493206ms","start":"2026-02-25T07:42:45.39428Z","end":"2026-02-25T07:42:45.614773Z","steps":["trace[1354175032] 'process raft request' (duration: 220.299781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.439501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.950357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:46.439562Z","caller":"traceutil/trace.go:171","msg":"trace[665543365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"674.06413ms","start":"2026-02-25T07:42:45.765485Z","end":"2026-02-25T07:42:46.439549Z","steps":["trace[665543365] 'range keys from in-memory index tree' (duration: 673.851745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.439591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:45.765462Z","time spent":"674.122792ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:46.439637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.706226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T07:42:46.439651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.298963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T07:42:46.439696Z","caller":"traceutil/trace.go:171","msg":"trace[860822847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"354.795668ms","start":"2026-02-25T07:42:46.084889Z","end":"2026-02-25T07:42:46.439684Z","steps":["trace[860822847] 'range keys from in-memory index tree' (duration: 354.589683ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:46.439708Z","caller":"traceutil/trace.go:171","msg":"trace[835665822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"272.408216ms","start":"2026-02-25T07:42:46.167287Z","end":"2026-02-25T07:42:46.439696Z","steps":["trace[835665822] 'range keys from in-memory index tree' (duration: 272.17508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.439722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:46.084881Z","time spent":"354.835639ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:46.43972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.882011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T07:42:46.439795Z","caller":"traceutil/trace.go:171","msg":"trace[1191290784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"355.007004ms","start":"2026-02-25T07:42:46.084767Z","end":"2026-02-25T07:42:46.439774Z","steps":["trace[1191290784] 'range keys from in-memory index tree' (duration: 354.582242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.439848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:46.084743Z","time spent":"355.091716ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:46.441024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.848374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T07:42:46.441074Z","caller":"traceutil/trace.go:171","msg":"trace[1194546386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"357.175812ms","start":"2026-02-25T07:42:46.083887Z","end":"2026-02-25T07:42:46.441063Z","steps":["trace[1194546386] 'range keys from in-memory index tree' (duration: 355.294891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.441105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:46.083868Z","time spent":"357.229914ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T07:42:46.603064Z","caller":"traceutil/trace.go:171","msg":"trace[1842279552] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"160.499334ms","start":"2026-02-25T07:42:46.44254Z","end":"2026-02-25T07:42:46.603039Z","steps":["trace[1842279552] 'process raft request' (duration: 160.201176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.99863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.067218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:42:46.998851Z","caller":"traceutil/trace.go:171","msg":"trace[123732793] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"555.080243ms","start":"2026-02-25T07:42:46.443746Z","end":"2026-02-25T07:42:46.998826Z","steps":["trace[123732793] 'process raft request' (duration: 448.692026ms)","trace[123732793] 'compare' (duration: 105.400801ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:46.998896Z","caller":"traceutil/trace.go:171","msg":"trace[600593875] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"554.805535ms","start":"2026-02-25T07:42:46.444061Z","end":"2026-02-25T07:42:46.998866Z","steps":["trace[600593875] 'process raft request' (duration: 554.695622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:46.998981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:46.444044Z","time spent":"554.899158ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:46.998995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:46.443724Z","time spent":"555.183215ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:42:46.999163Z","caller":"traceutil/trace.go:171","msg":"trace[805830012] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"554.234901ms","start":"2026-02-25T07:42:46.444898Z","end":"2026-02-25T07:42:46.999133Z","steps":["trace[805830012] 'process raft request' (duration: 553.924183ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:46.999107Z","caller":"traceutil/trace.go:171","msg":"trace[38874859] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1061; }","duration":"254.334119ms","start":"2026-02-25T07:42:46.744739Z","end":"2026-02-25T07:42:46.999073Z","steps":["trace[38874859] 'read index received' (duration: 147.740847ms)","trace[38874859] 'applied index is now lower than readState.Index' (duration: 106.591182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:46.999465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.594567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T07:42:46.999336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:46.444885Z","time spent":"554.375894ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T07:42:46.999543Z","caller":"traceutil/trace.go:171","msg":"trace[1955563493] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1017; }","duration":"254.830163ms","start":"2026-02-25T07:42:46.744684Z","end":"2026-02-25T07:42:46.999514Z","steps":["trace[1955563493] 'agreement among raft nodes before linearized reading' (duration: 254.597797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:48.193912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519898,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T07:42:48.219256Z","caller":"traceutil/trace.go:171","msg":"trace[2124817534] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"526.328116ms","start":"2026-02-25T07:42:47.692881Z","end":"2026-02-25T07:42:48.219209Z","steps":["trace[2124817534] 'read index received' (duration: 526.135621ms)","trace[2124817534] 'applied index is now lower than readState.Index' (duration: 191.745µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:42:48.219303Z","caller":"traceutil/trace.go:171","msg":"trace[1431912293] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"595.450261ms","start":"2026-02-25T07:42:47.623823Z","end":"2026-02-25T07:42:48.219273Z","steps":["trace[1431912293] 'process raft request' (duration: 595.253026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:48.219383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.364619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:48.219413Z","caller":"traceutil/trace.go:171","msg":"trace[1140865943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"454.453751ms","start":"2026-02-25T07:42:47.764951Z","end":"2026-02-25T07:42:48.219405Z","steps":["trace[1140865943] 'agreement among raft nodes before linearized reading' (duration: 454.38883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:48.219439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:47.764929Z","time spent":"454.503912ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:48.219473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:47.623793Z","time spent":"595.582385ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:48.219596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.678835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-25T07:42:48.219708Z","caller":"traceutil/trace.go:171","msg":"trace[462065143] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1018; }","duration":"526.823589ms","start":"2026-02-25T07:42:47.692863Z","end":"2026-02-25T07:42:48.219687Z","steps":["trace[462065143] 'agreement among raft nodes before linearized reading' (duration: 526.47676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:48.219771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:47.692802Z","time spent":"526.951803ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T07:42:48.606405Z","caller":"traceutil/trace.go:171","msg":"trace[1773196872] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"382.011267ms","start":"2026-02-25T07:42:48.224366Z","end":"2026-02-25T07:42:48.606377Z","steps":["trace[1773196872] 'process raft request' (duration: 380.65055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:48.607531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:48.224349Z","time spent":"383.084264ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T07:42:49.365243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.33286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:49.365755Z","caller":"traceutil/trace.go:171","msg":"trace[809409307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"599.581016ms","start":"2026-02-25T07:42:48.76585Z","end":"2026-02-25T07:42:49.365431Z","steps":["trace[809409307] 'range keys from in-memory index tree' (duration: 599.258849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:49.366439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:48.765826Z","time spent":"600.575573ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T07:42:49.504081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132982448519906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T07:42:49.962242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.827578ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T07:42:49.962361Z","caller":"traceutil/trace.go:171","msg":"trace[1468910246] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"959.003645ms","start":"2026-02-25T07:42:49.003326Z","end":"2026-02-25T07:42:49.96233Z","steps":["trace[1468910246] 'read index received' (duration: 662.090865ms)","trace[1468910246] 'applied index is now lower than readState.Index' (duration: 296.91124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:42:49.962491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.814189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:49.962534Z","caller":"traceutil/trace.go:171","msg":"trace[55707536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"594.896041ms","start":"2026-02-25T07:42:49.367624Z","end":"2026-02-25T07:42:49.96252Z","steps":["trace[55707536] 'agreement among raft nodes before linearized reading' (duration: 594.821709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:49.962545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.84834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T07:42:49.962583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.838251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T07:42:49.962593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:49.367601Z","time spent":"594.980123ms","remote":"127.0.0.1:41896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T07:42:49.962621Z","caller":"traceutil/trace.go:171","msg":"trace[1819221149] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1020; }","duration":"708.043615ms","start":"2026-02-25T07:42:49.25456Z","end":"2026-02-25T07:42:49.962604Z","steps":["trace[1819221149] 'agreement among raft nodes before linearized reading' (duration: 707.899852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:49.962633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.28669ms","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":"warn","ts":"2026-02-25T07:42:49.962674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:49.254534Z","time spent":"708.126738ms","remote":"127.0.0.1:42368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T07:42:49.962712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.701417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T07:42:49.96271Z","caller":"traceutil/trace.go:171","msg":"trace[1513208627] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"115.374923ms","start":"2026-02-25T07:42:49.847317Z","end":"2026-02-25T07:42:49.962692Z","steps":["trace[1513208627] 'agreement among raft nodes before linearized reading' (duration: 115.165967ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:49.962749Z","caller":"traceutil/trace.go:171","msg":"trace[1490722453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"958.774969ms","start":"2026-02-25T07:42:49.003966Z","end":"2026-02-25T07:42:49.962741Z","steps":["trace[1490722453] 'agreement among raft nodes before linearized reading' (duration: 958.686037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:49.962783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:49.003946Z","time spent":"958.830901ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T07:42:49.96263Z","caller":"traceutil/trace.go:171","msg":"trace[702168008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"958.922403ms","start":"2026-02-25T07:42:49.003694Z","end":"2026-02-25T07:42:49.962617Z","steps":["trace[702168008] 'agreement among raft nodes before linearized reading' (duration: 958.750218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:49.962863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:49.003673Z","time spent":"959.17277ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T07:42:49.962863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.506138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T07:42:49.963159Z","caller":"traceutil/trace.go:171","msg":"trace[1735758474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"959.858437ms","start":"2026-02-25T07:42:49.003273Z","end":"2026-02-25T07:42:49.963132Z","steps":["trace[1735758474] 'agreement among raft nodes before linearized reading' (duration: 959.141438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:49.96326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T07:42:49.003251Z","time spent":"959.957141ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T07:42:52.497223Z","caller":"traceutil/trace.go:171","msg":"trace[359694896] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"249.510655ms","start":"2026-02-25T07:42:52.247693Z","end":"2026-02-25T07:42:52.497203Z","steps":["trace[359694896] 'process raft request' (duration: 249.364251ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:42:54.707267Z","caller":"traceutil/trace.go:171","msg":"trace[210281794] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"202.225735ms","start":"2026-02-25T07:42:54.50497Z","end":"2026-02-25T07:42:54.707196Z","steps":["trace[210281794] 'process raft request' (duration: 201.997689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:55.671737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.864774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:55.671953Z","caller":"traceutil/trace.go:171","msg":"trace[579375810] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1050; }","duration":"214.017998ms","start":"2026-02-25T07:42:55.457804Z","end":"2026-02-25T07:42:55.671822Z","steps":["trace[579375810] 'range keys from in-memory index tree' (duration: 213.740872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:42:55.672232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.759341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:42:55.672584Z","caller":"traceutil/trace.go:171","msg":"trace[1360518440] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1050; }","duration":"248.266774ms","start":"2026-02-25T07:42:55.424038Z","end":"2026-02-25T07:42:55.672305Z","steps":["trace[1360518440] 'count revisions from in-memory index tree' (duration: 247.662088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:00.155747Z","caller":"traceutil/trace.go:171","msg":"trace[2099275350] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"106.085372ms","start":"2026-02-25T07:43:00.049635Z","end":"2026-02-25T07:43:00.155721Z","steps":["trace[2099275350] 'process raft request' (duration: 105.299511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:00.172226Z","caller":"traceutil/trace.go:171","msg":"trace[1082381531] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"122.178067ms","start":"2026-02-25T07:43:00.050025Z","end":"2026-02-25T07:43:00.172203Z","steps":["trace[1082381531] 'process raft request' (duration: 121.993062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:43:00.172385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.977455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-25T07:43:00.172424Z","caller":"traceutil/trace.go:171","msg":"trace[336405873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1083; }","duration":"109.058828ms","start":"2026-02-25T07:43:00.063356Z","end":"2026-02-25T07:43:00.172415Z","steps":["trace[336405873] 'agreement among raft nodes before linearized reading' (duration: 108.918714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:00.172157Z","caller":"traceutil/trace.go:171","msg":"trace[714199945] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1131; }","duration":"108.747069ms","start":"2026-02-25T07:43:00.06339Z","end":"2026-02-25T07:43:00.172138Z","steps":["trace[714199945] 'read index received' (duration: 91.617957ms)","trace[714199945] 'applied index is now lower than readState.Index' (duration: 17.128482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:43:00.172873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.221302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T07:43:00.172935Z","caller":"traceutil/trace.go:171","msg":"trace[866077181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"109.325195ms","start":"2026-02-25T07:43:00.063601Z","end":"2026-02-25T07:43:00.172926Z","steps":["trace[866077181] 'agreement among raft nodes before linearized reading' (duration: 109.14202ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:00.301329Z","caller":"traceutil/trace.go:171","msg":"trace[592999730] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"119.167029ms","start":"2026-02-25T07:43:00.182136Z","end":"2026-02-25T07:43:00.301303Z","steps":["trace[592999730] 'process raft request' (duration: 119.011524ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:02.269053Z","caller":"traceutil/trace.go:171","msg":"trace[1600501495] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"104.353556ms","start":"2026-02-25T07:43:02.164673Z","end":"2026-02-25T07:43:02.269027Z","steps":["trace[1600501495] 'process raft request' (duration: 104.176422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:43:13.058515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.782982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-25T07:43:13.058574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.599172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T07:43:13.058612Z","caller":"traceutil/trace.go:171","msg":"trace[1998831379] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1137; }","duration":"153.913126ms","start":"2026-02-25T07:43:12.904672Z","end":"2026-02-25T07:43:13.058585Z","steps":["trace[1998831379] 'range keys from in-memory index tree' (duration: 153.589687ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:13.058692Z","caller":"traceutil/trace.go:171","msg":"trace[1711800065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1137; }","duration":"205.767016ms","start":"2026-02-25T07:43:12.852906Z","end":"2026-02-25T07:43:13.058673Z","steps":["trace[1711800065] 'range keys from in-memory index tree' (duration: 205.432688ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:13.164006Z","caller":"traceutil/trace.go:171","msg":"trace[1796331799] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"100.146217ms","start":"2026-02-25T07:43:13.063831Z","end":"2026-02-25T07:43:13.163977Z","steps":["trace[1796331799] 'process raft request' (duration: 99.88432ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:18.597736Z","caller":"traceutil/trace.go:171","msg":"trace[2118732913] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"118.212143ms","start":"2026-02-25T07:43:18.479505Z","end":"2026-02-25T07:43:18.597717Z","steps":["trace[2118732913] 'read index received' (duration: 42.241761ms)","trace[2118732913] 'applied index is now lower than readState.Index' (duration: 75.969682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:43:18.59788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.347708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:43:18.597869Z","caller":"traceutil/trace.go:171","msg":"trace[1891229296] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"142.349129ms","start":"2026-02-25T07:43:18.455488Z","end":"2026-02-25T07:43:18.597837Z","steps":["trace[1891229296] 'process raft request' (duration: 66.343415ms)","trace[1891229296] 'compare' (duration: 75.797818ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:43:18.597918Z","caller":"traceutil/trace.go:171","msg":"trace[731039555] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1160; }","duration":"118.446099ms","start":"2026-02-25T07:43:18.479461Z","end":"2026-02-25T07:43:18.597907Z","steps":["trace[731039555] 'agreement among raft nodes before linearized reading' (duration: 118.326996ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:24.870558Z","caller":"traceutil/trace.go:171","msg":"trace[252579240] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"106.46529ms","start":"2026-02-25T07:43:24.764069Z","end":"2026-02-25T07:43:24.870534Z","steps":["trace[252579240] 'read index received' (duration: 106.293106ms)","trace[252579240] 'applied index is now lower than readState.Index' (duration: 171.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:43:24.87069Z","caller":"traceutil/trace.go:171","msg":"trace[18062865] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"245.684539ms","start":"2026-02-25T07:43:24.62497Z","end":"2026-02-25T07:43:24.870655Z","steps":["trace[18062865] 'process raft request' (duration: 245.420152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:43:24.870714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.624274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T07:43:24.870765Z","caller":"traceutil/trace.go:171","msg":"trace[745480696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"106.717247ms","start":"2026-02-25T07:43:24.764038Z","end":"2026-02-25T07:43:24.870755Z","steps":["trace[745480696] 'agreement among raft nodes before linearized reading' (duration: 106.587684ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:28.184063Z","caller":"traceutil/trace.go:171","msg":"trace[890040937] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"133.371496ms","start":"2026-02-25T07:43:28.050645Z","end":"2026-02-25T07:43:28.184016Z","steps":["trace[890040937] 'process raft request' (duration: 133.124449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:43:30.529209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.155024ms","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:508"} {"level":"warn","ts":"2026-02-25T07:43:30.529225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.881156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T07:43:30.529312Z","caller":"traceutil/trace.go:171","msg":"trace[2072302369] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1205; }","duration":"202.326618ms","start":"2026-02-25T07:43:30.326965Z","end":"2026-02-25T07:43:30.529292Z","steps":["trace[2072302369] 'range keys from in-memory index tree' (duration: 201.898257ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:30.529353Z","caller":"traceutil/trace.go:171","msg":"trace[1891787771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1205; }","duration":"113.04452ms","start":"2026-02-25T07:43:30.416266Z","end":"2026-02-25T07:43:30.529311Z","steps":["trace[1891787771] 'range keys from in-memory index tree' (duration: 112.64551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:30.643437Z","caller":"traceutil/trace.go:171","msg":"trace[1489993446] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"108.782961ms","start":"2026-02-25T07:43:30.534627Z","end":"2026-02-25T07:43:30.64341Z","steps":["trace[1489993446] 'process raft request' (duration: 108.729499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:30.64353Z","caller":"traceutil/trace.go:171","msg":"trace[1556137849] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"108.882253ms","start":"2026-02-25T07:43:30.53462Z","end":"2026-02-25T07:43:30.643502Z","steps":["trace[1556137849] 'process raft request' (duration: 108.572425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:30.648526Z","caller":"traceutil/trace.go:171","msg":"trace[45577041] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"113.807229ms","start":"2026-02-25T07:43:30.534699Z","end":"2026-02-25T07:43:30.648507Z","steps":["trace[45577041] 'process raft request' (duration: 113.697117ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:30.973037Z","caller":"traceutil/trace.go:171","msg":"trace[726791926] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"121.851138ms","start":"2026-02-25T07:43:30.851154Z","end":"2026-02-25T07:43:30.973006Z","steps":["trace[726791926] 'process raft request' (duration: 121.609341ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:37.652992Z","caller":"traceutil/trace.go:171","msg":"trace[1968848566] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"108.73258ms","start":"2026-02-25T07:43:37.544224Z","end":"2026-02-25T07:43:37.652956Z","steps":["trace[1968848566] 'process raft request' (duration: 108.523015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:43:38.414676Z","caller":"traceutil/trace.go:171","msg":"trace[1516710791] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"108.366261ms","start":"2026-02-25T07:43:38.306286Z","end":"2026-02-25T07:43:38.414652Z","steps":["trace[1516710791] 'process raft request' (duration: 37.691844ms)","trace[1516710791] 'compare' (duration: 70.558914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:43:44.086306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.317765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T07:43:44.086484Z","caller":"traceutil/trace.go:171","msg":"trace[729682907] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"159.47983ms","start":"2026-02-25T07:43:43.926973Z","end":"2026-02-25T07:43:44.086453Z","steps":["trace[729682907] 'process raft request' (duration: 49.880448ms)","trace[729682907] 'compare' (duration: 109.091959ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:43:49.04934Z","caller":"traceutil/trace.go:171","msg":"trace[1657956948] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"123.601724ms","start":"2026-02-25T07:43:48.925711Z","end":"2026-02-25T07:43:49.049313Z","steps":["trace[1657956948] 'process raft request' (duration: 99.85931ms)","trace[1657956948] 'compare' (duration: 23.650502ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:44:13.738688Z","caller":"traceutil/trace.go:171","msg":"trace[429292395] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"131.083352ms","start":"2026-02-25T07:44:13.607566Z","end":"2026-02-25T07:44:13.738649Z","steps":["trace[429292395] 'process raft request' (duration: 33.368703ms)","trace[429292395] 'compare' (duration: 97.571936ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:44:24.261929Z","caller":"traceutil/trace.go:171","msg":"trace[729390123] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"134.276215ms","start":"2026-02-25T07:44:24.127618Z","end":"2026-02-25T07:44:24.261895Z","steps":["trace[729390123] 'process raft request' (duration: 60.255539ms)","trace[729390123] 'compare' (duration: 73.895983ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:44:25.950101Z","caller":"traceutil/trace.go:171","msg":"trace[390828758] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"106.042714ms","start":"2026-02-25T07:44:25.844031Z","end":"2026-02-25T07:44:25.950073Z","steps":["trace[390828758] 'process raft request' (duration: 105.89552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:44:26.084854Z","caller":"traceutil/trace.go:171","msg":"trace[2060610796] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"107.582874ms","start":"2026-02-25T07:44:25.977241Z","end":"2026-02-25T07:44:26.084824Z","steps":["trace[2060610796] 'process raft request' (duration: 34.423181ms)","trace[2060610796] 'compare' (duration: 73.052931ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:45:09.273581Z","caller":"traceutil/trace.go:171","msg":"trace[116272865] linearizableReadLoop","detail":"{readStateIndex:1684; appliedIndex:1683; }","duration":"135.635708ms","start":"2026-02-25T07:45:09.137918Z","end":"2026-02-25T07:45:09.273553Z","steps":["trace[116272865] 'read index received' (duration: 48.668428ms)","trace[116272865] 'applied index is now lower than readState.Index' (duration: 86.966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T07:45:09.274006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.895134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-25T07:45:09.274098Z","caller":"traceutil/trace.go:171","msg":"trace[2029120018] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1608; }","duration":"136.164962ms","start":"2026-02-25T07:45:09.137911Z","end":"2026-02-25T07:45:09.274076Z","steps":["trace[2029120018] 'agreement among raft nodes before linearized reading' (duration: 135.745381ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:45:14.479791Z","caller":"traceutil/trace.go:171","msg":"trace[105492085] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"124.626757ms","start":"2026-02-25T07:45:14.355129Z","end":"2026-02-25T07:45:14.479756Z","steps":["trace[105492085] 'process raft request' (duration: 52.846666ms)","trace[105492085] 'compare' (duration: 71.625507ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:45:44.662367Z","caller":"traceutil/trace.go:171","msg":"trace[1941376206] linearizableReadLoop","detail":"{readStateIndex:1838; appliedIndex:1837; }","duration":"130.564999ms","start":"2026-02-25T07:45:44.531779Z","end":"2026-02-25T07:45:44.662344Z","steps":["trace[1941376206] 'read index received' (duration: 130.414016ms)","trace[1941376206] 'applied index is now lower than readState.Index' (duration: 149.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:45:44.662437Z","caller":"traceutil/trace.go:171","msg":"trace[1223184793] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"204.463714ms","start":"2026-02-25T07:45:44.457933Z","end":"2026-02-25T07:45:44.662397Z","steps":["trace[1223184793] 'process raft request' (duration: 204.259229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T07:45:44.66263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.834016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-02-25T07:45:44.662704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.179525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T07:45:44.662795Z","caller":"traceutil/trace.go:171","msg":"trace[501492103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1755; }","duration":"124.286668ms","start":"2026-02-25T07:45:44.538462Z","end":"2026-02-25T07:45:44.662748Z","steps":["trace[501492103] 'agreement among raft nodes before linearized reading' (duration: 124.087403ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:45:44.66276Z","caller":"traceutil/trace.go:171","msg":"trace[864758352] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1755; }","duration":"131.00654ms","start":"2026-02-25T07:45:44.531733Z","end":"2026-02-25T07:45:44.66274Z","steps":["trace[864758352] 'agreement among raft nodes before linearized reading' (duration: 130.743524ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:45:44.906268Z","caller":"traceutil/trace.go:171","msg":"trace[1472499141] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"130.696812ms","start":"2026-02-25T07:45:44.775536Z","end":"2026-02-25T07:45:44.906233Z","steps":["trace[1472499141] 'process raft request' (duration: 130.62122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:45:44.906325Z","caller":"traceutil/trace.go:171","msg":"trace[1235552724] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"130.779924ms","start":"2026-02-25T07:45:44.7755Z","end":"2026-02-25T07:45:44.906279Z","steps":["trace[1235552724] 'process raft request' (duration: 118.960372ms)","trace[1235552724] 'compare' (duration: 11.224217ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:45:45.955578Z","caller":"traceutil/trace.go:171","msg":"trace[2119907940] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"140.826553ms","start":"2026-02-25T07:45:45.814728Z","end":"2026-02-25T07:45:45.955555Z","steps":["trace[2119907940] 'process raft request' (duration: 52.473706ms)","trace[2119907940] 'compare' (duration: 88.243784ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T07:45:49.961778Z","caller":"traceutil/trace.go:171","msg":"trace[1894968979] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"107.286402ms","start":"2026-02-25T07:45:49.854461Z","end":"2026-02-25T07:45:49.961747Z","steps":["trace[1894968979] 'process raft request' (duration: 107.119548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:45:56.931056Z","caller":"traceutil/trace.go:171","msg":"trace[1125675936] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"131.668387ms","start":"2026-02-25T07:45:56.799367Z","end":"2026-02-25T07:45:56.931035Z","steps":["trace[1125675936] 'process raft request' (duration: 131.547954ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T07:46:09.150629Z","caller":"traceutil/trace.go:171","msg":"trace[282788626] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"122.280371ms","start":"2026-02-25T07:46:09.028313Z","end":"2026-02-25T07:46:09.150593Z","steps":["trace[282788626] 'process raft request' (duration: 121.85853ms)"],"step_count":1}