{"level":"warn","ts":"2026-02-09T18:54:35.788834Z","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-09T18:54:35.788971Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-09T18:54:35.794545Z","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-09T18:54:35.794568Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-09T18:54:35.794587Z","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-09T18:54:35.79565Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-09T18:54:35.795727Z","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":2,"max-cpu-available":2,"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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-09T18:54:35.865007Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"69.066161ms"} {"level":"info","ts":"2026-02-09T18:54:36.06296Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-09T18:54:36.063037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T18:54:36.063055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-09T18:54:36.063068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T18:54:36.06308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-09T18:54:36.063115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-09T18:54:36.151153Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T18:54:36.159422Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T18:54:36.162412Z","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-09T18:54:36.166606Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T18:54:36.168163Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T18:54:36.168404Z","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-09T18:54:36.168461Z","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-09T18:54:36.168483Z","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-09T18:54:36.173313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-09T18:54:36.176409Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-09T18:54:36.180752Z","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-09T18:54:36.181342Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-09T18:54:36.181358Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-09T18:54:36.183714Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T18:54:36.183774Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T18:54:36.267794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T18:54:36.267825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T18:54:36.267836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-09T18:54:36.267856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-09T18:54:36.267868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-09T18:54:36.267876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-09T18:54:36.267886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-09T18:54:36.268648Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T18:54:36.268671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:54:36.268663Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:54:36.268957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:54:36.273427Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-09T18:54:36.280417Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T18:54:36.280441Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T18:54:36.286473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T18:54:36.311364Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:54:36.311422Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:54:36.312407Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:54:47.627203Z","caller":"traceutil/trace.go:171","msg":"trace[870413666] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"161.111436ms","start":"2026-02-09T18:54:47.463014Z","end":"2026-02-09T18:54:47.624126Z","steps":["trace[870413666] 'process raft request' (duration: 143.887248ms)","trace[870413666] 'compare' (duration: 17.037354ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:50.802214Z","caller":"traceutil/trace.go:171","msg":"trace[133032113] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"354.96019ms","start":"2026-02-09T18:54:50.447234Z","end":"2026-02-09T18:54:50.802194Z","steps":["trace[133032113] 'read index received' (duration: 354.793526ms)","trace[133032113] 'applied index is now lower than readState.Index' (duration: 165.824µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:50.802468Z","caller":"traceutil/trace.go:171","msg":"trace[1189365114] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"361.181694ms","start":"2026-02-09T18:54:50.441274Z","end":"2026-02-09T18:54:50.802455Z","steps":["trace[1189365114] 'process raft request' (duration: 360.806175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:50.80291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:50.441263Z","time spent":"361.232585ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:50.803651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.228541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:54:50.803701Z","caller":"traceutil/trace.go:171","msg":"trace[1156670537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"299.302053ms","start":"2026-02-09T18:54:50.504386Z","end":"2026-02-09T18:54:50.803688Z","steps":["trace[1156670537] 'agreement among raft nodes before linearized reading' (duration: 299.220191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:50.803853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.614858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:54:50.803878Z","caller":"traceutil/trace.go:171","msg":"trace[1464049087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"356.657759ms","start":"2026-02-09T18:54:50.447213Z","end":"2026-02-09T18:54:50.803871Z","steps":["trace[1464049087] 'agreement among raft nodes before linearized reading' (duration: 356.616408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:50.803903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:50.447203Z","time spent":"356.69328ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:54:50.804009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.670529ms","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-09T18:54:50.804032Z","caller":"traceutil/trace.go:171","msg":"trace[360903139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"100.719059ms","start":"2026-02-09T18:54:50.703306Z","end":"2026-02-09T18:54:50.804025Z","steps":["trace[360903139] 'agreement among raft nodes before linearized reading' (duration: 100.682218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:55.337352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021258186073571,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:54:55.504636Z","caller":"traceutil/trace.go:171","msg":"trace[602950376] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"678.928792ms","start":"2026-02-09T18:54:54.825684Z","end":"2026-02-09T18:54:55.504612Z","steps":["trace[602950376] 'process raft request' (duration: 678.81984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:55.504762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:54.825674Z","time spent":"679.024784ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:55.590684Z","caller":"traceutil/trace.go:171","msg":"trace[347932602] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"754.268865ms","start":"2026-02-09T18:54:54.836392Z","end":"2026-02-09T18:54:55.590661Z","steps":["trace[347932602] 'read index received' (duration: 668.835039ms)","trace[347932602] 'applied index is now lower than readState.Index' (duration: 85.432486ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:55.590854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.444159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:54:55.590893Z","caller":"traceutil/trace.go:171","msg":"trace[1868589995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"754.521331ms","start":"2026-02-09T18:54:54.836362Z","end":"2026-02-09T18:54:55.590884Z","steps":["trace[1868589995] 'agreement among raft nodes before linearized reading' (duration: 754.430239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:55.5909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.344454ms","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-09T18:54:55.590925Z","caller":"traceutil/trace.go:171","msg":"trace[2029723711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"718.389786ms","start":"2026-02-09T18:54:54.872527Z","end":"2026-02-09T18:54:55.590917Z","steps":["trace[2029723711] 'agreement among raft nodes before linearized reading' (duration: 718.346925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:55.590928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:54.83635Z","time spent":"754.570562ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:54:55.590944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:54.872515Z","time spent":"718.425226ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:54:55.590855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.822282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:54:55.591095Z","caller":"traceutil/trace.go:171","msg":"trace[1887074368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:452; }","duration":"721.090537ms","start":"2026-02-09T18:54:54.869987Z","end":"2026-02-09T18:54:55.591077Z","steps":["trace[1887074368] 'agreement among raft nodes before linearized reading' (duration: 720.820391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:55.591147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:54.869973Z","time spent":"721.15688ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:55:01.888608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.846944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:55:01.888683Z","caller":"traceutil/trace.go:171","msg":"trace[1631066759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"257.949326ms","start":"2026-02-09T18:55:01.630714Z","end":"2026-02-09T18:55:01.888663Z","steps":["trace[1631066759] 'range keys from in-memory index tree' (duration: 257.794762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:01.889206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.891224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:01.88926Z","caller":"traceutil/trace.go:171","msg":"trace[1287216988] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"256.174205ms","start":"2026-02-09T18:55:01.633076Z","end":"2026-02-09T18:55:01.88925Z","steps":["trace[1287216988] 'read index received' (duration: 27.023995ms)","trace[1287216988] 'applied index is now lower than readState.Index' (duration: 229.14929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:01.889332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.268147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:55:01.889355Z","caller":"traceutil/trace.go:171","msg":"trace[320750423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"256.336408ms","start":"2026-02-09T18:55:01.633011Z","end":"2026-02-09T18:55:01.889347Z","steps":["trace[320750423] 'agreement among raft nodes before linearized reading' (duration: 256.269857ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:01.890299Z","caller":"traceutil/trace.go:171","msg":"trace[684526850] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"257.70934ms","start":"2026-02-09T18:55:01.632573Z","end":"2026-02-09T18:55:01.890282Z","steps":["trace[684526850] 'process raft request' (duration: 27.564077ms)","trace[684526850] 'compare' (duration: 228.347061ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:08.140453Z","caller":"traceutil/trace.go:171","msg":"trace[2098142952] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"211.635834ms","start":"2026-02-09T18:55:07.928803Z","end":"2026-02-09T18:55:08.140439Z","steps":["trace[2098142952] 'read index received' (duration: 211.518202ms)","trace[2098142952] 'applied index is now lower than readState.Index' (duration: 117.142µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:08.140659Z","caller":"traceutil/trace.go:171","msg":"trace[574227391] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"221.266097ms","start":"2026-02-09T18:55:07.919387Z","end":"2026-02-09T18:55:08.140653Z","steps":["trace[574227391] 'process raft request' (duration: 220.973541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.140811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.997524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:55:08.140834Z","caller":"traceutil/trace.go:171","msg":"trace[1501849509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"212.051294ms","start":"2026-02-09T18:55:07.928775Z","end":"2026-02-09T18:55:08.140826Z","steps":["trace[1501849509] 'agreement among raft nodes before linearized reading' (duration: 211.972023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.140919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.616248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:55:08.140938Z","caller":"traceutil/trace.go:171","msg":"trace[2054635191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"209.67485ms","start":"2026-02-09T18:55:07.931259Z","end":"2026-02-09T18:55:08.140934Z","steps":["trace[2054635191] 'agreement among raft nodes before linearized reading' (duration: 209.632079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.864831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.928521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:08.864999Z","caller":"traceutil/trace.go:171","msg":"trace[532548524] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"553.054431ms","start":"2026-02-09T18:55:08.311929Z","end":"2026-02-09T18:55:08.864984Z","steps":["trace[532548524] 'read index received' (duration: 235.895496ms)","trace[532548524] 'applied index is now lower than readState.Index' (duration: 317.158035ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:08.865078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.143323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:08.865109Z","caller":"traceutil/trace.go:171","msg":"trace[1000398859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"553.197335ms","start":"2026-02-09T18:55:08.311904Z","end":"2026-02-09T18:55:08.865101Z","steps":["trace[1000398859] 'agreement among raft nodes before linearized reading' (duration: 553.130423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.865144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:08.311891Z","time spent":"553.245866ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:55:08.865466Z","caller":"traceutil/trace.go:171","msg":"trace[125647425] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"720.674098ms","start":"2026-02-09T18:55:08.144779Z","end":"2026-02-09T18:55:08.865453Z","steps":["trace[125647425] 'process raft request' (duration: 403.037441ms)","trace[125647425] 'compare' (duration: 316.779997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:08.865531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:08.144767Z","time spent":"720.73302ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:08.865641Z","caller":"traceutil/trace.go:171","msg":"trace[1257944969] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"720.691668ms","start":"2026-02-09T18:55:08.144941Z","end":"2026-02-09T18:55:08.865633Z","steps":["trace[1257944969] 'process raft request' (duration: 719.983552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.865686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:08.144934Z","time spent":"720.72706ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:09.303932Z","caller":"traceutil/trace.go:171","msg":"trace[1781079187] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"197.115829ms","start":"2026-02-09T18:55:09.10679Z","end":"2026-02-09T18:55:09.303906Z","steps":["trace[1781079187] 'process raft request' (duration: 196.983326ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:09.86681Z","caller":"traceutil/trace.go:171","msg":"trace[1976091294] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"118.520491ms","start":"2026-02-09T18:55:09.748265Z","end":"2026-02-09T18:55:09.866785Z","steps":["trace[1976091294] 'read index received' (duration: 118.358808ms)","trace[1976091294] 'applied index is now lower than readState.Index' (duration: 160.543µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:09.866938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.657194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T18:55:09.866968Z","caller":"traceutil/trace.go:171","msg":"trace[828649702] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:520; }","duration":"118.745666ms","start":"2026-02-09T18:55:09.748213Z","end":"2026-02-09T18:55:09.866958Z","steps":["trace[828649702] 'agreement among raft nodes before linearized reading' (duration: 118.663914ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:09.867032Z","caller":"traceutil/trace.go:171","msg":"trace[45429571] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"252.248774ms","start":"2026-02-09T18:55:09.614758Z","end":"2026-02-09T18:55:09.867006Z","steps":["trace[45429571] 'process raft request' (duration: 251.840185ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:12.200187Z","caller":"traceutil/trace.go:171","msg":"trace[1604626416] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"324.142297ms","start":"2026-02-09T18:55:11.87602Z","end":"2026-02-09T18:55:12.200163Z","steps":["trace[1604626416] 'process raft request' (duration: 323.82621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:12.200279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:11.876011Z","time spent":"324.216798ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:12.473875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.1037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:12.473955Z","caller":"traceutil/trace.go:171","msg":"trace[1225081487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"159.213283ms","start":"2026-02-09T18:55:12.314726Z","end":"2026-02-09T18:55:12.473939Z","steps":["trace[1225081487] 'range keys from in-memory index tree' (duration: 159.019448ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:12.628638Z","caller":"traceutil/trace.go:171","msg":"trace[775353415] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"153.617903ms","start":"2026-02-09T18:55:12.475002Z","end":"2026-02-09T18:55:12.62862Z","steps":["trace[775353415] 'read index received' (duration: 153.46754ms)","trace[775353415] 'applied index is now lower than readState.Index' (duration: 149.533µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:12.628746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.732726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:12.628768Z","caller":"traceutil/trace.go:171","msg":"trace[1447217410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"153.804468ms","start":"2026-02-09T18:55:12.474956Z","end":"2026-02-09T18:55:12.62876Z","steps":["trace[1447217410] 'agreement among raft nodes before linearized reading' (duration: 153.732686ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:12.628934Z","caller":"traceutil/trace.go:171","msg":"trace[1326739163] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"191.860677ms","start":"2026-02-09T18:55:12.437048Z","end":"2026-02-09T18:55:12.628909Z","steps":["trace[1326739163] 'process raft request' (duration: 191.431517ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:13.051394Z","caller":"traceutil/trace.go:171","msg":"trace[1482769720] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"170.279658ms","start":"2026-02-09T18:55:12.881093Z","end":"2026-02-09T18:55:13.051373Z","steps":["trace[1482769720] 'process raft request' (duration: 170.140155ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:13.26061Z","caller":"traceutil/trace.go:171","msg":"trace[1338541321] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"376.717923ms","start":"2026-02-09T18:55:12.883867Z","end":"2026-02-09T18:55:13.260585Z","steps":["trace[1338541321] 'process raft request' (duration: 330.903943ms)","trace[1338541321] 'compare' (duration: 45.700497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:13.260743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:12.88385Z","time spent":"376.824976ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:15.581419Z","caller":"traceutil/trace.go:171","msg":"trace[301281064] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"522.896604ms","start":"2026-02-09T18:55:15.058498Z","end":"2026-02-09T18:55:15.581395Z","steps":["trace[301281064] 'process raft request' (duration: 522.673948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:15.5816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.058486Z","time spent":"523.035287ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:15.81061Z","caller":"traceutil/trace.go:171","msg":"trace[1363893653] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"544.939263ms","start":"2026-02-09T18:55:15.265653Z","end":"2026-02-09T18:55:15.810592Z","steps":["trace[1363893653] 'read index received' (duration: 316.263565ms)","trace[1363893653] 'applied index is now lower than readState.Index' (duration: 228.674678ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:15.810864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.20809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:55:15.810892Z","caller":"traceutil/trace.go:171","msg":"trace[1234998309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"545.279861ms","start":"2026-02-09T18:55:15.265605Z","end":"2026-02-09T18:55:15.810885Z","steps":["trace[1234998309] 'agreement among raft nodes before linearized reading' (duration: 545.168079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:15.810913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.265586Z","time spent":"545.321563ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:15.811039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.92277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:15.81106Z","caller":"traceutil/trace.go:171","msg":"trace[786825153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"495.973101ms","start":"2026-02-09T18:55:15.315081Z","end":"2026-02-09T18:55:15.811055Z","steps":["trace[786825153] 'agreement among raft nodes before linearized reading' (duration: 495.94079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:15.811075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.315067Z","time spent":"496.004983ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:19.441786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.695517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ng959\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-09T18:55:19.441812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.690534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:19.441932Z","caller":"traceutil/trace.go:171","msg":"trace[1047615757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"124.790506ms","start":"2026-02-09T18:55:19.317093Z","end":"2026-02-09T18:55:19.441883Z","steps":["trace[1047615757] 'range keys from in-memory index tree' (duration: 124.631563ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:19.441856Z","caller":"traceutil/trace.go:171","msg":"trace[1624489416] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ng959; range_end:; response_count:1; response_revision:542; }","duration":"943.773558ms","start":"2026-02-09T18:55:18.498067Z","end":"2026-02-09T18:55:19.441841Z","steps":["trace[1624489416] 'range keys from in-memory index tree' (duration: 943.611604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:19.442181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:18.498016Z","time spent":"944.154277ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-ng959\" "} {"level":"info","ts":"2026-02-09T18:55:20.979101Z","caller":"traceutil/trace.go:171","msg":"trace[1799240127] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"113.873863ms","start":"2026-02-09T18:55:20.865203Z","end":"2026-02-09T18:55:20.979077Z","steps":["trace[1799240127] 'process raft request' (duration: 113.71534ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:21.675194Z","caller":"traceutil/trace.go:171","msg":"trace[1566871432] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"152.403045ms","start":"2026-02-09T18:55:21.522775Z","end":"2026-02-09T18:55:21.675178Z","steps":["trace[1566871432] 'process raft request' (duration: 151.612327ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:22.293456Z","caller":"traceutil/trace.go:171","msg":"trace[784705911] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"448.434282ms","start":"2026-02-09T18:55:21.844986Z","end":"2026-02-09T18:55:22.29342Z","steps":["trace[784705911] 'process raft request' (duration: 448.325089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:22.293577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:21.844975Z","time spent":"448.540774ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:22.37148Z","caller":"traceutil/trace.go:171","msg":"trace[227929470] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"115.962262ms","start":"2026-02-09T18:55:22.255504Z","end":"2026-02-09T18:55:22.371466Z","steps":["trace[227929470] 'read index received' (duration: 37.884616ms)","trace[227929470] 'applied index is now lower than readState.Index' (duration: 78.077126ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:22.37151Z","caller":"traceutil/trace.go:171","msg":"trace[727764308] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"361.93764ms","start":"2026-02-09T18:55:22.009546Z","end":"2026-02-09T18:55:22.371484Z","steps":["trace[727764308] 'process raft request' (duration: 361.851048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:22.371677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.159226ms","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-09T18:55:22.371696Z","caller":"traceutil/trace.go:171","msg":"trace[186099504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"116.227058ms","start":"2026-02-09T18:55:22.255463Z","end":"2026-02-09T18:55:22.37169Z","steps":["trace[186099504] 'agreement among raft nodes before linearized reading' (duration: 116.077475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:22.371728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:22.009524Z","time spent":"362.068534ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T18:55:27.130127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021258186073925,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:55:27.226963Z","caller":"traceutil/trace.go:171","msg":"trace[1937446660] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"624.949484ms","start":"2026-02-09T18:55:26.602001Z","end":"2026-02-09T18:55:27.22695Z","steps":["trace[1937446660] 'process raft request' (duration: 624.859912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.227062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.601989Z","time spent":"625.022045ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:27.239123Z","caller":"traceutil/trace.go:171","msg":"trace[917185985] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:647; }","duration":"609.590129ms","start":"2026-02-09T18:55:26.629518Z","end":"2026-02-09T18:55:27.239108Z","steps":["trace[917185985] 'read index received' (duration: 597.783166ms)","trace[917185985] 'applied index is now lower than readState.Index' (duration: 11.806173ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:27.239183Z","caller":"traceutil/trace.go:171","msg":"trace[1957223040] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"634.091826ms","start":"2026-02-09T18:55:26.605087Z","end":"2026-02-09T18:55:27.239179Z","steps":["trace[1957223040] 'process raft request' (duration: 633.776309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.239242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.605076Z","time spent":"634.120886ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":614,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:55:27.239518Z","caller":"traceutil/trace.go:171","msg":"trace[74978302] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"629.790866ms","start":"2026-02-09T18:55:26.60971Z","end":"2026-02-09T18:55:27.239501Z","steps":["trace[74978302] 'process raft request' (duration: 629.216363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.239576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.609703Z","time spent":"629.828767ms","remote":"127.0.0.1:55088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4202,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:27.239783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.016509ms","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:5"} {"level":"info","ts":"2026-02-09T18:55:27.239849Z","caller":"traceutil/trace.go:171","msg":"trace[709289053] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:629; }","duration":"610.142872ms","start":"2026-02-09T18:55:26.629689Z","end":"2026-02-09T18:55:27.239832Z","steps":["trace[709289053] 'agreement among raft nodes before linearized reading' (duration: 609.984818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.23989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.629684Z","time spent":"610.197273ms","remote":"127.0.0.1:42284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:55:27.240152Z","caller":"traceutil/trace.go:171","msg":"trace[333382725] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"530.325246ms","start":"2026-02-09T18:55:26.709819Z","end":"2026-02-09T18:55:27.240145Z","steps":["trace[333382725] 'process raft request' (duration: 529.171239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.240169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.736226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-09T18:55:27.240199Z","caller":"traceutil/trace.go:171","msg":"trace[494649991] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"137.117531ms","start":"2026-02-09T18:55:27.103077Z","end":"2026-02-09T18:55:27.240194Z","steps":["trace[494649991] 'process raft request' (duration: 135.992835ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:27.240203Z","caller":"traceutil/trace.go:171","msg":"trace[1350513018] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"364.801707ms","start":"2026-02-09T18:55:26.875392Z","end":"2026-02-09T18:55:27.240194Z","steps":["trace[1350513018] 'agreement among raft nodes before linearized reading' (duration: 364.686455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.240231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.875377Z","time spent":"364.848258ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-09T18:55:27.240296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.775037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:27.240312Z","caller":"traceutil/trace.go:171","msg":"trace[1761913219] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:629; }","duration":"610.849688ms","start":"2026-02-09T18:55:26.629458Z","end":"2026-02-09T18:55:27.240308Z","steps":["trace[1761913219] 'agreement among raft nodes before linearized reading' (duration: 610.814007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.240325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.629444Z","time spent":"610.877328ms","remote":"127.0.0.1:42284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-09T18:55:27.240354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.380295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:27.240379Z","caller":"traceutil/trace.go:171","msg":"trace[513623113] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:629; }","duration":"555.428146ms","start":"2026-02-09T18:55:26.684943Z","end":"2026-02-09T18:55:27.240372Z","steps":["trace[513623113] 'agreement among raft nodes before linearized reading' (duration: 555.390266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:27.240403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.684931Z","time spent":"555.466767ms","remote":"127.0.0.1:42284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 "} {"level":"warn","ts":"2026-02-09T18:55:27.24018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:26.709792Z","time spent":"530.373947ms","remote":"127.0.0.1:54962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:55:30.525609Z","caller":"traceutil/trace.go:171","msg":"trace[1078356341] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"155.128168ms","start":"2026-02-09T18:55:30.370456Z","end":"2026-02-09T18:55:30.525584Z","steps":["trace[1078356341] 'process raft request' (duration: 154.713228ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:33.956407Z","caller":"traceutil/trace.go:171","msg":"trace[922935166] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"162.467387ms","start":"2026-02-09T18:55:33.793923Z","end":"2026-02-09T18:55:33.956391Z","steps":["trace[922935166] 'process raft request' (duration: 156.94472ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:35.066099Z","caller":"traceutil/trace.go:171","msg":"trace[1693695686] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"362.232478ms","start":"2026-02-09T18:55:34.703844Z","end":"2026-02-09T18:55:35.066077Z","steps":["trace[1693695686] 'process raft request' (duration: 299.908546ms)","trace[1693695686] 'compare' (duration: 62.23273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:35.066316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:34.703799Z","time spent":"362.362741ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5011,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:35.813199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021258186074077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:55:36.163258Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088626687s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-09T18:55:37.060504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.949136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:37.062424Z","caller":"traceutil/trace.go:171","msg":"trace[1190374079] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.987856795s","start":"2026-02-09T18:55:35.074554Z","end":"2026-02-09T18:55:37.062411Z","steps":["trace[1190374079] 'process raft request' (duration: 1.088947624s)","trace[1190374079] 'compare' (duration: 896.71096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:37.062606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.074546Z","time spent":"1.987959967s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:37.313262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000374785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-09T18:55:37.313331Z","caller":"traceutil/trace.go:171","msg":"trace[2107009650] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000486347s","start":"2026-02-09T18:55:35.31283Z","end":"2026-02-09T18:55:37.313316Z","steps":["trace[2107009650] 'agreement among raft nodes before linearized reading' (duration: 2.000399265s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.313374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.312806Z","time spent":"2.000561209s","remote":"127.0.0.1:54908","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-09T18:55:37.630661Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.467249876s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-09T18:55:37.81321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.566782ms","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-09T18:55:37.813366Z","caller":"traceutil/trace.go:171","msg":"trace[1138604645] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:697; }","duration":"182.729506ms","start":"2026-02-09T18:55:37.630618Z","end":"2026-02-09T18:55:37.813348Z","steps":["trace[1138604645] 'range keys from in-memory index tree' (duration: 182.552792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.813756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.952711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:37.815874Z","caller":"traceutil/trace.go:171","msg":"trace[448628981] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"2.740618665s","start":"2026-02-09T18:55:35.075242Z","end":"2026-02-09T18:55:37.815861Z","steps":["trace[448628981] 'process raft request' (duration: 2.555507144s)","trace[448628981] 'compare' (duration: 182.794718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:37.815914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.075237Z","time spent":"2.740657896s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1298,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:37.816079Z","caller":"traceutil/trace.go:171","msg":"trace[450526394] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"2.736854018s","start":"2026-02-09T18:55:35.079221Z","end":"2026-02-09T18:55:37.816075Z","steps":["trace[450526394] 'process raft request' (duration: 2.734584846s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.816103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.079215Z","time spent":"2.736875369s","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:38.063916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.061134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T18:55:38.063982Z","caller":"traceutil/trace.go:171","msg":"trace[1108456028] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:720; }","duration":"2.751089368s","start":"2026-02-09T18:55:35.31288Z","end":"2026-02-09T18:55:38.063969Z","steps":["trace[1108456028] 'read index received' (duration: 850.629503ms)","trace[1108456028] 'applied index is now lower than readState.Index' (duration: 1.900459024s)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:38.064211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.430546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:38.064227Z","caller":"traceutil/trace.go:171","msg":"trace[1457016483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"750.469837ms","start":"2026-02-09T18:55:37.313752Z","end":"2026-02-09T18:55:38.064222Z","steps":["trace[1457016483] 'agreement among raft nodes before linearized reading' (duration: 750.431696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.064246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:37.313741Z","time spent":"750.500898ms","remote":"127.0.0.1:54912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:38.064248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.72089698s","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-09T18:55:38.06429Z","caller":"traceutil/trace.go:171","msg":"trace[1559840641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"2.720959561s","start":"2026-02-09T18:55:35.34332Z","end":"2026-02-09T18:55:38.06428Z","steps":["trace[1559840641] 'agreement among raft nodes before linearized reading' (duration: 2.720842008s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.06431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.343313Z","time spent":"2.720993231s","remote":"127.0.0.1:55174","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-09T18:55:38.064397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.558389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-9c7p2\" ","response":"range_response_count:1 size:4990"} {"level":"info","ts":"2026-02-09T18:55:38.064412Z","caller":"traceutil/trace.go:171","msg":"trace[2077967899] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-9c7p2; range_end:; response_count:1; response_revision:699; }","duration":"245.58972ms","start":"2026-02-09T18:55:37.818818Z","end":"2026-02-09T18:55:38.064407Z","steps":["trace[2077967899] 'agreement among raft nodes before linearized reading' (duration: 245.522538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.064429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281820007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T18:55:38.064446Z","caller":"traceutil/trace.go:171","msg":"trace[1294099740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"1.281940739s","start":"2026-02-09T18:55:36.782501Z","end":"2026-02-09T18:55:38.064442Z","steps":["trace[1294099740] 'agreement among raft nodes before linearized reading' (duration: 1.281897358s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.064459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.782481Z","time spent":"1.281974461s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:38.064539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453685362s","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-09T18:55:38.064551Z","caller":"traceutil/trace.go:171","msg":"trace[499485648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"1.453709092s","start":"2026-02-09T18:55:36.610838Z","end":"2026-02-09T18:55:38.064547Z","steps":["trace[499485648] 'agreement among raft nodes before linearized reading' (duration: 1.453673541s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.064562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.610831Z","time spent":"1.453727932s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:55:38.06462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079799772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:55:38.064636Z","caller":"traceutil/trace.go:171","msg":"trace[1305874674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:699; }","duration":"2.079843804s","start":"2026-02-09T18:55:35.984788Z","end":"2026-02-09T18:55:38.064631Z","steps":["trace[1305874674] 'agreement among raft nodes before linearized reading' (duration: 2.079799863s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.064648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.984769Z","time spent":"2.079876095s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:55:38.064712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455728629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:55:38.064723Z","caller":"traceutil/trace.go:171","msg":"trace[2124973091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:699; }","duration":"1.45576727s","start":"2026-02-09T18:55:36.608952Z","end":"2026-02-09T18:55:38.064719Z","steps":["trace[2124973091] 'agreement among raft nodes before linearized reading' (duration: 1.455734909s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:38.064741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.608936Z","time spent":"1.45580295s","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T18:55:44.589125Z","caller":"traceutil/trace.go:171","msg":"trace[867342359] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"396.215744ms","start":"2026-02-09T18:55:44.19288Z","end":"2026-02-09T18:55:44.589095Z","steps":["trace[867342359] 'read index received' (duration: 396.058081ms)","trace[867342359] 'applied index is now lower than readState.Index' (duration: 157.033µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:44.589359Z","caller":"traceutil/trace.go:171","msg":"trace[949721589] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"397.717219ms","start":"2026-02-09T18:55:44.191628Z","end":"2026-02-09T18:55:44.589345Z","steps":["trace[949721589] 'process raft request' (duration: 397.372511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:44.589431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.191615Z","time spent":"397.76437ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:44.589614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.732436ms","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-09T18:55:44.589639Z","caller":"traceutil/trace.go:171","msg":"trace[1893720120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"396.781927ms","start":"2026-02-09T18:55:44.192848Z","end":"2026-02-09T18:55:44.58963Z","steps":["trace[1893720120] 'agreement among raft nodes before linearized reading' (duration: 396.691635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:44.589664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.192832Z","time spent":"396.825938ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:45.096961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.919929ms","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-09T18:55:45.097014Z","caller":"traceutil/trace.go:171","msg":"trace[27330378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"892.001191ms","start":"2026-02-09T18:55:44.205Z","end":"2026-02-09T18:55:45.097001Z","steps":["trace[27330378] 'agreement among raft nodes before linearized reading' (duration: 384.758199ms)","trace[27330378] 'range keys from in-memory index tree' (duration: 507.130499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.097043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.20499Z","time spent":"892.045881ms","remote":"127.0.0.1:55174","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-09T18:55:45.097198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.931118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.097218Z","caller":"traceutil/trace.go:171","msg":"trace[1727127836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"782.964548ms","start":"2026-02-09T18:55:44.314246Z","end":"2026-02-09T18:55:45.097211Z","steps":["trace[1727127836] 'agreement among raft nodes before linearized reading' (duration: 275.568923ms)","trace[1727127836] 'range keys from in-memory index tree' (duration: 507.367295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.097235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.31424Z","time spent":"782.99185ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:45.097344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.515238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T18:55:45.097362Z","caller":"traceutil/trace.go:171","msg":"trace[1752628414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"814.55164ms","start":"2026-02-09T18:55:44.282804Z","end":"2026-02-09T18:55:45.097356Z","steps":["trace[1752628414] 'agreement among raft nodes before linearized reading' (duration: 307.016521ms)","trace[1752628414] 'range keys from in-memory index tree' (duration: 507.478488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.097379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.282794Z","time spent":"814.58157ms","remote":"127.0.0.1:55174","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-09T18:55:45.097474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.45218ms","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-09T18:55:45.097501Z","caller":"traceutil/trace.go:171","msg":"trace[1491775122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"815.504411ms","start":"2026-02-09T18:55:44.28199Z","end":"2026-02-09T18:55:45.097495Z","steps":["trace[1491775122] 'agreement among raft nodes before linearized reading' (duration: 307.85023ms)","trace[1491775122] 'range keys from in-memory index tree' (duration: 507.59929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.09752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.281977Z","time spent":"815.539442ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T18:55:45.097988Z","caller":"traceutil/trace.go:171","msg":"trace[2042047570] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"502.979513ms","start":"2026-02-09T18:55:44.595001Z","end":"2026-02-09T18:55:45.09798Z","steps":["trace[2042047570] 'process raft request' (duration: 501.791726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.098044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:44.594986Z","time spent":"503.020344ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:45.551144Z","caller":"traceutil/trace.go:171","msg":"trace[548260252] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"443.394515ms","start":"2026-02-09T18:55:45.10773Z","end":"2026-02-09T18:55:45.551124Z","steps":["trace[548260252] 'process raft request' (duration: 421.616911ms)","trace[548260252] 'compare' (duration: 20.994685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.551258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.107718Z","time spent":"443.474367ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:45.754921Z","caller":"traceutil/trace.go:171","msg":"trace[1703231367] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"647.066105ms","start":"2026-02-09T18:55:45.107834Z","end":"2026-02-09T18:55:45.754901Z","steps":["trace[1703231367] 'process raft request' (duration: 589.229338ms)","trace[1703231367] 'compare' (duration: 57.556391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.755044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.107817Z","time spent":"647.159417ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:45.755286Z","caller":"traceutil/trace.go:171","msg":"trace[581434862] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"647.396983ms","start":"2026-02-09T18:55:45.107883Z","end":"2026-02-09T18:55:45.75528Z","steps":["trace[581434862] 'process raft request' (duration: 646.83798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.755321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.107879Z","time spent":"647.423774ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:45.897304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.538377ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T18:55:45.897369Z","caller":"traceutil/trace.go:171","msg":"trace[1097543787] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:775; }","duration":"582.255566ms","start":"2026-02-09T18:55:45.315102Z","end":"2026-02-09T18:55:45.897357Z","steps":["trace[1097543787] 'read index received' (duration: 214.251345ms)","trace[1097543787] 'applied index is now lower than readState.Index' (duration: 368.003281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:45.897677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.577274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.897692Z","caller":"traceutil/trace.go:171","msg":"trace[1140609617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"582.624655ms","start":"2026-02-09T18:55:45.315062Z","end":"2026-02-09T18:55:45.897687Z","steps":["trace[1140609617] 'agreement among raft nodes before linearized reading' (duration: 582.589394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.897707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.315047Z","time spent":"582.656475ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:45.897812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.815989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.897826Z","caller":"traceutil/trace.go:171","msg":"trace[803793916] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:750; }","duration":"422.85042ms","start":"2026-02-09T18:55:45.47497Z","end":"2026-02-09T18:55:45.897821Z","steps":["trace[803793916] 'agreement among raft nodes before linearized reading' (duration: 422.82885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.897841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.474957Z","time spent":"422.8792ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-09T18:55:45.898552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.520303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.898566Z","caller":"traceutil/trace.go:171","msg":"trace[1683391184] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:750; }","duration":"387.543393ms","start":"2026-02-09T18:55:45.511019Z","end":"2026-02-09T18:55:45.898562Z","steps":["trace[1683391184] 'agreement among raft nodes before linearized reading' (duration: 387.516703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.898582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.511014Z","time spent":"387.564694ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-09T18:55:45.898647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.843848ms","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-09T18:55:45.898661Z","caller":"traceutil/trace.go:171","msg":"trace[388398169] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:750; }","duration":"396.866958ms","start":"2026-02-09T18:55:45.50179Z","end":"2026-02-09T18:55:45.898657Z","steps":["trace[388398169] 'agreement among raft nodes before linearized reading' (duration: 396.846148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.898686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.501785Z","time spent":"396.885019ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:45.898739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.606676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.898751Z","caller":"traceutil/trace.go:171","msg":"trace[813777903] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:750; }","duration":"397.638996ms","start":"2026-02-09T18:55:45.501108Z","end":"2026-02-09T18:55:45.898747Z","steps":["trace[813777903] 'agreement among raft nodes before linearized reading' (duration: 397.620566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.898762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.501099Z","time spent":"397.659587ms","remote":"127.0.0.1:34360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-09T18:55:45.898825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.046583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.898839Z","caller":"traceutil/trace.go:171","msg":"trace[762750717] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:750; }","duration":"410.069425ms","start":"2026-02-09T18:55:45.488765Z","end":"2026-02-09T18:55:45.898835Z","steps":["trace[762750717] 'agreement among raft nodes before linearized reading' (duration: 410.049344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.898853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.488761Z","time spent":"410.087685ms","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-09T18:55:45.898918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.319663ms","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-09T18:55:45.89893Z","caller":"traceutil/trace.go:171","msg":"trace[1373223080] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:750; }","duration":"411.338244ms","start":"2026-02-09T18:55:45.487588Z","end":"2026-02-09T18:55:45.898926Z","steps":["trace[1373223080] 'agreement among raft nodes before linearized reading' (duration: 411.320433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.898941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.487585Z","time spent":"411.353414ms","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:45.898993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.457716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.899005Z","caller":"traceutil/trace.go:171","msg":"trace[929633443] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:750; }","duration":"411.488116ms","start":"2026-02-09T18:55:45.487513Z","end":"2026-02-09T18:55:45.899001Z","steps":["trace[929633443] 'agreement among raft nodes before linearized reading' (duration: 411.470376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.899016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.487501Z","time spent":"411.511848ms","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-09T18:55:45.899081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.039867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.899097Z","caller":"traceutil/trace.go:171","msg":"trace[889908795] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:750; }","duration":"424.057937ms","start":"2026-02-09T18:55:45.475035Z","end":"2026-02-09T18:55:45.899093Z","steps":["trace[889908795] 'agreement among raft nodes before linearized reading' (duration: 424.031807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.899117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.475025Z","time spent":"424.082258ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-09T18:55:45.899186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.1614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:45.8992Z","caller":"traceutil/trace.go:171","msg":"trace[1081542898] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:750; }","duration":"424.182081ms","start":"2026-02-09T18:55:45.475013Z","end":"2026-02-09T18:55:45.899195Z","steps":["trace[1081542898] 'agreement among raft nodes before linearized reading' (duration: 424.16199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:45.899213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.47501Z","time spent":"424.198491ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:55:47.138618Z","caller":"traceutil/trace.go:171","msg":"trace[83894361] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"460.380227ms","start":"2026-02-09T18:55:46.678219Z","end":"2026-02-09T18:55:47.1386Z","steps":["trace[83894361] 'read index received' (duration: 460.184913ms)","trace[83894361] 'applied index is now lower than readState.Index' (duration: 194.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:47.138869Z","caller":"traceutil/trace.go:171","msg":"trace[700732242] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"542.063347ms","start":"2026-02-09T18:55:46.596793Z","end":"2026-02-09T18:55:47.138857Z","steps":["trace[700732242] 'process raft request' (duration: 541.692119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.138974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.596783Z","time spent":"542.112838ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:47.139147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.926161ms","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-09T18:55:47.139176Z","caller":"traceutil/trace.go:171","msg":"trace[810862473] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:751; }","duration":"460.982131ms","start":"2026-02-09T18:55:46.678185Z","end":"2026-02-09T18:55:47.139167Z","steps":["trace[810862473] 'agreement among raft nodes before linearized reading' (duration: 460.92527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.139203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.678164Z","time spent":"461.032063ms","remote":"127.0.0.1:55240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:55.276707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.098635ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-09T18:55:55.545845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.700682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:55.545929Z","caller":"traceutil/trace.go:171","msg":"trace[837725126] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"233.79895ms","start":"2026-02-09T18:55:55.312118Z","end":"2026-02-09T18:55:55.545917Z","steps":["trace[837725126] 'read index received' (duration: 39.773895ms)","trace[837725126] 'applied index is now lower than readState.Index' (duration: 194.024305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:55.545982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.861411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:55.545999Z","caller":"traceutil/trace.go:171","msg":"trace[1263142906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"233.893062ms","start":"2026-02-09T18:55:55.312099Z","end":"2026-02-09T18:55:55.545992Z","steps":["trace[1263142906] 'agreement among raft nodes before linearized reading' (duration: 233.861772ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:55.54605Z","caller":"traceutil/trace.go:171","msg":"trace[552327081] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"264.501957ms","start":"2026-02-09T18:55:55.281526Z","end":"2026-02-09T18:55:55.546028Z","steps":["trace[552327081] 'process raft request' (duration: 128.545169ms)","trace[552327081] 'compare' (duration: 135.562969ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:55.801507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.761367ms","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-09T18:55:55.801581Z","caller":"traceutil/trace.go:171","msg":"trace[251319218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"116.847479ms","start":"2026-02-09T18:55:55.684719Z","end":"2026-02-09T18:55:55.801567Z","steps":["trace[251319218] 'range keys from in-memory index tree' (duration: 116.612764ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:56.191344Z","caller":"traceutil/trace.go:171","msg":"trace[2147443019] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"315.511571ms","start":"2026-02-09T18:55:55.875813Z","end":"2026-02-09T18:55:56.191324Z","steps":["trace[2147443019] 'read index received' (duration: 277.062386ms)","trace[2147443019] 'applied index is now lower than readState.Index' (duration: 38.448145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:56.191514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.685185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:55:56.191551Z","caller":"traceutil/trace.go:171","msg":"trace[1097484149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"315.728785ms","start":"2026-02-09T18:55:55.875802Z","end":"2026-02-09T18:55:56.19153Z","steps":["trace[1097484149] 'agreement among raft nodes before linearized reading' (duration: 315.586512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:56.191574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:55.875796Z","time spent":"315.771937ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T18:55:56.191651Z","caller":"traceutil/trace.go:171","msg":"trace[1185315492] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"315.972641ms","start":"2026-02-09T18:55:55.87567Z","end":"2026-02-09T18:55:56.191643Z","steps":["trace[1185315492] 'process raft request' (duration: 277.240499ms)","trace[1185315492] 'compare' (duration: 38.274021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:56.191707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:55.875661Z","time spent":"316.005623ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:56.488205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.783125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:56.48827Z","caller":"traceutil/trace.go:171","msg":"trace[1139151492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"175.889988ms","start":"2026-02-09T18:55:56.312366Z","end":"2026-02-09T18:55:56.488256Z","steps":["trace[1139151492] 'range keys from in-memory index tree' (duration: 175.701143ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.841267Z","caller":"traceutil/trace.go:171","msg":"trace[971126654] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"292.690805ms","start":"2026-02-09T18:55:57.548555Z","end":"2026-02-09T18:55:57.841246Z","steps":["trace[971126654] 'read index received' (duration: 292.503561ms)","trace[971126654] 'applied index is now lower than readState.Index' (duration: 186.374µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:57.841498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.917171ms","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-09T18:55:57.841544Z","caller":"traceutil/trace.go:171","msg":"trace[795728983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"293.004862ms","start":"2026-02-09T18:55:57.548525Z","end":"2026-02-09T18:55:57.84153Z","steps":["trace[795728983] 'agreement among raft nodes before linearized reading' (duration: 292.805298ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.841869Z","caller":"traceutil/trace.go:171","msg":"trace[1426539779] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"484.719554ms","start":"2026-02-09T18:55:57.357139Z","end":"2026-02-09T18:55:57.841859Z","steps":["trace[1426539779] 'process raft request' (duration: 483.978117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:57.841951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:57.357129Z","time spent":"484.771906ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:02.038607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.713411ms","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-09T18:56:02.038695Z","caller":"traceutil/trace.go:171","msg":"trace[1488377632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"118.840695ms","start":"2026-02-09T18:56:01.919831Z","end":"2026-02-09T18:56:02.038672Z","steps":["trace[1488377632] 'range keys from in-memory index tree' (duration: 118.565668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:02.039175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.164882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:56:02.039297Z","caller":"traceutil/trace.go:171","msg":"trace[867523634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"119.304515ms","start":"2026-02-09T18:56:01.919978Z","end":"2026-02-09T18:56:02.039283Z","steps":["trace[867523634] 'range keys from in-memory index tree' (duration: 119.07567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:02.475626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.104395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:56:02.476858Z","caller":"traceutil/trace.go:171","msg":"trace[752635064] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"263.983245ms","start":"2026-02-09T18:56:02.212856Z","end":"2026-02-09T18:56:02.476839Z","steps":["trace[752635064] 'read index received' (duration: 49.341µs)","trace[752635064] 'applied index is now lower than readState.Index' (duration: 263.931664ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:02.477064Z","caller":"traceutil/trace.go:171","msg":"trace[2134975314] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"412.284377ms","start":"2026-02-09T18:56:02.064753Z","end":"2026-02-09T18:56:02.477038Z","steps":["trace[2134975314] 'process raft request' (duration: 137.664208ms)","trace[2134975314] 'compare' (duration: 272.803787ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:02.477248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:02.064748Z","time spent":"412.381249ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:02.477523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.6657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T18:56:02.478617Z","caller":"traceutil/trace.go:171","msg":"trace[230793789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"265.793846ms","start":"2026-02-09T18:56:02.212807Z","end":"2026-02-09T18:56:02.478601Z","steps":["trace[230793789] 'agreement among raft nodes before linearized reading' (duration: 264.419875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:02.477716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.958447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:02.47897Z","caller":"traceutil/trace.go:171","msg":"trace[48527449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"163.208386ms","start":"2026-02-09T18:56:02.315717Z","end":"2026-02-09T18:56:02.478926Z","steps":["trace[48527449] 'agreement among raft nodes before linearized reading' (duration: 161.960567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:02.477823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.824175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:56:02.479191Z","caller":"traceutil/trace.go:171","msg":"trace[848889619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"137.221887ms","start":"2026-02-09T18:56:02.341959Z","end":"2026-02-09T18:56:02.47918Z","steps":["trace[848889619] 'agreement among raft nodes before linearized reading' (duration: 135.783424ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:16.484194Z","caller":"traceutil/trace.go:171","msg":"trace[1855309970] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"172.854028ms","start":"2026-02-09T18:56:16.311324Z","end":"2026-02-09T18:56:16.484178Z","steps":["trace[1855309970] 'read index received' (duration: 172.680184ms)","trace[1855309970] 'applied index is now lower than readState.Index' (duration: 173.334µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:16.484291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.95087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:16.484308Z","caller":"traceutil/trace.go:171","msg":"trace[989572715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"172.982351ms","start":"2026-02-09T18:56:16.31132Z","end":"2026-02-09T18:56:16.484303Z","steps":["trace[989572715] 'agreement among raft nodes before linearized reading' (duration: 172.9228ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:16.484501Z","caller":"traceutil/trace.go:171","msg":"trace[1088039873] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"298.943ms","start":"2026-02-09T18:56:16.185553Z","end":"2026-02-09T18:56:16.484496Z","steps":["trace[1088039873] 'process raft request' (duration: 298.492419ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:16.672466Z","caller":"traceutil/trace.go:171","msg":"trace[1959119077] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"128.877556ms","start":"2026-02-09T18:56:16.543569Z","end":"2026-02-09T18:56:16.672446Z","steps":["trace[1959119077] 'process raft request' (duration: 56.022539ms)","trace[1959119077] 'compare' (duration: 72.767225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:19.175981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021258186074533,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:56:19.177136Z","caller":"traceutil/trace.go:171","msg":"trace[720028863] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"501.464459ms","start":"2026-02-09T18:56:18.675658Z","end":"2026-02-09T18:56:19.177122Z","steps":["trace[720028863] 'read index received' (duration: 501.363307ms)","trace[720028863] 'applied index is now lower than readState.Index' (duration: 100.772µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:19.177261Z","caller":"traceutil/trace.go:171","msg":"trace[1063567953] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"688.075383ms","start":"2026-02-09T18:56:18.489166Z","end":"2026-02-09T18:56:19.177242Z","steps":["trace[1063567953] 'process raft request' (duration: 687.888608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:19.17732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:18.489158Z","time spent":"688.117943ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:19.177449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.794886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:56:19.177465Z","caller":"traceutil/trace.go:171","msg":"trace[653656527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"501.831727ms","start":"2026-02-09T18:56:18.675628Z","end":"2026-02-09T18:56:19.17746Z","steps":["trace[653656527] 'agreement among raft nodes before linearized reading' (duration: 501.771325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:19.177479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:18.675617Z","time spent":"501.859658ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:56:19.177557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.236014ms","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-09T18:56:19.177569Z","caller":"traceutil/trace.go:171","msg":"trace[1154241693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"458.270954ms","start":"2026-02-09T18:56:18.719295Z","end":"2026-02-09T18:56:19.177566Z","steps":["trace[1154241693] 'agreement among raft nodes before linearized reading' (duration: 458.235013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:19.177581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:18.719278Z","time spent":"458.299565ms","remote":"127.0.0.1:55174","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-09T18:56:19.177677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.837228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-09T18:56:19.177691Z","caller":"traceutil/trace.go:171","msg":"trace[1360393116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"441.875838ms","start":"2026-02-09T18:56:18.735811Z","end":"2026-02-09T18:56:19.177687Z","steps":["trace[1360393116] 'agreement among raft nodes before linearized reading' (duration: 441.840377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:19.177702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:18.735796Z","time spent":"441.903449ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:56:19.666629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.951321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:56:19.66685Z","caller":"traceutil/trace.go:171","msg":"trace[2023416000] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:933; }","duration":"338.295465ms","start":"2026-02-09T18:56:19.328538Z","end":"2026-02-09T18:56:19.666833Z","steps":["trace[2023416000] 'read index received' (duration: 3.706616ms)","trace[2023416000] 'applied index is now lower than readState.Index' (duration: 334.587929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:19.666936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.390347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:19.666961Z","caller":"traceutil/trace.go:171","msg":"trace[518488144] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:900; }","duration":"338.419618ms","start":"2026-02-09T18:56:19.328534Z","end":"2026-02-09T18:56:19.666953Z","steps":["trace[518488144] 'agreement among raft nodes before linearized reading' (duration: 338.348676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:19.666994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:19.328501Z","time spent":"338.484448ms","remote":"127.0.0.1:55192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:56:19.667204Z","caller":"traceutil/trace.go:171","msg":"trace[1424681532] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"484.680613ms","start":"2026-02-09T18:56:19.182502Z","end":"2026-02-09T18:56:19.667183Z","steps":["trace[1424681532] 'process raft request' (duration: 484.217532ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:19.667278Z","caller":"traceutil/trace.go:171","msg":"trace[1920901029] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"483.605469ms","start":"2026-02-09T18:56:19.183661Z","end":"2026-02-09T18:56:19.667266Z","steps":["trace[1920901029] 'process raft request' (duration: 483.135658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:19.667306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:19.182496Z","time spent":"484.753095ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:19.667339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:19.183655Z","time spent":"483.65047ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:19.667422Z","caller":"traceutil/trace.go:171","msg":"trace[306689974] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"485.593114ms","start":"2026-02-09T18:56:19.181821Z","end":"2026-02-09T18:56:19.667414Z","steps":["trace[306689974] 'process raft request' (duration: 35.777634ms)","trace[306689974] 'compare' (duration: 448.836038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:19.667452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:19.181809Z","time spent":"485.626855ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:20.919925Z","caller":"traceutil/trace.go:171","msg":"trace[1530250127] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"172.572091ms","start":"2026-02-09T18:56:20.747335Z","end":"2026-02-09T18:56:20.919907Z","steps":["trace[1530250127] 'process raft request' (duration: 172.203363ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:23.522239Z","caller":"traceutil/trace.go:171","msg":"trace[940425774] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"210.956165ms","start":"2026-02-09T18:56:23.311268Z","end":"2026-02-09T18:56:23.522225Z","steps":["trace[940425774] 'read index received' (duration: 210.819011ms)","trace[940425774] 'applied index is now lower than readState.Index' (duration: 136.324µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:23.522332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.045977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:23.522349Z","caller":"traceutil/trace.go:171","msg":"trace[869281022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"211.080417ms","start":"2026-02-09T18:56:23.311264Z","end":"2026-02-09T18:56:23.522344Z","steps":["trace[869281022] 'agreement among raft nodes before linearized reading' (duration: 211.014456ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:23.522536Z","caller":"traceutil/trace.go:171","msg":"trace[781657766] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"333.28719ms","start":"2026-02-09T18:56:23.189243Z","end":"2026-02-09T18:56:23.52253Z","steps":["trace[781657766] 'process raft request' (duration: 332.904311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:23.522581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:23.189233Z","time spent":"333.31548ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:24.558374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.958724ms","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-09T18:56:24.55842Z","caller":"traceutil/trace.go:171","msg":"trace[43803863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:912; }","duration":"430.038007ms","start":"2026-02-09T18:56:24.128371Z","end":"2026-02-09T18:56:24.558409Z","steps":["trace[43803863] 'range keys from in-memory index tree' (duration: 429.862682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:24.55844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:24.128352Z","time spent":"430.084227ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T18:56:24.558772Z","caller":"traceutil/trace.go:171","msg":"trace[1730029734] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"225.9623ms","start":"2026-02-09T18:56:24.332804Z","end":"2026-02-09T18:56:24.558767Z","steps":["trace[1730029734] 'read index received' (duration: 225.229433ms)","trace[1730029734] 'applied index is now lower than readState.Index' (duration: 732.447µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:24.558814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.007471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:24.558825Z","caller":"traceutil/trace.go:171","msg":"trace[370452917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"226.037921ms","start":"2026-02-09T18:56:24.332784Z","end":"2026-02-09T18:56:24.558822Z","steps":["trace[370452917] 'agreement among raft nodes before linearized reading' (duration: 226.00234ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:24.558941Z","caller":"traceutil/trace.go:171","msg":"trace[1257060152] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"229.803679ms","start":"2026-02-09T18:56:24.329132Z","end":"2026-02-09T18:56:24.558936Z","steps":["trace[1257060152] 'process raft request' (duration: 228.952869ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:24.956066Z","caller":"traceutil/trace.go:171","msg":"trace[1209802586] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"390.928966ms","start":"2026-02-09T18:56:24.565111Z","end":"2026-02-09T18:56:24.95604Z","steps":["trace[1209802586] 'process raft request' (duration: 347.340182ms)","trace[1209802586] 'compare' (duration: 42.868377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:24.956182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:24.565091Z","time spent":"391.027939ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:24.956366Z","caller":"traceutil/trace.go:171","msg":"trace[222590670] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"360.565787ms","start":"2026-02-09T18:56:24.595794Z","end":"2026-02-09T18:56:24.95636Z","steps":["trace[222590670] 'read index received' (duration: 316.664877ms)","trace[222590670] 'applied index is now lower than readState.Index' (duration: 43.89977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:24.960844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.02993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:56:24.960894Z","caller":"traceutil/trace.go:171","msg":"trace[688286705] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:914; }","duration":"365.091481ms","start":"2026-02-09T18:56:24.595791Z","end":"2026-02-09T18:56:24.960882Z","steps":["trace[688286705] 'agreement among raft nodes before linearized reading' (duration: 360.618168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:24.960933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:24.59576Z","time spent":"365.154853ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:56:25.692532Z","caller":"traceutil/trace.go:171","msg":"trace[2024089291] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"163.873831ms","start":"2026-02-09T18:56:25.528642Z","end":"2026-02-09T18:56:25.692516Z","steps":["trace[2024089291] 'process raft request' (duration: 163.763499ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:25.867657Z","caller":"traceutil/trace.go:171","msg":"trace[191799684] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"142.785705ms","start":"2026-02-09T18:56:25.724853Z","end":"2026-02-09T18:56:25.867639Z","steps":["trace[191799684] 'process raft request' (duration: 87.390511ms)","trace[191799684] 'compare' (duration: 54.902973ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:33.49718Z","caller":"traceutil/trace.go:171","msg":"trace[614735359] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"186.578393ms","start":"2026-02-09T18:56:33.310579Z","end":"2026-02-09T18:56:33.497157Z","steps":["trace[614735359] 'read index received' (duration: 186.396139ms)","trace[614735359] 'applied index is now lower than readState.Index' (duration: 181.344µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:33.49729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.689366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:33.49732Z","caller":"traceutil/trace.go:171","msg":"trace[343860472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"186.759558ms","start":"2026-02-09T18:56:33.310551Z","end":"2026-02-09T18:56:33.497311Z","steps":["trace[343860472] 'agreement among raft nodes before linearized reading' (duration: 186.691856ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:33.4974Z","caller":"traceutil/trace.go:171","msg":"trace[1885169344] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"488.06211ms","start":"2026-02-09T18:56:33.009312Z","end":"2026-02-09T18:56:33.497374Z","steps":["trace[1885169344] 'process raft request' (duration: 487.684862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:33.497548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:33.009295Z","time spent":"488.186553ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:34.148163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.818642ms","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-09T18:56:34.148226Z","caller":"traceutil/trace.go:171","msg":"trace[316747788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:936; }","duration":"436.908364ms","start":"2026-02-09T18:56:33.711296Z","end":"2026-02-09T18:56:34.148205Z","steps":["trace[316747788] 'range keys from in-memory index tree' (duration: 436.670028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.148253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:33.71128Z","time spent":"436.966405ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:56:34.148542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.053234ms","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-09T18:56:34.148562Z","caller":"traceutil/trace.go:171","msg":"trace[1699757780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:936; }","duration":"180.093174ms","start":"2026-02-09T18:56:33.968462Z","end":"2026-02-09T18:56:34.148555Z","steps":["trace[1699757780] 'range keys from in-memory index tree' (duration: 179.948311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.148676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.360303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-09T18:56:34.148692Z","caller":"traceutil/trace.go:171","msg":"trace[1300880290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"204.398423ms","start":"2026-02-09T18:56:33.944288Z","end":"2026-02-09T18:56:34.148687Z","steps":["trace[1300880290] 'range keys from in-memory index tree' (duration: 204.299701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.148948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.617327ms","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-09T18:56:34.148969Z","caller":"traceutil/trace.go:171","msg":"trace[608520335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"104.659208ms","start":"2026-02-09T18:56:34.044304Z","end":"2026-02-09T18:56:34.148964Z","steps":["trace[608520335] 'range keys from in-memory index tree' (duration: 104.556516ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:34.531182Z","caller":"traceutil/trace.go:171","msg":"trace[186587019] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"325.764517ms","start":"2026-02-09T18:56:34.205396Z","end":"2026-02-09T18:56:34.53116Z","steps":["trace[186587019] 'process raft request' (duration: 325.643724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.531322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:34.205379Z","time spent":"325.870739ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:34.677841Z","caller":"traceutil/trace.go:171","msg":"trace[2138215545] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:975; }","duration":"363.430743ms","start":"2026-02-09T18:56:34.314397Z","end":"2026-02-09T18:56:34.677828Z","steps":["trace[2138215545] 'read index received' (duration: 216.473921ms)","trace[2138215545] 'applied index is now lower than readState.Index' (duration: 146.956212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:34.677919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.509695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:34.677934Z","caller":"traceutil/trace.go:171","msg":"trace[491060575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"363.559166ms","start":"2026-02-09T18:56:34.31437Z","end":"2026-02-09T18:56:34.67793Z","steps":["trace[491060575] 'agreement among raft nodes before linearized reading' (duration: 363.509895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.67795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:34.314356Z","time spent":"363.589786ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:56:34.678112Z","caller":"traceutil/trace.go:171","msg":"trace[496513598] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"460.961887ms","start":"2026-02-09T18:56:34.217145Z","end":"2026-02-09T18:56:34.678107Z","steps":["trace[496513598] 'process raft request' (duration: 460.381474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.678155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:34.217131Z","time spent":"460.992618ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:34.678219Z","caller":"traceutil/trace.go:171","msg":"trace[1568378950] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"460.620139ms","start":"2026-02-09T18:56:34.217593Z","end":"2026-02-09T18:56:34.678213Z","steps":["trace[1568378950] 'process raft request' (duration: 460.170569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.67824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:34.217585Z","time spent":"460.64304ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:34.678297Z","caller":"traceutil/trace.go:171","msg":"trace[1517965101] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"454.540179ms","start":"2026-02-09T18:56:34.223753Z","end":"2026-02-09T18:56:34.678293Z","steps":["trace[1517965101] 'process raft request' (duration: 454.058098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:34.678318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:34.223746Z","time spent":"454.559291ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:35.525808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.309396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T18:56:35.526141Z","caller":"traceutil/trace.go:171","msg":"trace[1168148643] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"621.517892ms","start":"2026-02-09T18:56:34.904607Z","end":"2026-02-09T18:56:35.526125Z","steps":["trace[1168148643] 'process raft request' (duration: 621.286516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:35.526264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:34.904591Z","time spent":"621.619634ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:35.711185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.230165ms","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-09T18:56:35.711257Z","caller":"traceutil/trace.go:171","msg":"trace[60017148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"209.330617ms","start":"2026-02-09T18:56:35.501908Z","end":"2026-02-09T18:56:35.711239Z","steps":["trace[60017148] 'agreement among raft nodes before linearized reading' (duration: 24.202187ms)","trace[60017148] 'range keys from in-memory index tree' (duration: 184.954256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:36.162304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.733822ms","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-09T18:56:36.162367Z","caller":"traceutil/trace.go:171","msg":"trace[1995075995] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:942; }","duration":"324.830335ms","start":"2026-02-09T18:56:35.83752Z","end":"2026-02-09T18:56:36.16235Z","steps":["trace[1995075995] 'count revisions from in-memory index tree' (duration: 324.664301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:36.162397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:35.837506Z","time spent":"324.884476ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:56:36.810893Z","caller":"traceutil/trace.go:171","msg":"trace[912266572] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"109.51422ms","start":"2026-02-09T18:56:36.701357Z","end":"2026-02-09T18:56:36.810872Z","steps":["trace[912266572] 'process raft request' (duration: 109.447368ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:36.811257Z","caller":"traceutil/trace.go:171","msg":"trace[1117285475] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"109.890188ms","start":"2026-02-09T18:56:36.701357Z","end":"2026-02-09T18:56:36.811248Z","steps":["trace[1117285475] 'process raft request' (duration: 109.315895ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:36.815071Z","caller":"traceutil/trace.go:171","msg":"trace[335694429] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"109.491669ms","start":"2026-02-09T18:56:36.705566Z","end":"2026-02-09T18:56:36.815058Z","steps":["trace[335694429] 'process raft request' (duration: 109.418047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:37.378678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.698811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:56:37.37875Z","caller":"traceutil/trace.go:171","msg":"trace[291110557] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:946; }","duration":"410.825053ms","start":"2026-02-09T18:56:36.96791Z","end":"2026-02-09T18:56:37.378735Z","steps":["trace[291110557] 'count revisions from in-memory index tree' (duration: 410.631579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:37.378788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:36.96789Z","time spent":"410.888615ms","remote":"127.0.0.1:55088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:56:38.041382Z","caller":"traceutil/trace.go:171","msg":"trace[610549193] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"223.516983ms","start":"2026-02-09T18:56:37.817842Z","end":"2026-02-09T18:56:38.041359Z","steps":["trace[610549193] 'process raft request' (duration: 223.39374ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:40.19074Z","caller":"traceutil/trace.go:171","msg":"trace[1784100968] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"145.880147ms","start":"2026-02-09T18:56:40.044835Z","end":"2026-02-09T18:56:40.190715Z","steps":["trace[1784100968] 'read index received' (duration: 145.750024ms)","trace[1784100968] 'applied index is now lower than readState.Index' (duration: 129.003µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:40.190924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.070641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T18:56:40.190956Z","caller":"traceutil/trace.go:171","msg":"trace[1062344801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"146.158563ms","start":"2026-02-09T18:56:40.044788Z","end":"2026-02-09T18:56:40.190947Z","steps":["trace[1062344801] 'agreement among raft nodes before linearized reading' (duration: 146.01306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:40.191154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:39.752527Z","time spent":"438.624484ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-09T18:56:40.360172Z","caller":"traceutil/trace.go:171","msg":"trace[397628510] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"167.966495ms","start":"2026-02-09T18:56:40.192187Z","end":"2026-02-09T18:56:40.360154Z","steps":["trace[397628510] 'process raft request' (duration: 151.499786ms)","trace[397628510] 'compare' (duration: 16.160152ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:40.361002Z","caller":"traceutil/trace.go:171","msg":"trace[609836732] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"165.755433ms","start":"2026-02-09T18:56:40.195238Z","end":"2026-02-09T18:56:40.360994Z","steps":["trace[609836732] 'process raft request' (duration: 165.553378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:43.239862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.127821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-588bc\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-09T18:56:43.239948Z","caller":"traceutil/trace.go:171","msg":"trace[763130698] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-588bc; range_end:; response_count:1; response_revision:980; }","duration":"202.255474ms","start":"2026-02-09T18:56:43.037672Z","end":"2026-02-09T18:56:43.239928Z","steps":["trace[763130698] 'range keys from in-memory index tree' (duration: 201.924077ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:54.216599Z","caller":"traceutil/trace.go:171","msg":"trace[1432004339] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"243.012745ms","start":"2026-02-09T18:56:53.973563Z","end":"2026-02-09T18:56:54.216575Z","steps":["trace[1432004339] 'process raft request' (duration: 242.671007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:54.814834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021258186074895,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:56:54.832313Z","caller":"traceutil/trace.go:171","msg":"trace[595480679] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"517.73095ms","start":"2026-02-09T18:56:54.31456Z","end":"2026-02-09T18:56:54.832291Z","steps":["trace[595480679] 'read index received' (duration: 515.98937ms)","trace[595480679] 'applied index is now lower than readState.Index' (duration: 1.74046ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:54.832627Z","caller":"traceutil/trace.go:171","msg":"trace[884762886] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"611.048443ms","start":"2026-02-09T18:56:54.221564Z","end":"2026-02-09T18:56:54.832612Z","steps":["trace[884762886] 'process raft request' (duration: 609.069727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:54.832831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:54.221548Z","time spent":"611.109564ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:54.833053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.489508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:54.833088Z","caller":"traceutil/trace.go:171","msg":"trace[913764351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"518.554969ms","start":"2026-02-09T18:56:54.314521Z","end":"2026-02-09T18:56:54.833076Z","steps":["trace[913764351] 'agreement among raft nodes before linearized reading' (duration: 518.502848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:54.833115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:54.314504Z","time spent":"518.60611ms","remote":"127.0.0.1:54908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:56:54.833417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.801662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-09T18:56:54.833451Z","caller":"traceutil/trace.go:171","msg":"trace[264902936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"403.866494ms","start":"2026-02-09T18:56:54.429575Z","end":"2026-02-09T18:56:54.833441Z","steps":["trace[264902936] 'agreement among raft nodes before linearized reading' (duration: 403.746771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:54.833484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:54.429557Z","time spent":"403.920736ms","remote":"127.0.0.1:55174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:56:54.833769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.984991ms","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-09T18:56:54.833807Z","caller":"traceutil/trace.go:171","msg":"trace[1740182479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"193.064323ms","start":"2026-02-09T18:56:54.640734Z","end":"2026-02-09T18:56:54.833798Z","steps":["trace[1740182479] 'agreement among raft nodes before linearized reading' (duration: 192.973811ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:58:19.457739Z","caller":"traceutil/trace.go:171","msg":"trace[932510759] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"139.178937ms","start":"2026-02-09T18:58:19.318535Z","end":"2026-02-09T18:58:19.457714Z","steps":["trace[932510759] 'process raft request' (duration: 104.670809ms)","trace[932510759] 'compare' (duration: 34.356064ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:58:19.457805Z","caller":"traceutil/trace.go:171","msg":"trace[683978186] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"137.772874ms","start":"2026-02-09T18:58:19.319925Z","end":"2026-02-09T18:58:19.457698Z","steps":["trace[683978186] 'read index received' (duration: 12.798976ms)","trace[683978186] 'applied index is now lower than readState.Index' (duration: 124.973428ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:58:19.457889Z","caller":"traceutil/trace.go:171","msg":"trace[329263599] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"137.784874ms","start":"2026-02-09T18:58:19.320098Z","end":"2026-02-09T18:58:19.457883Z","steps":["trace[329263599] 'process raft request' (duration: 137.562389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:58:19.458011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.074571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:58:19.458027Z","caller":"traceutil/trace.go:171","msg":"trace[529273808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1404; }","duration":"138.122083ms","start":"2026-02-09T18:58:19.3199Z","end":"2026-02-09T18:58:19.458022Z","steps":["trace[529273808] 'agreement among raft nodes before linearized reading' (duration: 138.071542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T19:00:00.614232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.018559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T19:00:00.615072Z","caller":"traceutil/trace.go:171","msg":"trace[1324022483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1843; }","duration":"299.592542ms","start":"2026-02-09T19:00:00.315156Z","end":"2026-02-09T19:00:00.614748Z","steps":["trace[1324022483] 'range keys from in-memory index tree' (duration: 298.942897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T19:00:00.616687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.806503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-09T19:00:00.616772Z","caller":"traceutil/trace.go:171","msg":"trace[1097231393] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1843; }","duration":"137.928785ms","start":"2026-02-09T19:00:00.47882Z","end":"2026-02-09T19:00:00.616749Z","steps":["trace[1097231393] 'range keys from in-memory index tree' (duration: 137.611828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T19:00:07.756487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.652069ms","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-09T19:00:07.756606Z","caller":"traceutil/trace.go:171","msg":"trace[1601969746] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1874; }","duration":"124.779842ms","start":"2026-02-09T19:00:07.631813Z","end":"2026-02-09T19:00:07.756593Z","steps":["trace[1601969746] 'range keys from in-memory index tree' (duration: 124.639079ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T19:00:08.231069Z","caller":"traceutil/trace.go:171","msg":"trace[1796169832] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"151.394021ms","start":"2026-02-09T19:00:08.079649Z","end":"2026-02-09T19:00:08.231043Z","steps":["trace[1796169832] 'process raft request' (duration: 150.373068ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T19:00:09.538407Z","caller":"traceutil/trace.go:171","msg":"trace[840901097] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"113.108642ms","start":"2026-02-09T19:00:09.425266Z","end":"2026-02-09T19:00:09.538375Z","steps":["trace[840901097] 'process raft request' (duration: 112.62905ms)"],"step_count":1}