{"level":"warn","ts":"2026-02-03T10:24:37.852215Z","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-03T10:24:37.852316Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-03T10:24:37.852548Z","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-03T10:24:37.852563Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-03T10:24:37.852611Z","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-03T10:24:37.853244Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-02-03T10:24:37.85335Z","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://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-03T10:24:37.884091Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"30.460823ms"} {"level":"info","ts":"2026-02-03T10:24:37.937033Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-02-03T10:24:37.93713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T10:24:37.937152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-02-03T10:24:37.93716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T10:24:37.937173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-02-03T10:24:37.937237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-02-03T10:24:37.975149Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T10:24:37.989467Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T10:24:38.003171Z","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-03T10:24:38.008249Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T10:24:38.012938Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T10:24:38.013132Z","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-03T10:24:38.013165Z","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-03T10:24:38.013173Z","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-03T10:24:38.015376Z","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-03T10:24:38.015534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T10:24:38.01556Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T10:24:38.015647Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-03T10:24:38.015671Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-03T10:24:38.027645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-02-03T10:24:38.027763Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-03T10:24:38.137846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T10:24:38.137977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T10:24:38.138042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-02-03T10:24:38.138078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-02-03T10:24:38.138107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-03T10:24:38.138137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-02-03T10:24:38.138166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-03T10:24:38.139671Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T10:24:38.139728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:24:38.140164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:24:38.140266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:24:38.141976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-02-03T10:24:38.142251Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T10:24:38.143968Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T10:24:38.145764Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:24:38.147989Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:24:38.148013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:24:38.148138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T10:24:55.812816Z","caller":"traceutil/trace.go:171","msg":"trace[1155111349] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"218.206718ms","start":"2026-02-03T10:24:55.594594Z","end":"2026-02-03T10:24:55.8128Z","steps":["trace[1155111349] 'process raft request' (duration: 218.118197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:24:56.420605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184073702225044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:24:56.921266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184073702225044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:24:57.102683Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.280678967s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:24:57.113683Z","caller":"traceutil/trace.go:171","msg":"trace[16397547] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.291860637s","start":"2026-02-03T10:24:55.821771Z","end":"2026-02-03T10:24:57.113631Z","steps":["trace[16397547] 'process raft request' (duration: 1.281047944s)","trace[16397547] 'compare' (duration: 10.604059ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:24:57.114396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:24:55.821752Z","time spent":"1.292010631s","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:24:57.131349Z","caller":"traceutil/trace.go:171","msg":"trace[1276492345] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:423; }","duration":"1.211329271s","start":"2026-02-03T10:24:55.920004Z","end":"2026-02-03T10:24:57.131333Z","steps":["trace[1276492345] 'read index received' (duration: 1.182820745s)","trace[1276492345] 'applied index is now lower than readState.Index' (duration: 28.507726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:24:57.131476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211455263s","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-03T10:24:57.131504Z","caller":"traceutil/trace.go:171","msg":"trace[1880242742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"1.211532564s","start":"2026-02-03T10:24:55.919965Z","end":"2026-02-03T10:24:57.131498Z","steps":["trace[1880242742] 'agreement among raft nodes before linearized reading' (duration: 1.211461753s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:24:57.131526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:24:55.919904Z","time spent":"1.211615246s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:24:57.13173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.755971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:24:57.131744Z","caller":"traceutil/trace.go:171","msg":"trace[1646360997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"293.792621ms","start":"2026-02-03T10:24:56.837947Z","end":"2026-02-03T10:24:57.13174Z","steps":["trace[1646360997] 'agreement among raft nodes before linearized reading' (duration: 293.768651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:24:57.131752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.937821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-03T10:24:57.131827Z","caller":"traceutil/trace.go:171","msg":"trace[647495276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"982.017594ms","start":"2026-02-03T10:24:56.149787Z","end":"2026-02-03T10:24:57.131804Z","steps":["trace[647495276] 'agreement among raft nodes before linearized reading' (duration: 981.89944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:24:57.131857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.631528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-03T10:24:57.131867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:24:56.149709Z","time spent":"982.149626ms","remote":"127.0.0.1:45354","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-03T10:24:57.131883Z","caller":"traceutil/trace.go:171","msg":"trace[1593260462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"979.648429ms","start":"2026-02-03T10:24:56.152219Z","end":"2026-02-03T10:24:57.131868Z","steps":["trace[1593260462] 'agreement among raft nodes before linearized reading' (duration: 979.616908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:24:57.131901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:24:56.152187Z","time spent":"979.710899ms","remote":"127.0.0.1:45354","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-03T10:25:08.592568Z","caller":"traceutil/trace.go:171","msg":"trace[1670332423] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"534.518556ms","start":"2026-02-03T10:25:08.058033Z","end":"2026-02-03T10:25:08.592552Z","steps":["trace[1670332423] 'process raft request' (duration: 534.399574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:08.592692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:08.058024Z","time spent":"534.602717ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:11.354498Z","caller":"traceutil/trace.go:171","msg":"trace[1341152208] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"145.807236ms","start":"2026-02-03T10:25:11.208674Z","end":"2026-02-03T10:25:11.354481Z","steps":["trace[1341152208] 'read index received' (duration: 145.659804ms)","trace[1341152208] 'applied index is now lower than readState.Index' (duration: 146.582µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:25:11.354591Z","caller":"traceutil/trace.go:171","msg":"trace[50063077] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"156.227832ms","start":"2026-02-03T10:25:11.198358Z","end":"2026-02-03T10:25:11.354586Z","steps":["trace[50063077] 'process raft request' (duration: 155.998358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:11.354759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.060011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-03T10:25:11.354797Z","caller":"traceutil/trace.go:171","msg":"trace[354561588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"146.126813ms","start":"2026-02-03T10:25:11.208654Z","end":"2026-02-03T10:25:11.354781Z","steps":["trace[354561588] 'agreement among raft nodes before linearized reading' (duration: 146.028221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:11.354878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.021269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-03T10:25:11.354928Z","caller":"traceutil/trace.go:171","msg":"trace[1934745981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"146.08719ms","start":"2026-02-03T10:25:11.20883Z","end":"2026-02-03T10:25:11.354917Z","steps":["trace[1934745981] 'agreement among raft nodes before linearized reading' (duration: 146.002609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:11.76783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.542011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:25:11.768007Z","caller":"traceutil/trace.go:171","msg":"trace[861319343] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"408.647536ms","start":"2026-02-03T10:25:11.359328Z","end":"2026-02-03T10:25:11.767976Z","steps":["trace[861319343] 'process raft request' (duration: 279.691227ms)","trace[861319343] 'compare' (duration: 128.237075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:25:11.768071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:11.359314Z","time spent":"408.725667ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:11.775421Z","caller":"traceutil/trace.go:171","msg":"trace[780408181] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"415.946873ms","start":"2026-02-03T10:25:11.359448Z","end":"2026-02-03T10:25:11.775394Z","steps":["trace[780408181] 'process raft request' (duration: 415.751139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:11.775573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:11.359441Z","time spent":"416.052445ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:11.775731Z","caller":"traceutil/trace.go:171","msg":"trace[1740546179] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:496; }","duration":"344.699211ms","start":"2026-02-03T10:25:11.431015Z","end":"2026-02-03T10:25:11.775714Z","steps":["trace[1740546179] 'read index received' (duration: 208.15303ms)","trace[1740546179] 'applied index is now lower than readState.Index' (duration: 136.545051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:25:11.775853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.832014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:25:11.776072Z","caller":"traceutil/trace.go:171","msg":"trace[1327415985] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"414.7478ms","start":"2026-02-03T10:25:11.361314Z","end":"2026-02-03T10:25:11.776062Z","steps":["trace[1327415985] 'process raft request' (duration: 414.037026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:11.776134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:11.3613Z","time spent":"414.811052ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:11.775889Z","caller":"traceutil/trace.go:171","msg":"trace[1826551346] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:482; }","duration":"344.905566ms","start":"2026-02-03T10:25:11.430974Z","end":"2026-02-03T10:25:11.775879Z","steps":["trace[1826551346] 'agreement among raft nodes before linearized reading' (duration: 344.787694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:11.776346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:11.430952Z","time spent":"345.378404ms","remote":"127.0.0.1:53186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"info","ts":"2026-02-03T10:25:16.132193Z","caller":"traceutil/trace.go:171","msg":"trace[858152037] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"692.322858ms","start":"2026-02-03T10:25:15.439855Z","end":"2026-02-03T10:25:16.132177Z","steps":["trace[858152037] 'process raft request' (duration: 692.235086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:16.132303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:15.439842Z","time spent":"692.401849ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:16.132469Z","caller":"traceutil/trace.go:171","msg":"trace[1924174174] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:511; }","duration":"352.509179ms","start":"2026-02-03T10:25:15.779955Z","end":"2026-02-03T10:25:16.132464Z","steps":["trace[1924174174] 'read index received' (duration: 352.507099ms)","trace[1924174174] 'applied index is now lower than readState.Index' (duration: 1.68µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:25:16.13253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.092047ms","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-03T10:25:16.132547Z","caller":"traceutil/trace.go:171","msg":"trace[1351596266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"346.127747ms","start":"2026-02-03T10:25:15.786414Z","end":"2026-02-03T10:25:16.132542Z","steps":["trace[1351596266] 'agreement among raft nodes before linearized reading' (duration: 346.083726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:16.132562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:15.786404Z","time spent":"346.153559ms","remote":"127.0.0.1:45354","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-03T10:25:16.132739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.784534ms","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-03T10:25:16.132759Z","caller":"traceutil/trace.go:171","msg":"trace[1922054384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"352.865325ms","start":"2026-02-03T10:25:15.779889Z","end":"2026-02-03T10:25:16.132754Z","steps":["trace[1922054384] 'agreement among raft nodes before linearized reading' (duration: 352.835135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:16.132772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:15.779873Z","time spent":"352.896255ms","remote":"127.0.0.1:45354","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-03T10:25:16.132835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.959499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:16.132847Z","caller":"traceutil/trace.go:171","msg":"trace[1750239875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"289.992531ms","start":"2026-02-03T10:25:15.842851Z","end":"2026-02-03T10:25:16.132843Z","steps":["trace[1750239875] 'agreement among raft nodes before linearized reading' (duration: 289.97174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:16.132929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.236754ms","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-03T10:25:16.132941Z","caller":"traceutil/trace.go:171","msg":"trace[1879861033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"342.269344ms","start":"2026-02-03T10:25:15.790669Z","end":"2026-02-03T10:25:16.132938Z","steps":["trace[1879861033] 'agreement among raft nodes before linearized reading' (duration: 342.245544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:16.132953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:15.790658Z","time spent":"342.292046ms","remote":"127.0.0.1:45354","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-03T10:25:16.293763Z","caller":"traceutil/trace.go:171","msg":"trace[2025502468] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"152.839018ms","start":"2026-02-03T10:25:16.140904Z","end":"2026-02-03T10:25:16.293743Z","steps":["trace[2025502468] 'process raft request' (duration: 152.800907ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:25:16.294092Z","caller":"traceutil/trace.go:171","msg":"trace[513555435] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"155.365076ms","start":"2026-02-03T10:25:16.138699Z","end":"2026-02-03T10:25:16.294065Z","steps":["trace[513555435] 'process raft request' (duration: 86.309195ms)","trace[513555435] 'compare' (duration: 68.370418ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:25:16.294241Z","caller":"traceutil/trace.go:171","msg":"trace[1668205463] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"155.405647ms","start":"2026-02-03T10:25:16.138826Z","end":"2026-02-03T10:25:16.294232Z","steps":["trace[1668205463] 'process raft request' (duration: 154.832106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.970204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.547411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:25:18.970383Z","caller":"traceutil/trace.go:171","msg":"trace[1531775366] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"270.525674ms","start":"2026-02-03T10:25:18.699834Z","end":"2026-02-03T10:25:18.97036Z","steps":["trace[1531775366] 'read index received' (duration: 90.730399ms)","trace[1531775366] 'applied index is now lower than readState.Index' (duration: 179.793835ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:25:18.970599Z","caller":"traceutil/trace.go:171","msg":"trace[1638045964] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"271.457502ms","start":"2026-02-03T10:25:18.699119Z","end":"2026-02-03T10:25:18.970577Z","steps":["trace[1638045964] 'process raft request' (duration: 91.394092ms)","trace[1638045964] 'compare' (duration: 179.372157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:25:18.971277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.417162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T10:25:18.971348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.448823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.971377Z","caller":"traceutil/trace.go:171","msg":"trace[268037479] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"271.543214ms","start":"2026-02-03T10:25:18.699801Z","end":"2026-02-03T10:25:18.971344Z","steps":["trace[268037479] 'agreement among raft nodes before linearized reading' (duration: 271.399371ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:25:18.971401Z","caller":"traceutil/trace.go:171","msg":"trace[759103291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"261.514935ms","start":"2026-02-03T10:25:18.709868Z","end":"2026-02-03T10:25:18.971383Z","steps":["trace[759103291] 'agreement among raft nodes before linearized reading' (duration: 261.418984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.971878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.408136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.971913Z","caller":"traceutil/trace.go:171","msg":"trace[1469057895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"129.507688ms","start":"2026-02-03T10:25:18.842397Z","end":"2026-02-03T10:25:18.971905Z","steps":["trace[1469057895] 'agreement among raft nodes before linearized reading' (duration: 129.173562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.972257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.688652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.972291Z","caller":"traceutil/trace.go:171","msg":"trace[1507334441] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"249.762893ms","start":"2026-02-03T10:25:18.722518Z","end":"2026-02-03T10:25:18.972281Z","steps":["trace[1507334441] 'agreement among raft nodes before linearized reading' (duration: 249.699641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.972612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.534821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.972643Z","caller":"traceutil/trace.go:171","msg":"trace[1497022508] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"230.645604ms","start":"2026-02-03T10:25:18.741986Z","end":"2026-02-03T10:25:18.972632Z","steps":["trace[1497022508] 'agreement among raft nodes before linearized reading' (duration: 230.550022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.972786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.145211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.972806Z","caller":"traceutil/trace.go:171","msg":"trace[1814286870] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"250.165621ms","start":"2026-02-03T10:25:18.722634Z","end":"2026-02-03T10:25:18.972799Z","steps":["trace[1814286870] 'agreement among raft nodes before linearized reading' (duration: 250.11737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.972924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.300224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.972944Z","caller":"traceutil/trace.go:171","msg":"trace[533739084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"250.343674ms","start":"2026-02-03T10:25:18.722593Z","end":"2026-02-03T10:25:18.972937Z","steps":["trace[533739084] 'agreement among raft nodes before linearized reading' (duration: 250.296614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.973339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.442345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.973366Z","caller":"traceutil/trace.go:171","msg":"trace[174458250] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"260.585827ms","start":"2026-02-03T10:25:18.712773Z","end":"2026-02-03T10:25:18.973358Z","steps":["trace[174458250] 'agreement among raft nodes before linearized reading' (duration: 260.436494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.973522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.996094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.973548Z","caller":"traceutil/trace.go:171","msg":"trace[575192064] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"253.022054ms","start":"2026-02-03T10:25:18.720517Z","end":"2026-02-03T10:25:18.973539Z","steps":["trace[575192064] 'agreement among raft nodes before linearized reading' (duration: 252.980874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.974092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.289451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.974185Z","caller":"traceutil/trace.go:171","msg":"trace[1386681685] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"261.416443ms","start":"2026-02-03T10:25:18.71276Z","end":"2026-02-03T10:25:18.974176Z","steps":["trace[1386681685] 'agreement among raft nodes before linearized reading' (duration: 261.28109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:18.974819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.059524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:18.976453Z","caller":"traceutil/trace.go:171","msg":"trace[1913453441] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:518; }","duration":"263.705846ms","start":"2026-02-03T10:25:18.712737Z","end":"2026-02-03T10:25:18.976442Z","steps":["trace[1913453441] 'agreement among raft nodes before linearized reading' (duration: 262.050435ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:25:20.749713Z","caller":"traceutil/trace.go:171","msg":"trace[2067637797] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"122.942136ms","start":"2026-02-03T10:25:20.626666Z","end":"2026-02-03T10:25:20.749608Z","steps":["trace[2067637797] 'process raft request' (duration: 122.376215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:34.283161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.985043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:25:34.283226Z","caller":"traceutil/trace.go:171","msg":"trace[1697676123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"442.092665ms","start":"2026-02-03T10:25:33.841119Z","end":"2026-02-03T10:25:34.283211Z","steps":["trace[1697676123] 'range keys from in-memory index tree' (duration: 441.923463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:34.283254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:33.841099Z","time spent":"442.148546ms","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:25:50.810594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.839242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:25:50.810675Z","caller":"traceutil/trace.go:171","msg":"trace[881785193] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"169.450236ms","start":"2026-02-03T10:25:50.641211Z","end":"2026-02-03T10:25:50.810661Z","steps":["trace[881785193] 'process raft request' (duration: 48.464472ms)","trace[881785193] 'compare' (duration: 120.592917ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:25:51.631664Z","caller":"traceutil/trace.go:171","msg":"trace[1684134174] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"199.608543ms","start":"2026-02-03T10:25:51.43204Z","end":"2026-02-03T10:25:51.631648Z","steps":["trace[1684134174] 'read index received' (duration: 199.513122ms)","trace[1684134174] 'applied index is now lower than readState.Index' (duration: 94.621µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:25:51.631743Z","caller":"traceutil/trace.go:171","msg":"trace[1937164960] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"267.600323ms","start":"2026-02-03T10:25:51.364138Z","end":"2026-02-03T10:25:51.631738Z","steps":["trace[1937164960] 'process raft request' (duration: 267.41048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:51.631882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.836077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:25:51.631898Z","caller":"traceutil/trace.go:171","msg":"trace[617862080] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:734; }","duration":"199.884449ms","start":"2026-02-03T10:25:51.432008Z","end":"2026-02-03T10:25:51.631892Z","steps":["trace[617862080] 'agreement among raft nodes before linearized reading' (duration: 199.807557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:51.632047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.402953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:25:51.632061Z","caller":"traceutil/trace.go:171","msg":"trace[521480855] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:734; }","duration":"163.444204ms","start":"2026-02-03T10:25:51.468613Z","end":"2026-02-03T10:25:51.632057Z","steps":["trace[521480855] 'agreement among raft nodes before linearized reading' (duration: 163.408753ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:25:54.88871Z","caller":"traceutil/trace.go:171","msg":"trace[1865972057] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"287.705971ms","start":"2026-02-03T10:25:54.600987Z","end":"2026-02-03T10:25:54.888693Z","steps":["trace[1865972057] 'read index received' (duration: 287.582518ms)","trace[1865972057] 'applied index is now lower than readState.Index' (duration: 122.633µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:25:54.8888Z","caller":"traceutil/trace.go:171","msg":"trace[1914447537] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"292.43772ms","start":"2026-02-03T10:25:54.596355Z","end":"2026-02-03T10:25:54.888793Z","steps":["trace[1914447537] 'process raft request' (duration: 292.229895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:54.888964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.962786ms","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-03T10:25:54.888987Z","caller":"traceutil/trace.go:171","msg":"trace[1279603310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"288.010287ms","start":"2026-02-03T10:25:54.60097Z","end":"2026-02-03T10:25:54.88898Z","steps":["trace[1279603310] 'agreement among raft nodes before linearized reading' (duration: 287.919586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:55.451164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.326795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:25:55.451303Z","caller":"traceutil/trace.go:171","msg":"trace[1857815444] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:770; }","duration":"332.059465ms","start":"2026-02-03T10:25:55.11923Z","end":"2026-02-03T10:25:55.45129Z","steps":["trace[1857815444] 'read index received' (duration: 289.968003ms)","trace[1857815444] 'applied index is now lower than readState.Index' (duration: 42.090692ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:25:55.4514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.168457ms","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-03T10:25:55.451417Z","caller":"traceutil/trace.go:171","msg":"trace[2147006407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"332.208697ms","start":"2026-02-03T10:25:55.119202Z","end":"2026-02-03T10:25:55.451411Z","steps":["trace[2147006407] 'agreement among raft nodes before linearized reading' (duration: 332.120545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:55.451438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:55.119189Z","time spent":"332.244058ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-03T10:25:55.451594Z","caller":"traceutil/trace.go:171","msg":"trace[799816004] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"558.211389ms","start":"2026-02-03T10:25:54.893374Z","end":"2026-02-03T10:25:55.451586Z","steps":["trace[799816004] 'process raft request' (duration: 221.396744ms)","trace[799816004] 'compare' (duration: 336.03883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:25:55.451629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:54.893358Z","time spent":"558.25304ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:55.451703Z","caller":"traceutil/trace.go:171","msg":"trace[1253841340] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"557.164439ms","start":"2026-02-03T10:25:54.894532Z","end":"2026-02-03T10:25:55.451696Z","steps":["trace[1253841340] 'process raft request' (duration: 556.70333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:55.451733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:54.894509Z","time spent":"557.20696ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:55.451801Z","caller":"traceutil/trace.go:171","msg":"trace[2088021555] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"409.501022ms","start":"2026-02-03T10:25:55.042295Z","end":"2026-02-03T10:25:55.451796Z","steps":["trace[2088021555] 'process raft request' (duration: 408.973681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:55.451828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:55.042274Z","time spent":"409.539302ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:25:59.155138Z","caller":"traceutil/trace.go:171","msg":"trace[1129511662] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"238.013866ms","start":"2026-02-03T10:25:58.917097Z","end":"2026-02-03T10:25:59.155111Z","steps":["trace[1129511662] 'process raft request' (duration: 237.535126ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:25:59.987553Z","caller":"traceutil/trace.go:171","msg":"trace[1406221361] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"430.74451ms","start":"2026-02-03T10:25:59.556774Z","end":"2026-02-03T10:25:59.987518Z","steps":["trace[1406221361] 'process raft request' (duration: 430.519586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:25:59.987814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:59.556754Z","time spent":"430.906114ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:00.028154Z","caller":"traceutil/trace.go:171","msg":"trace[1823693529] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"470.974276ms","start":"2026-02-03T10:25:59.557035Z","end":"2026-02-03T10:26:00.02801Z","steps":["trace[1823693529] 'process raft request' (duration: 470.555129ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:00.0291Z","caller":"traceutil/trace.go:171","msg":"trace[1777015996] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"266.077044ms","start":"2026-02-03T10:25:59.762975Z","end":"2026-02-03T10:26:00.029053Z","steps":["trace[1777015996] 'read index received' (duration: 224.603264ms)","trace[1777015996] 'applied index is now lower than readState.Index' (duration: 41.4725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:00.029647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:59.557025Z","time spent":"471.524328ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:00.02973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.750776ms","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-03T10:26:00.029991Z","caller":"traceutil/trace.go:171","msg":"trace[254957785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"267.055083ms","start":"2026-02-03T10:25:59.762917Z","end":"2026-02-03T10:26:00.029972Z","steps":["trace[254957785] 'agreement among raft nodes before linearized reading' (duration: 266.326599ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:00.030289Z","caller":"traceutil/trace.go:171","msg":"trace[1753711539] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"471.858773ms","start":"2026-02-03T10:25:59.558416Z","end":"2026-02-03T10:26:00.030275Z","steps":["trace[1753711539] 'process raft request' (duration: 469.420027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:00.031264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:25:59.558406Z","time spent":"472.813861ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:00.261832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.479103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:00.261985Z","caller":"traceutil/trace.go:171","msg":"trace[1778783288] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"225.407299ms","start":"2026-02-03T10:26:00.036551Z","end":"2026-02-03T10:26:00.261958Z","steps":["trace[1778783288] 'process raft request' (duration: 26.668311ms)","trace[1778783288] 'compare' (duration: 198.071156ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:10.203067Z","caller":"traceutil/trace.go:171","msg":"trace[1538876252] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"109.413647ms","start":"2026-02-03T10:26:10.093621Z","end":"2026-02-03T10:26:10.203034Z","steps":["trace[1538876252] 'process raft request' (duration: 77.993887ms)","trace[1538876252] 'compare' (duration: 31.298148ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:10.203724Z","caller":"traceutil/trace.go:171","msg":"trace[1205983196] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"108.718534ms","start":"2026-02-03T10:26:10.094999Z","end":"2026-02-03T10:26:10.203717Z","steps":["trace[1205983196] 'process raft request' (duration: 108.686244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.507135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287426173s","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-03T10:26:15.507229Z","caller":"traceutil/trace.go:171","msg":"trace[424889851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"1.287569825s","start":"2026-02-03T10:26:14.219628Z","end":"2026-02-03T10:26:15.507197Z","steps":["trace[424889851] 'range keys from in-memory index tree' (duration: 1.28723321s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.507259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.219603Z","time spent":"1.287649927s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T10:26:15.507409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250783174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-03T10:26:15.507423Z","caller":"traceutil/trace.go:171","msg":"trace[1826219403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:846; }","duration":"1.250844665s","start":"2026-02-03T10:26:14.256572Z","end":"2026-02-03T10:26:15.507417Z","steps":["trace[1826219403] 'range keys from in-memory index tree' (duration: 1.250695902s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.507436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.256548Z","time spent":"1.250884116s","remote":"127.0.0.1:45354","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-03T10:26:15.507512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130258096s","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-03T10:26:15.507524Z","caller":"traceutil/trace.go:171","msg":"trace[386049365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:846; }","duration":"1.130312367s","start":"2026-02-03T10:26:14.377208Z","end":"2026-02-03T10:26:15.50752Z","steps":["trace[386049365] 'range keys from in-memory index tree' (duration: 1.130162925s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.507537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.377185Z","time spent":"1.130348647s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:26:15.507693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.324967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:15.507709Z","caller":"traceutil/trace.go:171","msg":"trace[1751009129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"669.370699ms","start":"2026-02-03T10:26:14.838332Z","end":"2026-02-03T10:26:15.507702Z","steps":["trace[1751009129] 'range keys from in-memory index tree' (duration: 669.240466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.507724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.838309Z","time spent":"669.411249ms","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:26:15.507871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.116784ms","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-03T10:26:15.507885Z","caller":"traceutil/trace.go:171","msg":"trace[364048883] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:846; }","duration":"610.179776ms","start":"2026-02-03T10:26:14.897701Z","end":"2026-02-03T10:26:15.50788Z","steps":["trace[364048883] 'count revisions from in-memory index tree' (duration: 610.048903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:15.507902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:14.897673Z","time spent":"610.224417ms","remote":"127.0.0.1:45360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:26:15.508127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.643662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T10:26:15.508142Z","caller":"traceutil/trace.go:171","msg":"trace[628186721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:846; }","duration":"243.687672ms","start":"2026-02-03T10:26:15.264449Z","end":"2026-02-03T10:26:15.508137Z","steps":["trace[628186721] 'range keys from in-memory index tree' (duration: 243.562571ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.761202Z","caller":"traceutil/trace.go:171","msg":"trace[1531510065] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"246.989735ms","start":"2026-02-03T10:26:15.514192Z","end":"2026-02-03T10:26:15.761182Z","steps":["trace[1531510065] 'process raft request' (duration: 246.553337ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.761463Z","caller":"traceutil/trace.go:171","msg":"trace[515388703] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"247.216209ms","start":"2026-02-03T10:26:15.514241Z","end":"2026-02-03T10:26:15.761457Z","steps":["trace[515388703] 'process raft request' (duration: 246.686319ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:15.763025Z","caller":"traceutil/trace.go:171","msg":"trace[343496800] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"246.541447ms","start":"2026-02-03T10:26:15.515115Z","end":"2026-02-03T10:26:15.761656Z","steps":["trace[343496800] 'process raft request' (duration: 245.966577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.598738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.968932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:16.598831Z","caller":"traceutil/trace.go:171","msg":"trace[1908540577] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"474.664757ms","start":"2026-02-03T10:26:16.12415Z","end":"2026-02-03T10:26:16.598815Z","steps":["trace[1908540577] 'read index received' (duration: 50.911µs)","trace[1908540577] 'applied index is now lower than readState.Index' (duration: 474.613036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:16.598967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.81848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-03T10:26:16.598984Z","caller":"traceutil/trace.go:171","msg":"trace[453535804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:851; }","duration":"474.87994ms","start":"2026-02-03T10:26:16.124097Z","end":"2026-02-03T10:26:16.598977Z","steps":["trace[453535804] 'agreement among raft nodes before linearized reading' (duration: 474.752478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:16.599005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:16.124071Z","time spent":"474.928262ms","remote":"127.0.0.1:45354","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-03T10:26:16.59918Z","caller":"traceutil/trace.go:171","msg":"trace[2010135221] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"757.727491ms","start":"2026-02-03T10:26:15.841446Z","end":"2026-02-03T10:26:16.599174Z","steps":["trace[2010135221] 'process raft request' (duration: 64.214048ms)","trace[2010135221] 'compare' (duration: 692.606166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:16.59921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:15.841427Z","time spent":"757.767702ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:17.707793Z","caller":"traceutil/trace.go:171","msg":"trace[1274002215] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"154.857482ms","start":"2026-02-03T10:26:17.55291Z","end":"2026-02-03T10:26:17.707767Z","steps":["trace[1274002215] 'process raft request' (duration: 154.720879ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:19.887503Z","caller":"traceutil/trace.go:171","msg":"trace[2044640938] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"123.057614ms","start":"2026-02-03T10:26:19.764423Z","end":"2026-02-03T10:26:19.887481Z","steps":["trace[2044640938] 'read index received' (duration: 122.84134ms)","trace[2044640938] 'applied index is now lower than readState.Index' (duration: 215.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:19.887815Z","caller":"traceutil/trace.go:171","msg":"trace[2125356420] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"171.921332ms","start":"2026-02-03T10:26:19.715882Z","end":"2026-02-03T10:26:19.887803Z","steps":["trace[2125356420] 'process raft request' (duration: 171.449924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:19.888087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.637545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:26:19.888122Z","caller":"traceutil/trace.go:171","msg":"trace[1964385794] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:858; }","duration":"123.734958ms","start":"2026-02-03T10:26:19.764377Z","end":"2026-02-03T10:26:19.888112Z","steps":["trace[1964385794] 'agreement among raft nodes before linearized reading' (duration: 123.647516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:20.390399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.621745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:20.390514Z","caller":"traceutil/trace.go:171","msg":"trace[1696917971] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"495.329895ms","start":"2026-02-03T10:26:19.895162Z","end":"2026-02-03T10:26:20.390492Z","steps":["trace[1696917971] 'process raft request' (duration: 363.511936ms)","trace[1696917971] 'compare' (duration: 131.468553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:20.390571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.895139Z","time spent":"495.404296ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:21.203016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.734074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:21.203162Z","caller":"traceutil/trace.go:171","msg":"trace[309296256] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:894; }","duration":"513.047309ms","start":"2026-02-03T10:26:20.690096Z","end":"2026-02-03T10:26:21.203143Z","steps":["trace[309296256] 'read index received' (duration: 84.03254ms)","trace[309296256] 'applied index is now lower than readState.Index' (duration: 429.013959ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:21.203338Z","caller":"traceutil/trace.go:171","msg":"trace[951079358] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.307798936s","start":"2026-02-03T10:26:19.895528Z","end":"2026-02-03T10:26:21.203327Z","steps":["trace[951079358] 'process raft request' (duration: 878.658514ms)","trace[951079358] 'compare' (duration: 428.566311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:21.203376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.895515Z","time spent":"1.307841886s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:21.203501Z","caller":"traceutil/trace.go:171","msg":"trace[776481058] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"1.307512061s","start":"2026-02-03T10:26:19.895981Z","end":"2026-02-03T10:26:21.203493Z","steps":["trace[776481058] 'process raft request' (duration: 1.307120363s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:21.20353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:19.895966Z","time spent":"1.307545151s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:26:21.203663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.574778ms","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-03T10:26:21.203677Z","caller":"traceutil/trace.go:171","msg":"trace[1571845946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"513.62665ms","start":"2026-02-03T10:26:20.690045Z","end":"2026-02-03T10:26:21.203672Z","steps":["trace[1571845946] 'agreement among raft nodes before linearized reading' (duration: 513.539818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:21.203692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.690022Z","time spent":"513.667241ms","remote":"127.0.0.1:45354","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-03T10:26:21.203768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.861183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:21.20378Z","caller":"traceutil/trace.go:171","msg":"trace[1475836746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"363.907674ms","start":"2026-02-03T10:26:20.839868Z","end":"2026-02-03T10:26:21.203776Z","steps":["trace[1475836746] 'agreement among raft nodes before linearized reading' (duration: 363.887713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:21.203791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:20.839845Z","time spent":"363.943845ms","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T10:26:21.588822Z","caller":"traceutil/trace.go:171","msg":"trace[2005074433] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"155.289371ms","start":"2026-02-03T10:26:21.433502Z","end":"2026-02-03T10:26:21.588792Z","steps":["trace[2005074433] 'read index received' (duration: 119.514319ms)","trace[2005074433] 'applied index is now lower than readState.Index' (duration: 35.774042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:21.58907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.544965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:26:21.58912Z","caller":"traceutil/trace.go:171","msg":"trace[711934089] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:862; }","duration":"155.667537ms","start":"2026-02-03T10:26:21.433442Z","end":"2026-02-03T10:26:21.589109Z","steps":["trace[711934089] 'agreement among raft nodes before linearized reading' (duration: 155.457464ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:21.589419Z","caller":"traceutil/trace.go:171","msg":"trace[1223822139] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"380.472746ms","start":"2026-02-03T10:26:21.208932Z","end":"2026-02-03T10:26:21.589405Z","steps":["trace[1223822139] 'process raft request' (duration: 344.171823ms)","trace[1223822139] 'compare' (duration: 35.564498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:21.589545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:21.208919Z","time spent":"380.533757ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:26:22.601869Z","caller":"traceutil/trace.go:171","msg":"trace[1120053284] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"205.589227ms","start":"2026-02-03T10:26:22.396254Z","end":"2026-02-03T10:26:22.601843Z","steps":["trace[1120053284] 'process raft request' (duration: 205.445144ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:26.134391Z","caller":"traceutil/trace.go:171","msg":"trace[1059770686] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"156.759168ms","start":"2026-02-03T10:26:25.977603Z","end":"2026-02-03T10:26:26.134363Z","steps":["trace[1059770686] 'process raft request' (duration: 156.586495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.84357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.299629ms","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-03T10:26:32.843653Z","caller":"traceutil/trace.go:171","msg":"trace[2046266530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"165.533734ms","start":"2026-02-03T10:26:32.678103Z","end":"2026-02-03T10:26:32.843636Z","steps":["trace[2046266530] 'range keys from in-memory index tree' (duration: 165.110554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:32.84375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.242067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:32.843767Z","caller":"traceutil/trace.go:171","msg":"trace[1450456297] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:899; }","duration":"165.278489ms","start":"2026-02-03T10:26:32.678481Z","end":"2026-02-03T10:26:32.843759Z","steps":["trace[1450456297] 'count revisions from in-memory index tree' (duration: 165.206257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:33.084341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.762624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:26:33.084505Z","caller":"traceutil/trace.go:171","msg":"trace[1615559193] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"217.152454ms","start":"2026-02-03T10:26:32.867337Z","end":"2026-02-03T10:26:33.08449Z","steps":["trace[1615559193] 'process raft request' (duration: 217.108014ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:33.084751Z","caller":"traceutil/trace.go:171","msg":"trace[177773540] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"231.641427ms","start":"2026-02-03T10:26:32.853099Z","end":"2026-02-03T10:26:33.08474Z","steps":["trace[177773540] 'process raft request' (duration: 121.395153ms)","trace[177773540] 'compare' (duration: 109.657322ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:33.08484Z","caller":"traceutil/trace.go:171","msg":"trace[1091015692] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"217.559062ms","start":"2026-02-03T10:26:32.867275Z","end":"2026-02-03T10:26:33.084834Z","steps":["trace[1091015692] 'read index received' (duration: 107.137765ms)","trace[1091015692] 'applied index is now lower than readState.Index' (duration: 110.420677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:33.084952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.674134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:33.084972Z","caller":"traceutil/trace.go:171","msg":"trace[1583753652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"217.717595ms","start":"2026-02-03T10:26:32.867246Z","end":"2026-02-03T10:26:33.084963Z","steps":["trace[1583753652] 'agreement among raft nodes before linearized reading' (duration: 217.679794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:33.569618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.683299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:26:33.569717Z","caller":"traceutil/trace.go:171","msg":"trace[1474104366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"246.77388ms","start":"2026-02-03T10:26:33.3229Z","end":"2026-02-03T10:26:33.569674Z","steps":["trace[1474104366] 'range keys from in-memory index tree' (duration: 246.486466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:33.569906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.616219ms","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-03T10:26:33.56992Z","caller":"traceutil/trace.go:171","msg":"trace[1346601314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"203.67031ms","start":"2026-02-03T10:26:33.366244Z","end":"2026-02-03T10:26:33.569915Z","steps":["trace[1346601314] 'range keys from in-memory index tree' (duration: 203.517157ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:33.737652Z","caller":"traceutil/trace.go:171","msg":"trace[80472991] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"156.282808ms","start":"2026-02-03T10:26:33.581343Z","end":"2026-02-03T10:26:33.737626Z","steps":["trace[80472991] 'process raft request' (duration: 100.477719ms)","trace[80472991] 'compare' (duration: 55.361701ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:46.055584Z","caller":"traceutil/trace.go:171","msg":"trace[1696561955] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"138.084867ms","start":"2026-02-03T10:26:45.917475Z","end":"2026-02-03T10:26:46.05556Z","steps":["trace[1696561955] 'process raft request' (duration: 137.18666ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:46.189515Z","caller":"traceutil/trace.go:171","msg":"trace[2115826802] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"119.419056ms","start":"2026-02-03T10:26:46.070043Z","end":"2026-02-03T10:26:46.189462Z","steps":["trace[2115826802] 'process raft request' (duration: 93.497379ms)","trace[2115826802] 'compare' (duration: 25.649282ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:26:46.190734Z","caller":"traceutil/trace.go:171","msg":"trace[187910564] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"120.280622ms","start":"2026-02-03T10:26:46.070439Z","end":"2026-02-03T10:26:46.19072Z","steps":["trace[187910564] 'process raft request' (duration: 118.943836ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:46.191147Z","caller":"traceutil/trace.go:171","msg":"trace[1223438354] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"120.737511ms","start":"2026-02-03T10:26:46.070397Z","end":"2026-02-03T10:26:46.191134Z","steps":["trace[1223438354] 'process raft request' (duration: 119.918125ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:50.738897Z","caller":"traceutil/trace.go:171","msg":"trace[2084116921] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"174.056644ms","start":"2026-02-03T10:26:50.564816Z","end":"2026-02-03T10:26:50.738873Z","steps":["trace[2084116921] 'read index received' (duration: 173.880751ms)","trace[2084116921] 'applied index is now lower than readState.Index' (duration: 174.963µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:26:50.73905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.225427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:26:50.739082Z","caller":"traceutil/trace.go:171","msg":"trace[1513489218] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1010; }","duration":"174.321709ms","start":"2026-02-03T10:26:50.564751Z","end":"2026-02-03T10:26:50.739073Z","steps":["trace[1513489218] 'agreement among raft nodes before linearized reading' (duration: 174.218037ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:26:50.739552Z","caller":"traceutil/trace.go:171","msg":"trace[1221438131] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"314.380831ms","start":"2026-02-03T10:26:50.425139Z","end":"2026-02-03T10:26:50.73952Z","steps":["trace[1221438131] 'process raft request' (duration: 313.621278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:26:50.739647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:26:50.425108Z","time spent":"314.479724ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:27:22.434275Z","caller":"traceutil/trace.go:171","msg":"trace[1345955453] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"332.998625ms","start":"2026-02-03T10:27:22.10126Z","end":"2026-02-03T10:27:22.434259Z","steps":["trace[1345955453] 'process raft request' (duration: 332.324023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:22.434414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:27:22.101222Z","time spent":"333.123368ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:27:22.436615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.041415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:27:22.436742Z","caller":"traceutil/trace.go:171","msg":"trace[242867737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"165.194848ms","start":"2026-02-03T10:27:22.271532Z","end":"2026-02-03T10:27:22.436727Z","steps":["trace[242867737] 'agreement among raft nodes before linearized reading' (duration: 165.038875ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:22.437294Z","caller":"traceutil/trace.go:171","msg":"trace[2118175753] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"162.540019ms","start":"2026-02-03T10:27:22.271569Z","end":"2026-02-03T10:27:22.434109Z","steps":["trace[2118175753] 'read index received' (duration: 162.009459ms)","trace[2118175753] 'applied index is now lower than readState.Index' (duration: 529.42µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:23.193378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.838611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:27:23.193664Z","caller":"traceutil/trace.go:171","msg":"trace[749458496] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"240.941465ms","start":"2026-02-03T10:27:22.95271Z","end":"2026-02-03T10:27:23.193651Z","steps":["trace[749458496] 'process raft request' (duration: 136.785787ms)","trace[749458496] 'compare' (duration: 103.652138ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:08.694304Z","caller":"traceutil/trace.go:171","msg":"trace[765891870] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"171.748952ms","start":"2026-02-03T10:28:08.522531Z","end":"2026-02-03T10:28:08.69428Z","steps":["trace[765891870] 'process raft request' (duration: 171.61559ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:08.941937Z","caller":"traceutil/trace.go:171","msg":"trace[953057913] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"131.046186ms","start":"2026-02-03T10:28:08.810868Z","end":"2026-02-03T10:28:08.941915Z","steps":["trace[953057913] 'process raft request' (duration: 107.886989ms)","trace[953057913] 'compare' (duration: 22.630536ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:10.475039Z","caller":"traceutil/trace.go:171","msg":"trace[398500271] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"103.212251ms","start":"2026-02-03T10:28:10.371798Z","end":"2026-02-03T10:28:10.47501Z","steps":["trace[398500271] 'process raft request' (duration: 103.045358ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:10.477483Z","caller":"traceutil/trace.go:171","msg":"trace[1494290310] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"104.494944ms","start":"2026-02-03T10:28:10.372966Z","end":"2026-02-03T10:28:10.477461Z","steps":["trace[1494290310] 'process raft request' (duration: 104.357742ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:30:21.72884Z","caller":"traceutil/trace.go:171","msg":"trace[1195735033] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"262.742187ms","start":"2026-02-03T10:30:21.466074Z","end":"2026-02-03T10:30:21.728816Z","steps":["trace[1195735033] 'process raft request' (duration: 258.948157ms)"],"step_count":1}