{"level":"warn","ts":"2026-05-06T09:24:35.273729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T09:24:35.27394Z","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-05-06T09:24:35.274011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T09:24:35.274213Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-06T09:24:35.274261Z","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-05-06T09:24:35.275021Z","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-05-06T09:24:35.275148Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://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-05-06T09:24:35.284055Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.656221ms"} {"level":"info","ts":"2026-05-06T09:24:35.521579Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-06T09:24:35.521725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T09:24:35.521761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-06T09:24:35.521783Z","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-05-06T09:24:35.521806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-06T09:24:35.521917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-06T09:24:36.432425Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T09:24:36.43465Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T09:24:36.438691Z","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-05-06T09:24:36.443985Z","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-05-06T09:24:36.444257Z","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-05-06T09:24:36.444885Z","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-05-06T09:24:36.445083Z","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-05-06T09:24:36.445123Z","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-05-06T09:24:36.446162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-06T09:24:36.446374Z","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-05-06T09:24:36.447791Z","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-05-06T09:24:36.447924Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-06T09:24:36.447974Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-06T09:24:36.448089Z","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-05-06T09:24:36.448215Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T09:24:36.923036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T09:24:36.923092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T09:24:36.923134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-06T09:24:36.923153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-06T09:24:36.923162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-06T09:24:36.923185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-06T09:24:36.923198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-06T09:24:36.923921Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T09:24:36.924794Z","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-05-06T09:24:36.924807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T09:24:36.925496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T09:24:36.925816Z","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-05-06T09:24:36.925952Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T09:24:36.925986Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T09:24:36.928148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T09:24:36.929724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-06T09:24:36.929892Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T09:24:36.929931Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T09:25:05.628262Z","caller":"traceutil/trace.go:171","msg":"trace[1810368963] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"155.768224ms","start":"2026-05-06T09:25:05.472463Z","end":"2026-05-06T09:25:05.628231Z","steps":["trace[1810368963] 'process raft request' (duration: 155.58952ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:05.900743Z","caller":"traceutil/trace.go:171","msg":"trace[780356027] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"363.11876ms","start":"2026-05-06T09:25:05.537585Z","end":"2026-05-06T09:25:05.900703Z","steps":["trace[780356027] 'process raft request' (duration: 362.860504ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:05.900899Z","caller":"traceutil/trace.go:171","msg":"trace[1987615691] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"361.774378ms","start":"2026-05-06T09:25:05.539074Z","end":"2026-05-06T09:25:05.900848Z","steps":["trace[1987615691] 'process raft request' (duration: 361.559033ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:05.901504Z","caller":"traceutil/trace.go:171","msg":"trace[2141955788] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"347.782737ms","start":"2026-05-06T09:25:05.553702Z","end":"2026-05-06T09:25:05.901485Z","steps":["trace[2141955788] 'process raft request' (duration: 347.4794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.901591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.537561Z","time spent":"363.454348ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T09:25:05.901704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.553685Z","time spent":"347.91419ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T09:25:05.9015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.539064Z","time spent":"361.927612ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.901529Z","caller":"traceutil/trace.go:171","msg":"trace[688072704] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"356.800671ms","start":"2026-05-06T09:25:05.544669Z","end":"2026-05-06T09:25:05.90147Z","steps":["trace[688072704] 'process raft request' (duration: 356.465003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.901935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.544636Z","time spent":"357.258241ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.901733Z","caller":"traceutil/trace.go:171","msg":"trace[1423984542] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"362.480495ms","start":"2026-05-06T09:25:05.539237Z","end":"2026-05-06T09:25:05.901717Z","steps":["trace[1423984542] 'process raft request' (duration: 361.823009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.902034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.539206Z","time spent":"362.810583ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.901726Z","caller":"traceutil/trace.go:171","msg":"trace[1798535527] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"347.811628ms","start":"2026-05-06T09:25:05.553752Z","end":"2026-05-06T09:25:05.901563Z","steps":["trace[1798535527] 'process raft request' (duration: 347.510611ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:05.902182Z","caller":"traceutil/trace.go:171","msg":"trace[1809502297] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"342.228455ms","start":"2026-05-06T09:25:05.559939Z","end":"2026-05-06T09:25:05.902167Z","steps":["trace[1809502297] 'process raft request' (duration: 341.383445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.902224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.559933Z","time spent":"342.273737ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.902596Z","caller":"traceutil/trace.go:171","msg":"trace[1109226806] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"326.168846ms","start":"2026-05-06T09:25:05.576419Z","end":"2026-05-06T09:25:05.902588Z","steps":["trace[1109226806] 'process raft request' (duration: 325.92579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.903975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.576402Z","time spent":"327.531648ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.903717Z","caller":"traceutil/trace.go:171","msg":"trace[1348774805] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"307.091775ms","start":"2026-05-06T09:25:05.596595Z","end":"2026-05-06T09:25:05.903686Z","steps":["trace[1348774805] 'process raft request' (duration: 305.895477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.904155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.596565Z","time spent":"307.512394ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.903771Z","caller":"traceutil/trace.go:171","msg":"trace[1934692459] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"335.010705ms","start":"2026-05-06T09:25:05.567563Z","end":"2026-05-06T09:25:05.902573Z","steps":["trace[1934692459] 'process raft request' (duration: 334.668267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.904305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.567556Z","time spent":"336.711105ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T09:25:05.902479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.553748Z","time spent":"348.466203ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.902536Z","caller":"traceutil/trace.go:171","msg":"trace[1696503931] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"341.5448ms","start":"2026-05-06T09:25:05.560981Z","end":"2026-05-06T09:25:05.902526Z","steps":["trace[1696503931] 'process raft request' (duration: 341.072109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:05.904797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:05.560975Z","time spent":"343.783102ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:05.909772Z","caller":"traceutil/trace.go:171","msg":"trace[615289404] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:521; }","duration":"201.157439ms","start":"2026-05-06T09:25:05.70859Z","end":"2026-05-06T09:25:05.909748Z","steps":["trace[615289404] 'read index received' (duration: 191.827109ms)","trace[615289404] 'applied index is now lower than readState.Index' (duration: 9.32979ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:25:05.909869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.277052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:25:05.90991Z","caller":"traceutil/trace.go:171","msg":"trace[1863297590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"201.354304ms","start":"2026-05-06T09:25:05.708549Z","end":"2026-05-06T09:25:05.909904Z","steps":["trace[1863297590] 'agreement among raft nodes before linearized reading' (duration: 201.253412ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:05.918866Z","caller":"traceutil/trace.go:171","msg":"trace[1639927398] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"205.121313ms","start":"2026-05-06T09:25:05.704746Z","end":"2026-05-06T09:25:05.909867Z","steps":["trace[1639927398] 'process raft request' (duration: 204.853637ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:07.231455Z","caller":"traceutil/trace.go:171","msg":"trace[714415493] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"157.553037ms","start":"2026-05-06T09:25:07.073879Z","end":"2026-05-06T09:25:07.231432Z","steps":["trace[714415493] 'read index received' (duration: 157.412394ms)","trace[714415493] 'applied index is now lower than readState.Index' (duration: 139.753µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:25:07.231676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.764982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-05-06T09:25:07.231716Z","caller":"traceutil/trace.go:171","msg":"trace[1615383751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"157.866784ms","start":"2026-05-06T09:25:07.073837Z","end":"2026-05-06T09:25:07.231704Z","steps":["trace[1615383751] 'agreement among raft nodes before linearized reading' (duration: 157.69194ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:07.231906Z","caller":"traceutil/trace.go:171","msg":"trace[2113061481] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"171.67276ms","start":"2026-05-06T09:25:07.060199Z","end":"2026-05-06T09:25:07.231872Z","steps":["trace[2113061481] 'process raft request' (duration: 171.097237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:08.257306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.065429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:25:08.25742Z","caller":"traceutil/trace.go:171","msg":"trace[907035303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"399.202531ms","start":"2026-05-06T09:25:07.858175Z","end":"2026-05-06T09:25:08.257377Z","steps":["trace[907035303] 'range keys from in-memory index tree' (duration: 398.977236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:08.257467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:07.858142Z","time spent":"399.315294ms","remote":"127.0.0.1:49074","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-05-06T09:25:08.257631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.828111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:25:08.257697Z","caller":"traceutil/trace.go:171","msg":"trace[1093939378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"318.933444ms","start":"2026-05-06T09:25:07.938748Z","end":"2026-05-06T09:25:08.257681Z","steps":["trace[1093939378] 'range keys from in-memory index tree' (duration: 318.679327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:08.257737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:07.938734Z","time spent":"318.994585ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T09:25:08.257796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.709729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T09:25:08.257843Z","caller":"traceutil/trace.go:171","msg":"trace[249741969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"625.792961ms","start":"2026-05-06T09:25:07.632037Z","end":"2026-05-06T09:25:08.25783Z","steps":["trace[249741969] 'range keys from in-memory index tree' (duration: 625.596686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:08.257892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:07.632015Z","time spent":"625.869263ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T09:25:08.258023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.514679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-05-06T09:25:08.258099Z","caller":"traceutil/trace.go:171","msg":"trace[733121292] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:585; }","duration":"889.622011ms","start":"2026-05-06T09:25:07.368455Z","end":"2026-05-06T09:25:08.258077Z","steps":["trace[733121292] 'range keys from in-memory index tree' (duration: 889.14651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:08.258138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:07.368443Z","time spent":"889.685862ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570129,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-06T09:25:11.973304Z","caller":"traceutil/trace.go:171","msg":"trace[850348256] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"176.565406ms","start":"2026-05-06T09:25:11.796707Z","end":"2026-05-06T09:25:11.973272Z","steps":["trace[850348256] 'process raft request' (duration: 158.731074ms)","trace[850348256] 'compare' (duration: 17.682258ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:25:12.344667Z","caller":"traceutil/trace.go:171","msg":"trace[1449913142] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"485.692698ms","start":"2026-05-06T09:25:11.858946Z","end":"2026-05-06T09:25:12.344638Z","steps":["trace[1449913142] 'read index received' (duration: 96.448381ms)","trace[1449913142] 'applied index is now lower than readState.Index' (duration: 389.243237ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:25:12.344771Z","caller":"traceutil/trace.go:171","msg":"trace[635477350] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"509.46777ms","start":"2026-05-06T09:25:11.835262Z","end":"2026-05-06T09:25:12.34473Z","steps":["trace[635477350] 'process raft request' (duration: 467.477447ms)","trace[635477350] 'compare' (duration: 41.770198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:25:12.344837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.873382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:25:12.344896Z","caller":"traceutil/trace.go:171","msg":"trace[2032242125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"485.999424ms","start":"2026-05-06T09:25:11.858878Z","end":"2026-05-06T09:25:12.344877Z","steps":["trace[2032242125] 'agreement among raft nodes before linearized reading' (duration: 485.868781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:12.344944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:11.858857Z","time spent":"486.079017ms","remote":"127.0.0.1:49074","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-05-06T09:25:12.344934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:11.83519Z","time spent":"509.657225ms","remote":"127.0.0.1:36894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:25:12.355383Z","caller":"traceutil/trace.go:171","msg":"trace[563636886] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"168.637259ms","start":"2026-05-06T09:25:12.186727Z","end":"2026-05-06T09:25:12.355364Z","steps":["trace[563636886] 'process raft request' (duration: 168.295671ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:12.355343Z","caller":"traceutil/trace.go:171","msg":"trace[1826632427] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"194.809168ms","start":"2026-05-06T09:25:12.160492Z","end":"2026-05-06T09:25:12.355301Z","steps":["trace[1826632427] 'process raft request' (duration: 194.348807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:13.374329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186107672807174,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T09:25:13.603887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09000695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T09:25:13.603988Z","caller":"traceutil/trace.go:171","msg":"trace[1384406377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.090146904s","start":"2026-05-06T09:25:12.513813Z","end":"2026-05-06T09:25:13.60396Z","steps":["trace[1384406377] 'range keys from in-memory index tree' (duration: 1.089808626s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:13.604046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:12.513798Z","time spent":"1.090234345s","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T09:25:13.632042Z","caller":"traceutil/trace.go:171","msg":"trace[756103904] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"758.783096ms","start":"2026-05-06T09:25:12.87324Z","end":"2026-05-06T09:25:13.632023Z","steps":["trace[756103904] 'read index received' (duration: 757.414774ms)","trace[756103904] 'applied index is now lower than readState.Index' (duration: 1.366792ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:25:13.632099Z","caller":"traceutil/trace.go:171","msg":"trace[504619050] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"877.919014ms","start":"2026-05-06T09:25:12.754157Z","end":"2026-05-06T09:25:13.632076Z","steps":["trace[504619050] 'process raft request' (duration: 876.862569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:13.632297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.054043ms","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-05-06T09:25:13.632371Z","caller":"traceutil/trace.go:171","msg":"trace[649491548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"759.180795ms","start":"2026-05-06T09:25:12.873179Z","end":"2026-05-06T09:25:13.632359Z","steps":["trace[649491548] 'agreement among raft nodes before linearized reading' (duration: 758.95564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:13.632407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:12.873157Z","time spent":"759.240467ms","remote":"127.0.0.1:49318","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-05-06T09:25:13.632523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:12.754129Z","time spent":"878.022967ms","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:25:15.218838Z","caller":"traceutil/trace.go:171","msg":"trace[790639142] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"325.625402ms","start":"2026-05-06T09:25:14.893189Z","end":"2026-05-06T09:25:15.218814Z","steps":["trace[790639142] 'process raft request' (duration: 325.482619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:15.219034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:14.893162Z","time spent":"325.746165ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:25:15.224697Z","caller":"traceutil/trace.go:171","msg":"trace[46168860] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"181.34438ms","start":"2026-05-06T09:25:15.043326Z","end":"2026-05-06T09:25:15.22467Z","steps":["trace[46168860] 'process raft request' (duration: 181.238637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:29.153721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.147182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T09:25:29.153786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.51083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7kr9c\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-06T09:25:29.153844Z","caller":"traceutil/trace.go:171","msg":"trace[1889943213] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7kr9c; range_end:; response_count:1; response_revision:775; }","duration":"309.611852ms","start":"2026-05-06T09:25:28.844215Z","end":"2026-05-06T09:25:29.153827Z","steps":["trace[1889943213] 'range keys from in-memory index tree' (duration: 309.271314ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:29.153842Z","caller":"traceutil/trace.go:171","msg":"trace[1790102054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"301.289526ms","start":"2026-05-06T09:25:28.85253Z","end":"2026-05-06T09:25:29.15382Z","steps":["trace[1790102054] 'range keys from in-memory index tree' (duration: 301.097422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:29.153884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:28.844196Z","time spent":"309.679203ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7kr9c\" "} {"level":"warn","ts":"2026-05-06T09:25:29.153886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:28.852524Z","time spent":"301.352327ms","remote":"127.0.0.1:49074","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-05-06T09:25:29.153936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.129506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T09:25:29.153958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.471607ms","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-05-06T09:25:29.154002Z","caller":"traceutil/trace.go:171","msg":"trace[1800064113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"699.229728ms","start":"2026-05-06T09:25:28.454755Z","end":"2026-05-06T09:25:29.153985Z","steps":["trace[1800064113] 'range keys from in-memory index tree' (duration: 698.973222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:29.154061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:28.454745Z","time spent":"699.292819ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T09:25:29.154053Z","caller":"traceutil/trace.go:171","msg":"trace[600872099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"240.5855ms","start":"2026-05-06T09:25:28.913444Z","end":"2026-05-06T09:25:29.15403Z","steps":["trace[600872099] 'range keys from in-memory index tree' (duration: 240.225271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:29.154054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.941842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:25:29.154113Z","caller":"traceutil/trace.go:171","msg":"trace[1857386529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"702.043195ms","start":"2026-05-06T09:25:28.452055Z","end":"2026-05-06T09:25:29.154099Z","steps":["trace[1857386529] 'range keys from in-memory index tree' (duration: 701.816329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:29.154148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:25:28.452031Z","time spent":"702.106486ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T09:25:29.295448Z","caller":"traceutil/trace.go:171","msg":"trace[230835511] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"137.561624ms","start":"2026-05-06T09:25:29.157867Z","end":"2026-05-06T09:25:29.295428Z","steps":["trace[230835511] 'process raft request' (duration: 137.40461ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:29.340759Z","caller":"traceutil/trace.go:171","msg":"trace[71680171] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"182.042115ms","start":"2026-05-06T09:25:29.158698Z","end":"2026-05-06T09:25:29.340741Z","steps":["trace[71680171] 'process raft request' (duration: 181.79922ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:29.34088Z","caller":"traceutil/trace.go:171","msg":"trace[1079424498] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"181.989194ms","start":"2026-05-06T09:25:29.158781Z","end":"2026-05-06T09:25:29.34077Z","steps":["trace[1079424498] 'process raft request' (duration: 181.843201ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:29.341443Z","caller":"traceutil/trace.go:171","msg":"trace[1739540337] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"178.855131ms","start":"2026-05-06T09:25:29.161895Z","end":"2026-05-06T09:25:29.34075Z","steps":["trace[1739540337] 'process raft request' (duration: 178.781529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:33.227552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.76039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-mkzjb\" ","response":"range_response_count:1 size:5393"} {"level":"info","ts":"2026-05-06T09:25:33.227658Z","caller":"traceutil/trace.go:171","msg":"trace[1899827579] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-mkzjb; range_end:; response_count:1; response_revision:826; }","duration":"132.913923ms","start":"2026-05-06T09:25:33.094722Z","end":"2026-05-06T09:25:33.227636Z","steps":["trace[1899827579] 'range keys from in-memory index tree' (duration: 132.592976ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:33.506731Z","caller":"traceutil/trace.go:171","msg":"trace[1303457312] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"164.773756ms","start":"2026-05-06T09:25:33.341926Z","end":"2026-05-06T09:25:33.5067Z","steps":["trace[1303457312] 'read index received' (duration: 69.059743ms)","trace[1303457312] 'applied index is now lower than readState.Index' (duration: 95.712673ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:25:33.506804Z","caller":"traceutil/trace.go:171","msg":"trace[1342085674] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"194.744685ms","start":"2026-05-06T09:25:33.312029Z","end":"2026-05-06T09:25:33.506774Z","steps":["trace[1342085674] 'process raft request' (duration: 99.036902ms)","trace[1342085674] 'compare' (duration: 95.515318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:25:33.507042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.505678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:25:33.507119Z","caller":"traceutil/trace.go:171","msg":"trace[580661883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"150.610241ms","start":"2026-05-06T09:25:33.356487Z","end":"2026-05-06T09:25:33.507098Z","steps":["trace[580661883] 'agreement among raft nodes before linearized reading' (duration: 150.416127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:33.507617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.115989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T09:25:33.507678Z","caller":"traceutil/trace.go:171","msg":"trace[149823840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"150.336875ms","start":"2026-05-06T09:25:33.357326Z","end":"2026-05-06T09:25:33.507663Z","steps":["trace[149823840] 'agreement among raft nodes before linearized reading' (duration: 149.897254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:25:33.508665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.708782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050244"} {"level":"info","ts":"2026-05-06T09:25:33.50875Z","caller":"traceutil/trace.go:171","msg":"trace[1908210037] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:828; }","duration":"166.815545ms","start":"2026-05-06T09:25:33.341913Z","end":"2026-05-06T09:25:33.508729Z","steps":["trace[1908210037] 'agreement among raft nodes before linearized reading' (duration: 164.950141ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:25:58.373874Z","caller":"traceutil/trace.go:171","msg":"trace[821496293] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"115.277186ms","start":"2026-05-06T09:25:58.258579Z","end":"2026-05-06T09:25:58.373856Z","steps":["trace[821496293] 'process raft request' (duration: 115.137473ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:00.052023Z","caller":"traceutil/trace.go:171","msg":"trace[887621535] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"129.074663ms","start":"2026-05-06T09:25:59.922925Z","end":"2026-05-06T09:26:00.051999Z","steps":["trace[887621535] 'process raft request' (duration: 128.907029ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:00.052066Z","caller":"traceutil/trace.go:171","msg":"trace[498105968] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1006; }","duration":"127.24631ms","start":"2026-05-06T09:25:59.924788Z","end":"2026-05-06T09:26:00.052034Z","steps":["trace[498105968] 'read index received' (duration: 127.211039ms)","trace[498105968] 'applied index is now lower than readState.Index' (duration: 32.781µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:00.052402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.590138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T09:26:00.052507Z","caller":"traceutil/trace.go:171","msg":"trace[1583551409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"127.719261ms","start":"2026-05-06T09:25:59.924749Z","end":"2026-05-06T09:26:00.052468Z","steps":["trace[1583551409] 'agreement among raft nodes before linearized reading' (duration: 127.416544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:00.052638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.089429ms","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-05-06T09:26:00.052682Z","caller":"traceutil/trace.go:171","msg":"trace[829748081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"106.172841ms","start":"2026-05-06T09:25:59.946499Z","end":"2026-05-06T09:26:00.052672Z","steps":["trace[829748081] 'agreement among raft nodes before linearized reading' (duration: 106.040218ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:03.896453Z","caller":"traceutil/trace.go:171","msg":"trace[1433146542] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"203.58486ms","start":"2026-05-06T09:26:03.692845Z","end":"2026-05-06T09:26:03.896429Z","steps":["trace[1433146542] 'read index received' (duration: 203.516318ms)","trace[1433146542] 'applied index is now lower than readState.Index' (duration: 67.452µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:03.896591Z","caller":"traceutil/trace.go:171","msg":"trace[947735710] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"231.384907ms","start":"2026-05-06T09:26:03.665145Z","end":"2026-05-06T09:26:03.89653Z","steps":["trace[947735710] 'process raft request' (duration: 231.147832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:03.896684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.826225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:26:03.896723Z","caller":"traceutil/trace.go:171","msg":"trace[1068975555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"203.930278ms","start":"2026-05-06T09:26:03.692782Z","end":"2026-05-06T09:26:03.896712Z","steps":["trace[1068975555] 'agreement among raft nodes before linearized reading' (duration: 203.766444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:03.902979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.88857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T09:26:03.903079Z","caller":"traceutil/trace.go:171","msg":"trace[1438848734] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:993; }","duration":"183.021342ms","start":"2026-05-06T09:26:03.720021Z","end":"2026-05-06T09:26:03.903042Z","steps":["trace[1438848734] 'agreement among raft nodes before linearized reading' (duration: 182.805887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:03.903299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.826529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T09:26:03.903338Z","caller":"traceutil/trace.go:171","msg":"trace[1921965646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"177.965923ms","start":"2026-05-06T09:26:03.725362Z","end":"2026-05-06T09:26:03.903328Z","steps":["trace[1921965646] 'agreement among raft nodes before linearized reading' (duration: 177.756398ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:04.276526Z","caller":"traceutil/trace.go:171","msg":"trace[213542189] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"138.082136ms","start":"2026-05-06T09:26:04.138415Z","end":"2026-05-06T09:26:04.276497Z","steps":["trace[213542189] 'process raft request' (duration: 137.83544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:05.084846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.000939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T09:26:05.085032Z","caller":"traceutil/trace.go:171","msg":"trace[1863068799] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"945.395449ms","start":"2026-05-06T09:26:04.139601Z","end":"2026-05-06T09:26:05.084996Z","steps":["trace[1863068799] 'process raft request' (duration: 379.795966ms)","trace[1863068799] 'compare' (duration: 564.140679ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:05.085124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:04.139588Z","time spent":"945.502361ms","remote":"127.0.0.1:49318","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-05-06T09:26:05.313918Z","caller":"traceutil/trace.go:171","msg":"trace[1386710360] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"923.512584ms","start":"2026-05-06T09:26:04.39038Z","end":"2026-05-06T09:26:05.313892Z","steps":["trace[1386710360] 'process raft request' (duration: 922.560142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:05.314096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:04.390344Z","time spent":"923.650798ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:26:10.363294Z","caller":"traceutil/trace.go:171","msg":"trace[333154325] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"144.198509ms","start":"2026-05-06T09:26:10.219063Z","end":"2026-05-06T09:26:10.363262Z","steps":["trace[333154325] 'process raft request' (duration: 143.897252ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:28.348477Z","caller":"traceutil/trace.go:171","msg":"trace[629292291] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"263.160525ms","start":"2026-05-06T09:26:28.085286Z","end":"2026-05-06T09:26:28.348446Z","steps":["trace[629292291] 'read index received' (duration: 262.856808ms)","trace[629292291] 'applied index is now lower than readState.Index' (duration: 302.187µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:28.348605Z","caller":"traceutil/trace.go:171","msg":"trace[15681220] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"274.867211ms","start":"2026-05-06T09:26:28.073679Z","end":"2026-05-06T09:26:28.348546Z","steps":["trace[15681220] 'process raft request' (duration: 274.589024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:28.348814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.521165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T09:26:28.348821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.075802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T09:26:28.348862Z","caller":"traceutil/trace.go:171","msg":"trace[1796051380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"252.644008ms","start":"2026-05-06T09:26:28.096206Z","end":"2026-05-06T09:26:28.34885Z","steps":["trace[1796051380] 'agreement among raft nodes before linearized reading' (duration: 252.471384ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:28.348876Z","caller":"traceutil/trace.go:171","msg":"trace[1840125339] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1071; }","duration":"103.198135ms","start":"2026-05-06T09:26:28.245661Z","end":"2026-05-06T09:26:28.348859Z","steps":["trace[1840125339] 'agreement among raft nodes before linearized reading' (duration: 103.092772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:28.348846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.544024ms","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-05-06T09:26:28.348987Z","caller":"traceutil/trace.go:171","msg":"trace[1399914722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"263.691437ms","start":"2026-05-06T09:26:28.08523Z","end":"2026-05-06T09:26:28.348921Z","steps":["trace[1399914722] 'agreement among raft nodes before linearized reading' (duration: 263.430251ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:29.006903Z","caller":"traceutil/trace.go:171","msg":"trace[1419000079] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"653.55958ms","start":"2026-05-06T09:26:28.35332Z","end":"2026-05-06T09:26:29.006879Z","steps":["trace[1419000079] 'process raft request' (duration: 653.435857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:29.007045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:28.353295Z","time spent":"653.677073ms","remote":"127.0.0.1:49318","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-05-06T09:26:29.011372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.556474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T09:26:29.011537Z","caller":"traceutil/trace.go:171","msg":"trace[1342423865] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1073; }","duration":"460.754219ms","start":"2026-05-06T09:26:28.550747Z","end":"2026-05-06T09:26:29.011501Z","steps":["trace[1342423865] 'agreement among raft nodes before linearized reading' (duration: 460.566414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:29.011641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:28.550728Z","time spent":"460.899203ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-05-06T09:26:29.011816Z","caller":"traceutil/trace.go:171","msg":"trace[1653706497] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1106; }","duration":"460.410201ms","start":"2026-05-06T09:26:28.550799Z","end":"2026-05-06T09:26:29.011209Z","steps":["trace[1653706497] 'read index received' (duration: 455.993647ms)","trace[1653706497] 'applied index is now lower than readState.Index' (duration: 4.415924ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:29.011279Z","caller":"traceutil/trace.go:171","msg":"trace[1211276631] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"657.663187ms","start":"2026-05-06T09:26:28.353596Z","end":"2026-05-06T09:26:29.011259Z","steps":["trace[1211276631] 'process raft request' (duration: 657.471023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:29.012242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:28.353572Z","time spent":"658.614309ms","remote":"127.0.0.1:49318","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-05-06T09:26:29.01208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.368478ms","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-05-06T09:26:29.012521Z","caller":"traceutil/trace.go:171","msg":"trace[899501143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"445.815358ms","start":"2026-05-06T09:26:28.566663Z","end":"2026-05-06T09:26:29.012479Z","steps":["trace[899501143] 'agreement among raft nodes before linearized reading' (duration: 445.290675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:29.012073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.637102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T09:26:29.012608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:28.566653Z","time spent":"445.9125ms","remote":"127.0.0.1:49318","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":"info","ts":"2026-05-06T09:26:29.012644Z","caller":"traceutil/trace.go:171","msg":"trace[1119966859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"157.242765ms","start":"2026-05-06T09:26:28.855381Z","end":"2026-05-06T09:26:29.012623Z","steps":["trace[1119966859] 'agreement among raft nodes before linearized reading' (duration: 156.641701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:29.968433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.967645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:26:29.968542Z","caller":"traceutil/trace.go:171","msg":"trace[1381077898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"113.122189ms","start":"2026-05-06T09:26:29.855393Z","end":"2026-05-06T09:26:29.968515Z","steps":["trace[1381077898] 'range keys from in-memory index tree' (duration: 112.884954ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:30.2671Z","caller":"traceutil/trace.go:171","msg":"trace[1545079660] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"288.796907ms","start":"2026-05-06T09:26:29.978251Z","end":"2026-05-06T09:26:30.267048Z","steps":["trace[1545079660] 'process raft request' (duration: 265.827578ms)","trace[1545079660] 'compare' (duration: 22.749564ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:31.148148Z","caller":"traceutil/trace.go:171","msg":"trace[1147423556] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"136.801625ms","start":"2026-05-06T09:26:31.011321Z","end":"2026-05-06T09:26:31.148122Z","steps":["trace[1147423556] 'read index received' (duration: 136.560929ms)","trace[1147423556] 'applied index is now lower than readState.Index' (duration: 239.876µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:31.148658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.328838ms","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-05-06T09:26:31.14864Z","caller":"traceutil/trace.go:171","msg":"trace[1502039329] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"202.95706ms","start":"2026-05-06T09:26:30.945645Z","end":"2026-05-06T09:26:31.148602Z","steps":["trace[1502039329] 'process raft request' (duration: 202.240133ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:31.148704Z","caller":"traceutil/trace.go:171","msg":"trace[1461577677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"137.42808ms","start":"2026-05-06T09:26:31.011264Z","end":"2026-05-06T09:26:31.148692Z","steps":["trace[1461577677] 'agreement among raft nodes before linearized reading' (duration: 137.277556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:31.148855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.70608ms","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-05-06T09:26:31.148961Z","caller":"traceutil/trace.go:171","msg":"trace[1667714852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"101.829853ms","start":"2026-05-06T09:26:31.047115Z","end":"2026-05-06T09:26:31.148945Z","steps":["trace[1667714852] 'agreement among raft nodes before linearized reading' (duration: 101.483305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:31.149162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.610513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T09:26:31.149255Z","caller":"traceutil/trace.go:171","msg":"trace[17929177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"132.045184ms","start":"2026-05-06T09:26:31.017186Z","end":"2026-05-06T09:26:31.149232Z","steps":["trace[17929177] 'agreement among raft nodes before linearized reading' (duration: 131.320667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:31.522049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.643867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T09:26:31.522293Z","caller":"traceutil/trace.go:171","msg":"trace[1168905288] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"369.374251ms","start":"2026-05-06T09:26:31.152889Z","end":"2026-05-06T09:26:31.522263Z","steps":["trace[1168905288] 'process raft request' (duration: 99.410266ms)","trace[1168905288] 'compare' (duration: 269.386941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:31.522455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:31.152855Z","time spent":"369.521505ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:26:31.522483Z","caller":"traceutil/trace.go:171","msg":"trace[2069720419] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"369.028403ms","start":"2026-05-06T09:26:31.153425Z","end":"2026-05-06T09:26:31.522453Z","steps":["trace[2069720419] 'process raft request' (duration: 368.744387ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:31.522574Z","caller":"traceutil/trace.go:171","msg":"trace[1841589270] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"368.233575ms","start":"2026-05-06T09:26:31.15432Z","end":"2026-05-06T09:26:31.522553Z","steps":["trace[1841589270] 'process raft request' (duration: 367.925468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:31.522618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:31.153384Z","time spent":"369.154466ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:26:31.522667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:31.15431Z","time spent":"368.312296ms","remote":"127.0.0.1:49318","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-05-06T09:26:31.522661Z","caller":"traceutil/trace.go:171","msg":"trace[938765017] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1115; }","duration":"270.219041ms","start":"2026-05-06T09:26:31.252396Z","end":"2026-05-06T09:26:31.522615Z","steps":["trace[938765017] 'read index received' (duration: 123.404641ms)","trace[938765017] 'applied index is now lower than readState.Index' (duration: 146.80687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:31.52295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.525568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T09:26:31.523017Z","caller":"traceutil/trace.go:171","msg":"trace[754746432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"270.665331ms","start":"2026-05-06T09:26:31.252334Z","end":"2026-05-06T09:26:31.522999Z","steps":["trace[754746432] 'agreement among raft nodes before linearized reading' (duration: 270.411595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:31.98211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.165609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T09:26:31.982262Z","caller":"traceutil/trace.go:171","msg":"trace[1719983003] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"244.795793ms","start":"2026-05-06T09:26:31.737441Z","end":"2026-05-06T09:26:31.982237Z","steps":["trace[1719983003] 'read index received' (duration: 71.267445ms)","trace[1719983003] 'applied index is now lower than readState.Index' (duration: 173.526388ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:31.982418Z","caller":"traceutil/trace.go:171","msg":"trace[913363422] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"454.621874ms","start":"2026-05-06T09:26:31.527743Z","end":"2026-05-06T09:26:31.982365Z","steps":["trace[913363422] 'process raft request' (duration: 281.072786ms)","trace[913363422] 'compare' (duration: 172.77891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:31.982436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.971657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T09:26:31.982531Z","caller":"traceutil/trace.go:171","msg":"trace[795497350] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1084; }","duration":"245.164542ms","start":"2026-05-06T09:26:31.737348Z","end":"2026-05-06T09:26:31.982512Z","steps":["trace[795497350] 'agreement among raft nodes before linearized reading' (duration: 244.996588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:31.982434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.780393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T09:26:31.982614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:31.527712Z","time spent":"454.793299ms","remote":"127.0.0.1:49318","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-05-06T09:26:31.982625Z","caller":"traceutil/trace.go:171","msg":"trace[1972370731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"128.009449ms","start":"2026-05-06T09:26:31.854598Z","end":"2026-05-06T09:26:31.982607Z","steps":["trace[1972370731] 'agreement among raft nodes before linearized reading' (duration: 127.777874ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:39.743975Z","caller":"traceutil/trace.go:171","msg":"trace[2035361871] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"106.991825ms","start":"2026-05-06T09:26:39.63695Z","end":"2026-05-06T09:26:39.743942Z","steps":["trace[2035361871] 'process raft request' (duration: 101.498186ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:39.744081Z","caller":"traceutil/trace.go:171","msg":"trace[1896345074] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"106.826731ms","start":"2026-05-06T09:26:39.637241Z","end":"2026-05-06T09:26:39.744068Z","steps":["trace[1896345074] 'process raft request' (duration: 106.656017ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:43.734991Z","caller":"traceutil/trace.go:171","msg":"trace[335840477] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"131.959231ms","start":"2026-05-06T09:26:43.603009Z","end":"2026-05-06T09:26:43.734968Z","steps":["trace[335840477] 'process raft request' (duration: 131.791507ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:43.735103Z","caller":"traceutil/trace.go:171","msg":"trace[919741016] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"132.150966ms","start":"2026-05-06T09:26:43.602925Z","end":"2026-05-06T09:26:43.735076Z","steps":["trace[919741016] 'process raft request' (duration: 105.248343ms)","trace[919741016] 'compare' (duration: 26.409511ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:43.921754Z","caller":"traceutil/trace.go:171","msg":"trace[1346701782] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"122.203861ms","start":"2026-05-06T09:26:43.799524Z","end":"2026-05-06T09:26:43.921728Z","steps":["trace[1346701782] 'process raft request' (duration: 38.358361ms)","trace[1346701782] 'compare' (duration: 83.720497ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:43.958198Z","caller":"traceutil/trace.go:171","msg":"trace[721396235] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"157.717576ms","start":"2026-05-06T09:26:43.800465Z","end":"2026-05-06T09:26:43.958182Z","steps":["trace[721396235] 'process raft request' (duration: 157.684746ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:43.958315Z","caller":"traceutil/trace.go:171","msg":"trace[1395865987] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"157.86747ms","start":"2026-05-06T09:26:43.800439Z","end":"2026-05-06T09:26:43.958306Z","steps":["trace[1395865987] 'process raft request' (duration: 157.668506ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:43.958387Z","caller":"traceutil/trace.go:171","msg":"trace[1451375231] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"158.3035ms","start":"2026-05-06T09:26:43.800055Z","end":"2026-05-06T09:26:43.958359Z","steps":["trace[1451375231] 'process raft request' (duration: 157.982933ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:45.943636Z","caller":"traceutil/trace.go:171","msg":"trace[1053738463] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"174.292946ms","start":"2026-05-06T09:26:45.769317Z","end":"2026-05-06T09:26:45.94361Z","steps":["trace[1053738463] 'read index received' (duration: 174.020529ms)","trace[1053738463] 'applied index is now lower than readState.Index' (duration: 270.697µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:45.943792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.468711ms","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-05-06T09:26:45.943841Z","caller":"traceutil/trace.go:171","msg":"trace[942673023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"174.557482ms","start":"2026-05-06T09:26:45.769276Z","end":"2026-05-06T09:26:45.943833Z","steps":["trace[942673023] 'agreement among raft nodes before linearized reading' (duration: 174.407928ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:45.943655Z","caller":"traceutil/trace.go:171","msg":"trace[401377994] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"202.936329ms","start":"2026-05-06T09:26:45.740695Z","end":"2026-05-06T09:26:45.943631Z","steps":["trace[401377994] 'process raft request' (duration: 202.678323ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:46.174728Z","caller":"traceutil/trace.go:171","msg":"trace[636599768] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"226.922093ms","start":"2026-05-06T09:26:45.947785Z","end":"2026-05-06T09:26:46.174707Z","steps":["trace[636599768] 'process raft request' (duration: 208.701755ms)","trace[636599768] 'compare' (duration: 17.957512ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:46.174743Z","caller":"traceutil/trace.go:171","msg":"trace[1004927768] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1219; }","duration":"213.879926ms","start":"2026-05-06T09:26:45.960845Z","end":"2026-05-06T09:26:46.174725Z","steps":["trace[1004927768] 'read index received' (duration: 195.71334ms)","trace[1004927768] 'applied index is now lower than readState.Index' (duration: 18.165586ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:26:46.174823Z","caller":"traceutil/trace.go:171","msg":"trace[1812975325] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"226.562825ms","start":"2026-05-06T09:26:45.948253Z","end":"2026-05-06T09:26:46.174816Z","steps":["trace[1812975325] 'process raft request' (duration: 226.398811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:46.175032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.181923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T09:26:46.175053Z","caller":"traceutil/trace.go:171","msg":"trace[350160042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"214.235905ms","start":"2026-05-06T09:26:45.960811Z","end":"2026-05-06T09:26:46.175047Z","steps":["trace[350160042] 'agreement among raft nodes before linearized reading' (duration: 214.133843ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:48.397954Z","caller":"traceutil/trace.go:171","msg":"trace[707903866] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"305.001399ms","start":"2026-05-06T09:26:48.092935Z","end":"2026-05-06T09:26:48.397936Z","steps":["trace[707903866] 'read index received' (duration: 304.882496ms)","trace[707903866] 'applied index is now lower than readState.Index' (duration: 117.443µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:26:48.398105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.153192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:26:48.398127Z","caller":"traceutil/trace.go:171","msg":"trace[285621832] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1184; }","duration":"305.225434ms","start":"2026-05-06T09:26:48.092894Z","end":"2026-05-06T09:26:48.39812Z","steps":["trace[285621832] 'agreement among raft nodes before linearized reading' (duration: 305.145102ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:48.398084Z","caller":"traceutil/trace.go:171","msg":"trace[2136484668] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"448.977652ms","start":"2026-05-06T09:26:47.949069Z","end":"2026-05-06T09:26:48.398047Z","steps":["trace[2136484668] 'process raft request' (duration: 448.741717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:48.398147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:48.092877Z","time spent":"305.265054ms","remote":"127.0.0.1:49312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-06T09:26:48.398258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:26:47.949051Z","time spent":"449.109645ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:26:48.398279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.770791ms","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-05-06T09:26:48.398358Z","caller":"traceutil/trace.go:171","msg":"trace[1087751303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"218.896354ms","start":"2026-05-06T09:26:48.179441Z","end":"2026-05-06T09:26:48.398338Z","steps":["trace[1087751303] 'agreement among raft nodes before linearized reading' (duration: 218.654378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:48.398407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.365344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T09:26:48.398437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.255938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T09:26:48.398458Z","caller":"traceutil/trace.go:171","msg":"trace[1794460318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"212.29501ms","start":"2026-05-06T09:26:48.186155Z","end":"2026-05-06T09:26:48.39845Z","steps":["trace[1794460318] 'agreement among raft nodes before linearized reading' (duration: 212.218608ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:26:48.398453Z","caller":"traceutil/trace.go:171","msg":"trace[1886878439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"213.446626ms","start":"2026-05-06T09:26:48.184993Z","end":"2026-05-06T09:26:48.398439Z","steps":["trace[1886878439] 'agreement among raft nodes before linearized reading' (duration: 213.330414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:26:48.398585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.023418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:26:48.398618Z","caller":"traceutil/trace.go:171","msg":"trace[1647723568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"219.095089ms","start":"2026-05-06T09:26:48.179512Z","end":"2026-05-06T09:26:48.398607Z","steps":["trace[1647723568] 'agreement among raft nodes before linearized reading' (duration: 218.998177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:00.961486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186107672808575,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T09:27:01.139158Z","caller":"traceutil/trace.go:171","msg":"trace[2114398540] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"677.944893ms","start":"2026-05-06T09:27:00.461187Z","end":"2026-05-06T09:27:01.139132Z","steps":["trace[2114398540] 'read index received' (duration: 677.781419ms)","trace[2114398540] 'applied index is now lower than readState.Index' (duration: 162.724µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:27:01.139492Z","caller":"traceutil/trace.go:171","msg":"trace[1537098070] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"693.398137ms","start":"2026-05-06T09:27:00.446084Z","end":"2026-05-06T09:27:01.139482Z","steps":["trace[1537098070] 'process raft request' (duration: 692.821503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.139594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:00.446064Z","time spent":"693.456047ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:27:01.139791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.600099ms","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-05-06T09:27:01.139823Z","caller":"traceutil/trace.go:171","msg":"trace[1231139584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"678.631829ms","start":"2026-05-06T09:27:00.461184Z","end":"2026-05-06T09:27:01.139816Z","steps":["trace[1231139584] 'agreement among raft nodes before linearized reading' (duration: 678.536067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.139846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:00.461173Z","time spent":"678.66664ms","remote":"127.0.0.1:49318","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-05-06T09:27:01.140041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.573699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T09:27:01.140094Z","caller":"traceutil/trace.go:171","msg":"trace[583386507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"667.657412ms","start":"2026-05-06T09:27:00.472424Z","end":"2026-05-06T09:27:01.140081Z","steps":["trace[583386507] 'agreement among raft nodes before linearized reading' (duration: 667.506268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.140105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.152086ms","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":"warn","ts":"2026-05-06T09:27:01.140121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:00.472413Z","time spent":"667.702952ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T09:27:01.14013Z","caller":"traceutil/trace.go:171","msg":"trace[1960068412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"489.212318ms","start":"2026-05-06T09:27:00.650912Z","end":"2026-05-06T09:27:01.140124Z","steps":["trace[1960068412] 'agreement among raft nodes before linearized reading' (duration: 489.143006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.140153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:00.650888Z","time spent":"489.260559ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T09:27:01.1402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.93849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:27:01.140214Z","caller":"traceutil/trace.go:171","msg":"trace[1081733924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"285.989272ms","start":"2026-05-06T09:27:00.85422Z","end":"2026-05-06T09:27:01.14021Z","steps":["trace[1081733924] 'agreement among raft nodes before linearized reading' (duration: 285.965181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.140264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.536355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T09:27:01.14028Z","caller":"traceutil/trace.go:171","msg":"trace[882993880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"666.568036ms","start":"2026-05-06T09:27:00.473708Z","end":"2026-05-06T09:27:01.140276Z","steps":["trace[882993880] 'agreement among raft nodes before linearized reading' (duration: 666.521825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.140296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:00.473698Z","time spent":"666.595087ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T09:27:01.140385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.756271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T09:27:01.140396Z","caller":"traceutil/trace.go:171","msg":"trace[1281521105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"666.812182ms","start":"2026-05-06T09:27:00.473581Z","end":"2026-05-06T09:27:01.140393Z","steps":["trace[1281521105] 'agreement among raft nodes before linearized reading' (duration: 666.767611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:01.140408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:00.473554Z","time spent":"666.851272ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T09:27:01.3181Z","caller":"traceutil/trace.go:171","msg":"trace[1044731395] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"173.921828ms","start":"2026-05-06T09:27:01.144145Z","end":"2026-05-06T09:27:01.318066Z","steps":["trace[1044731395] 'process raft request' (duration: 173.684792ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:01.318236Z","caller":"traceutil/trace.go:171","msg":"trace[344947388] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"173.910218ms","start":"2026-05-06T09:27:01.144287Z","end":"2026-05-06T09:27:01.318198Z","steps":["trace[344947388] 'process raft request' (duration: 173.680032ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:01.318437Z","caller":"traceutil/trace.go:171","msg":"trace[1293466849] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"172.798501ms","start":"2026-05-06T09:27:01.145622Z","end":"2026-05-06T09:27:01.318421Z","steps":["trace[1293466849] 'process raft request' (duration: 172.707699ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:01.31842Z","caller":"traceutil/trace.go:171","msg":"trace[1458847498] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"173.976309ms","start":"2026-05-06T09:27:01.144415Z","end":"2026-05-06T09:27:01.318391Z","steps":["trace[1458847498] 'process raft request' (duration: 173.59119ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:01.524518Z","caller":"traceutil/trace.go:171","msg":"trace[478789592] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"141.472575ms","start":"2026-05-06T09:27:01.383025Z","end":"2026-05-06T09:27:01.524498Z","steps":["trace[478789592] 'process raft request' (duration: 140.525883ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:03.389946Z","caller":"traceutil/trace.go:171","msg":"trace[1474785655] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"173.112468ms","start":"2026-05-06T09:27:03.216801Z","end":"2026-05-06T09:27:03.389913Z","steps":["trace[1474785655] 'process raft request' (duration: 112.886743ms)","trace[1474785655] 'compare' (duration: 60.024911ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:27:05.471413Z","caller":"traceutil/trace.go:171","msg":"trace[908225772] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"359.907058ms","start":"2026-05-06T09:27:05.11148Z","end":"2026-05-06T09:27:05.471387Z","steps":["trace[908225772] 'process raft request' (duration: 359.735114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:05.471588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.111454Z","time spent":"360.044292ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:27:06.415498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.448883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T09:27:06.415749Z","caller":"traceutil/trace.go:171","msg":"trace[1377407265] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1289; }","duration":"1.188435521s","start":"2026-05-06T09:27:05.227298Z","end":"2026-05-06T09:27:06.415734Z","steps":["trace[1377407265] 'read index received' (duration: 244.008524ms)","trace[1377407265] 'applied index is now lower than readState.Index' (duration: 944.426007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:27:06.415866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188568864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:27:06.415825Z","caller":"traceutil/trace.go:171","msg":"trace[258838762] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"1.250176462s","start":"2026-05-06T09:27:05.165611Z","end":"2026-05-06T09:27:06.415787Z","steps":["trace[258838762] 'process raft request' (duration: 1.249978557s)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:06.415899Z","caller":"traceutil/trace.go:171","msg":"trace[440718782] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1247; }","duration":"1.188664786s","start":"2026-05-06T09:27:05.227226Z","end":"2026-05-06T09:27:06.415891Z","steps":["trace[440718782] 'agreement among raft nodes before linearized reading' (duration: 1.188600255s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.415928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.2272Z","time spent":"1.188720737s","remote":"127.0.0.1:49222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T09:27:06.416038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.165591Z","time spent":"1.250322225s","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:27:06.416122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12200824s","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-05-06T09:27:06.4162Z","caller":"traceutil/trace.go:171","msg":"trace[1007518259] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1247; }","duration":"1.122123412s","start":"2026-05-06T09:27:05.294057Z","end":"2026-05-06T09:27:06.41618Z","steps":["trace[1007518259] 'agreement among raft nodes before linearized reading' (duration: 1.121979269s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.294046Z","time spent":"1.122189624s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-06T09:27:06.416328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.053726ms","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-05-06T09:27:06.416352Z","caller":"traceutil/trace.go:171","msg":"trace[704748604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"978.102638ms","start":"2026-05-06T09:27:05.438244Z","end":"2026-05-06T09:27:06.416346Z","steps":["trace[704748604] 'agreement among raft nodes before linearized reading' (duration: 978.001676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.438232Z","time spent":"978.131958ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T09:27:06.416427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.085883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:27:06.416443Z","caller":"traceutil/trace.go:171","msg":"trace[422509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"560.145615ms","start":"2026-05-06T09:27:05.856293Z","end":"2026-05-06T09:27:06.416438Z","steps":["trace[422509] 'agreement among raft nodes before linearized reading' (duration: 560.119705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.856266Z","time spent":"560.188216ms","remote":"127.0.0.1:49074","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-05-06T09:27:06.416508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.310763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T09:27:06.416536Z","caller":"traceutil/trace.go:171","msg":"trace[2113922421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"978.394114ms","start":"2026-05-06T09:27:05.438135Z","end":"2026-05-06T09:27:06.416529Z","steps":["trace[2113922421] 'agreement among raft nodes before linearized reading' (duration: 978.279382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.438093Z","time spent":"978.463466ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T09:27:06.41657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.168049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T09:27:06.416592Z","caller":"traceutil/trace.go:171","msg":"trace[375670679] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1247; }","duration":"821.235371ms","start":"2026-05-06T09:27:05.595349Z","end":"2026-05-06T09:27:06.416584Z","steps":["trace[375670679] 'agreement among raft nodes before linearized reading' (duration: 821.19478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.59532Z","time spent":"821.288612ms","remote":"127.0.0.1:36944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-06T09:27:06.416719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.150479ms","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-05-06T09:27:06.416739Z","caller":"traceutil/trace.go:171","msg":"trace[670737261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"978.179569ms","start":"2026-05-06T09:27:05.438553Z","end":"2026-05-06T09:27:06.416733Z","steps":["trace[670737261] 'agreement among raft nodes before linearized reading' (duration: 978.127528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021365425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T09:27:06.416799Z","caller":"traceutil/trace.go:171","msg":"trace[2096815460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"1.021464557s","start":"2026-05-06T09:27:05.395306Z","end":"2026-05-06T09:27:06.416771Z","steps":["trace[2096815460] 'agreement among raft nodes before linearized reading' (duration: 1.021270102s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.39528Z","time spent":"1.021554438s","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T09:27:06.416848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009763772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T09:27:06.416752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.438548Z","time spent":"978.201769ms","remote":"127.0.0.1:49318","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":"info","ts":"2026-05-06T09:27:06.41688Z","caller":"traceutil/trace.go:171","msg":"trace[1243510553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"1.009855093s","start":"2026-05-06T09:27:05.407015Z","end":"2026-05-06T09:27:06.41687Z","steps":["trace[1243510553] 'agreement among raft nodes before linearized reading' (duration: 1.009786752s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:06.416903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:05.406989Z","time spent":"1.009908955s","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T09:27:06.524867Z","caller":"traceutil/trace.go:171","msg":"trace[1614097930] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"104.231659ms","start":"2026-05-06T09:27:06.420601Z","end":"2026-05-06T09:27:06.524833Z","steps":["trace[1614097930] 'read index received' (duration: 104.039614ms)","trace[1614097930] 'applied index is now lower than readState.Index' (duration: 190.385µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:27:06.525078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.433523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:27:06.525138Z","caller":"traceutil/trace.go:171","msg":"trace[450913907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"104.543146ms","start":"2026-05-06T09:27:06.420578Z","end":"2026-05-06T09:27:06.525121Z","steps":["trace[450913907] 'agreement among raft nodes before linearized reading' (duration: 104.383753ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:06.525069Z","caller":"traceutil/trace.go:171","msg":"trace[12267179] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"105.079639ms","start":"2026-05-06T09:27:06.41994Z","end":"2026-05-06T09:27:06.525019Z","steps":["trace[12267179] 'process raft request' (duration: 104.644699ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:06.536085Z","caller":"traceutil/trace.go:171","msg":"trace[1657489004] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"115.253577ms","start":"2026-05-06T09:27:06.420811Z","end":"2026-05-06T09:27:06.536064Z","steps":["trace[1657489004] 'process raft request' (duration: 115.097344ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:06.536319Z","caller":"traceutil/trace.go:171","msg":"trace[1834835383] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"115.176756ms","start":"2026-05-06T09:27:06.421135Z","end":"2026-05-06T09:27:06.536312Z","steps":["trace[1834835383] 'process raft request' (duration: 114.89603ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:06.536612Z","caller":"traceutil/trace.go:171","msg":"trace[660522000] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"115.222867ms","start":"2026-05-06T09:27:06.421364Z","end":"2026-05-06T09:27:06.536587Z","steps":["trace[660522000] 'process raft request' (duration: 114.889379ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:06.536604Z","caller":"traceutil/trace.go:171","msg":"trace[239162964] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"115.060973ms","start":"2026-05-06T09:27:06.421523Z","end":"2026-05-06T09:27:06.536584Z","steps":["trace[239162964] 'process raft request' (duration: 114.95349ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:07.216043Z","caller":"traceutil/trace.go:171","msg":"trace[1603861300] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"390.021496ms","start":"2026-05-06T09:27:06.825998Z","end":"2026-05-06T09:27:07.21602Z","steps":["trace[1603861300] 'process raft request' (duration: 389.970505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:07.216172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:06.825985Z","time spent":"390.119169ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:27:07.216326Z","caller":"traceutil/trace.go:171","msg":"trace[643348287] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"391.100642ms","start":"2026-05-06T09:27:06.825219Z","end":"2026-05-06T09:27:07.21632Z","steps":["trace[643348287] 'process raft request' (duration: 390.638461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:07.216351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:06.825199Z","time spent":"391.138192ms","remote":"127.0.0.1:49132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T09:27:18.861878Z","caller":"traceutil/trace.go:171","msg":"trace[2022336321] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"253.078865ms","start":"2026-05-06T09:27:18.608765Z","end":"2026-05-06T09:27:18.861844Z","steps":["trace[2022336321] 'process raft request' (duration: 252.880981ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:19.183069Z","caller":"traceutil/trace.go:171","msg":"trace[713217144] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1393; }","duration":"328.156203ms","start":"2026-05-06T09:27:18.854884Z","end":"2026-05-06T09:27:19.18304Z","steps":["trace[713217144] 'read index received' (duration: 6.775449ms)","trace[713217144] 'applied index is now lower than readState.Index' (duration: 321.379574ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:27:19.183082Z","caller":"traceutil/trace.go:171","msg":"trace[1940790649] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"574.243724ms","start":"2026-05-06T09:27:18.608787Z","end":"2026-05-06T09:27:19.183031Z","steps":["trace[1940790649] 'process raft request' (duration: 573.706502ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:19.183161Z","caller":"traceutil/trace.go:171","msg":"trace[1193217143] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"574.170722ms","start":"2026-05-06T09:27:18.608951Z","end":"2026-05-06T09:27:19.183121Z","steps":["trace[1193217143] 'process raft request' (duration: 573.950067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:19.183235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.330927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:27:19.183293Z","caller":"traceutil/trace.go:171","msg":"trace[1491431930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"328.45727ms","start":"2026-05-06T09:27:18.85482Z","end":"2026-05-06T09:27:19.183277Z","steps":["trace[1491431930] 'agreement among raft nodes before linearized reading' (duration: 328.335257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:19.183317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:18.608773Z","time spent":"574.418678ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:27:19.183345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:18.608941Z","time spent":"574.300075ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T09:27:19.183348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:18.854796Z","time spent":"328.538752ms","remote":"127.0.0.1:49074","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-05-06T09:27:19.183378Z","caller":"traceutil/trace.go:171","msg":"trace[1388413645] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"574.536031ms","start":"2026-05-06T09:27:18.608826Z","end":"2026-05-06T09:27:19.183362Z","steps":["trace[1388413645] 'process raft request' (duration: 573.934037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:19.183482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:18.608814Z","time spent":"574.631563ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:27:19.183496Z","caller":"traceutil/trace.go:171","msg":"trace[271515045] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"574.522971ms","start":"2026-05-06T09:27:18.608948Z","end":"2026-05-06T09:27:19.183471Z","steps":["trace[271515045] 'process raft request' (duration: 574.000958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T09:27:19.183722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T09:27:18.60893Z","time spent":"574.701445ms","remote":"127.0.0.1:49318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T09:27:29.38914Z","caller":"traceutil/trace.go:171","msg":"trace[175183248] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"153.465372ms","start":"2026-05-06T09:27:29.235644Z","end":"2026-05-06T09:27:29.389109Z","steps":["trace[175183248] 'process raft request' (duration: 153.38883ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:27:29.38916Z","caller":"traceutil/trace.go:171","msg":"trace[1417959830] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"154.767003ms","start":"2026-05-06T09:27:29.234354Z","end":"2026-05-06T09:27:29.389121Z","steps":["trace[1417959830] 'process raft request' (duration: 121.006658ms)","trace[1417959830] 'compare' (duration: 33.497889ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:27:29.389341Z","caller":"traceutil/trace.go:171","msg":"trace[1990401046] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"152.641012ms","start":"2026-05-06T09:27:29.236662Z","end":"2026-05-06T09:27:29.389303Z","steps":["trace[1990401046] 'process raft request' (duration: 152.411987ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:28:31.840922Z","caller":"traceutil/trace.go:171","msg":"trace[438863816] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"100.285434ms","start":"2026-05-06T09:28:31.740612Z","end":"2026-05-06T09:28:31.840897Z","steps":["trace[438863816] 'process raft request' (duration: 100.156311ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:28:31.955543Z","caller":"traceutil/trace.go:171","msg":"trace[1356647005] linearizableReadLoop","detail":"{readStateIndex:1745; appliedIndex:1744; }","duration":"100.226913ms","start":"2026-05-06T09:28:31.855284Z","end":"2026-05-06T09:28:31.955511Z","steps":["trace[1356647005] 'read index received' (duration: 69.155331ms)","trace[1356647005] 'applied index is now lower than readState.Index' (duration: 31.070122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T09:28:31.955671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.366176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T09:28:31.95575Z","caller":"traceutil/trace.go:171","msg":"trace[1310954685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1683; }","duration":"100.470358ms","start":"2026-05-06T09:28:31.855265Z","end":"2026-05-06T09:28:31.955735Z","steps":["trace[1310954685] 'agreement among raft nodes before linearized reading' (duration: 100.331975ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:28:31.955565Z","caller":"traceutil/trace.go:171","msg":"trace[1633453294] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"214.178999ms","start":"2026-05-06T09:28:31.741353Z","end":"2026-05-06T09:28:31.955532Z","steps":["trace[1633453294] 'process raft request' (duration: 183.072826ms)","trace[1633453294] 'compare' (duration: 30.913209ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T09:30:36.913306Z","caller":"traceutil/trace.go:171","msg":"trace[1868737732] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"217.098146ms","start":"2026-05-06T09:30:36.696163Z","end":"2026-05-06T09:30:36.913261Z","steps":["trace[1868737732] 'process raft request' (duration: 216.996364ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T09:30:36.913343Z","caller":"traceutil/trace.go:171","msg":"trace[1978365064] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"232.834127ms","start":"2026-05-06T09:30:36.680468Z","end":"2026-05-06T09:30:36.913302Z","steps":["trace[1978365064] 'process raft request' (duration: 193.180393ms)","trace[1978365064] 'compare' (duration: 38.943357ms)"],"step_count":2}