{"level":"warn","ts":"2026-05-26T15:55:06.687831Z","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-26T15:55:06.68798Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100: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.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-26T15:55:06.688088Z","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-26T15:55:06.688105Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-05-26T15:55:06.688152Z","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-26T15:55:06.689221Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-05-26T15:55:06.689382Z","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.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-26T15:55:06.69721Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.524612ms"} {"level":"info","ts":"2026-05-26T15:55:06.714609Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-05-26T15:55:06.71474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T15:55:06.714795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-05-26T15:55:06.714811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T15:55:06.714821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-05-26T15:55:06.714889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-05-26T15:55:06.722428Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T15:55:06.724223Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T15:55:06.725545Z","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-26T15:55:06.728984Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T15:55:06.732014Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T15:55:06.729538Z","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-26T15:55:06.732197Z","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-26T15:55:06.732257Z","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-26T15:55:06.739311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-05-26T15:55:06.739499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-05-26T15:55:06.74051Z","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-26T15:55:06.740668Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-05-26T15:55:06.740727Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-05-26T15:55:06.740885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T15:55:06.740913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T15:55:06.915539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T15:55:06.915596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T15:55:06.915619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-05-26T15:55:06.915638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-05-26T15:55:06.915649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-05-26T15:55:06.915664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-05-26T15:55:06.915677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-05-26T15:55:06.91664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:06.917301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:55:06.917302Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T15:55:06.917345Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:55:06.917823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T15:55:06.917903Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T15:55:06.921063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T15:55:06.921616Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:06.921733Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:06.921776Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:06.921091Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"warn","ts":"2026-05-26T15:55:11.668653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.09405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-05-26T15:55:11.668791Z","caller":"traceutil/trace.go:171","msg":"trace[2015596512] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:204; }","duration":"660.293057ms","start":"2026-05-26T15:55:11.008473Z","end":"2026-05-26T15:55:11.668766Z","steps":["trace[2015596512] 'range keys from in-memory index tree' (duration: 659.971928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:11.668847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:11.008453Z","time spent":"660.382898ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-26T15:55:12.223594Z","caller":"traceutil/trace.go:171","msg":"trace[710043171] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"550.179167ms","start":"2026-05-26T15:55:11.673362Z","end":"2026-05-26T15:55:12.223541Z","steps":["trace[710043171] 'process raft request' (duration: 550.000122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:12.224363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:11.673345Z","time spent":"550.409143ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:55:12.230244Z","caller":"traceutil/trace.go:171","msg":"trace[1181998838] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"361.757649ms","start":"2026-05-26T15:55:11.86844Z","end":"2026-05-26T15:55:12.230197Z","steps":["trace[1181998838] 'read index received' (duration: 354.950107ms)","trace[1181998838] 'applied index is now lower than readState.Index' (duration: 6.806912ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:55:12.230344Z","caller":"traceutil/trace.go:171","msg":"trace[865420616] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"554.42784ms","start":"2026-05-26T15:55:11.675909Z","end":"2026-05-26T15:55:12.230337Z","steps":["trace[865420616] 'process raft request' (duration: 554.178793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:12.230589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:11.675885Z","time spent":"554.478322ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:55:12.230665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.199252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:55:12.230805Z","caller":"traceutil/trace.go:171","msg":"trace[377528462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:206; }","duration":"362.352896ms","start":"2026-05-26T15:55:11.868397Z","end":"2026-05-26T15:55:12.23075Z","steps":["trace[377528462] 'agreement among raft nodes before linearized reading' (duration: 362.13636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:12.230874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:11.868378Z","time spent":"362.485209ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-26T15:55:42.958106Z","caller":"traceutil/trace.go:171","msg":"trace[1214502274] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"156.234995ms","start":"2026-05-26T15:55:42.801842Z","end":"2026-05-26T15:55:42.958077Z","steps":["trace[1214502274] 'process raft request' (duration: 156.087891ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.153614Z","caller":"traceutil/trace.go:171","msg":"trace[494983941] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"294.003644ms","start":"2026-05-26T15:55:42.859573Z","end":"2026-05-26T15:55:43.153576Z","steps":["trace[494983941] 'process raft request' (duration: 293.904862ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.153669Z","caller":"traceutil/trace.go:171","msg":"trace[850222970] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"293.973033ms","start":"2026-05-26T15:55:42.85965Z","end":"2026-05-26T15:55:43.153623Z","steps":["trace[850222970] 'process raft request' (duration: 293.86443ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.153875Z","caller":"traceutil/trace.go:171","msg":"trace[1061973965] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"288.831446ms","start":"2026-05-26T15:55:42.865025Z","end":"2026-05-26T15:55:43.153856Z","steps":["trace[1061973965] 'process raft request' (duration: 288.602899ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.153969Z","caller":"traceutil/trace.go:171","msg":"trace[1558125289] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"294.21935ms","start":"2026-05-26T15:55:42.85974Z","end":"2026-05-26T15:55:43.15396Z","steps":["trace[1558125289] 'process raft request' (duration: 293.811229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:43.154063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.025333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:55:43.15411Z","caller":"traceutil/trace.go:171","msg":"trace[1825304266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"298.116715ms","start":"2026-05-26T15:55:42.855986Z","end":"2026-05-26T15:55:43.154103Z","steps":["trace[1825304266] 'agreement among raft nodes before linearized reading' (duration: 298.001972ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.154113Z","caller":"traceutil/trace.go:171","msg":"trace[2139877792] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"291.550139ms","start":"2026-05-26T15:55:42.862548Z","end":"2026-05-26T15:55:43.154098Z","steps":["trace[2139877792] 'process raft request' (duration: 291.041735ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.154221Z","caller":"traceutil/trace.go:171","msg":"trace[1882141365] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"247.247272ms","start":"2026-05-26T15:55:42.906965Z","end":"2026-05-26T15:55:43.154212Z","steps":["trace[1882141365] 'process raft request' (duration: 246.890602ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.154275Z","caller":"traceutil/trace.go:171","msg":"trace[1680575118] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"246.186864ms","start":"2026-05-26T15:55:42.908055Z","end":"2026-05-26T15:55:43.154241Z","steps":["trace[1680575118] 'process raft request' (duration: 245.834004ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.153966Z","caller":"traceutil/trace.go:171","msg":"trace[1485653195] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"297.92783ms","start":"2026-05-26T15:55:42.856024Z","end":"2026-05-26T15:55:43.153952Z","steps":["trace[1485653195] 'read index received' (duration: 102.315921ms)","trace[1485653195] 'applied index is now lower than readState.Index' (duration: 195.610239ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:55:43.154351Z","caller":"traceutil/trace.go:171","msg":"trace[2011168895] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"288.99846ms","start":"2026-05-26T15:55:42.865342Z","end":"2026-05-26T15:55:43.154341Z","steps":["trace[2011168895] 'process raft request' (duration: 288.331673ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.154447Z","caller":"traceutil/trace.go:171","msg":"trace[1980564180] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"274.854961ms","start":"2026-05-26T15:55:42.87957Z","end":"2026-05-26T15:55:43.154425Z","steps":["trace[1980564180] 'process raft request' (duration: 274.213165ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.154549Z","caller":"traceutil/trace.go:171","msg":"trace[17843101] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"270.890736ms","start":"2026-05-26T15:55:42.883618Z","end":"2026-05-26T15:55:43.154509Z","steps":["trace[17843101] 'process raft request' (duration: 270.196097ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.154696Z","caller":"traceutil/trace.go:171","msg":"trace[418219761] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"307.137556ms","start":"2026-05-26T15:55:42.847547Z","end":"2026-05-26T15:55:43.154685Z","steps":["trace[418219761] 'process raft request' (duration: 305.7714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:43.154793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:42.847528Z","time spent":"307.225379ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:55:43.159566Z","caller":"traceutil/trace.go:171","msg":"trace[2034895225] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"124.559617ms","start":"2026-05-26T15:55:43.034975Z","end":"2026-05-26T15:55:43.159534Z","steps":["trace[2034895225] 'process raft request' (duration: 124.357831ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.483992Z","caller":"traceutil/trace.go:171","msg":"trace[1457994450] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"165.689709ms","start":"2026-05-26T15:55:43.318271Z","end":"2026-05-26T15:55:43.483961Z","steps":["trace[1457994450] 'process raft request' (duration: 100.79521ms)","trace[1457994450] 'compare' (duration: 64.734724ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:55:43.516039Z","caller":"traceutil/trace.go:171","msg":"trace[1817549416] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"161.693152ms","start":"2026-05-26T15:55:43.354313Z","end":"2026-05-26T15:55:43.516007Z","steps":["trace[1817549416] 'process raft request' (duration: 157.17546ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:55:43.516185Z","caller":"traceutil/trace.go:171","msg":"trace[1348430442] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"161.406514ms","start":"2026-05-26T15:55:43.354752Z","end":"2026-05-26T15:55:43.516159Z","steps":["trace[1348430442] 'process raft request' (duration: 156.857552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:44.113518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.700667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:55:44.113643Z","caller":"traceutil/trace.go:171","msg":"trace[1165206209] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"557.929093ms","start":"2026-05-26T15:55:43.555694Z","end":"2026-05-26T15:55:44.113623Z","steps":["trace[1165206209] 'process raft request' (duration: 33.889417ms)","trace[1165206209] 'compare' (duration: 523.570374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:55:44.113706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:43.555674Z","time spent":"558.002416ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:55:44.175222Z","caller":"traceutil/trace.go:171","msg":"trace[458715845] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"618.596759ms","start":"2026-05-26T15:55:43.556597Z","end":"2026-05-26T15:55:44.175194Z","steps":["trace[458715845] 'process raft request' (duration: 618.236509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:44.175375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:43.556568Z","time spent":"618.731852ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:55:44.175542Z","caller":"traceutil/trace.go:171","msg":"trace[1740711253] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"611.237041ms","start":"2026-05-26T15:55:43.564281Z","end":"2026-05-26T15:55:44.175518Z","steps":["trace[1740711253] 'process raft request' (duration: 610.80001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:44.175765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:43.564265Z","time spent":"611.354115ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:55:44.180213Z","caller":"traceutil/trace.go:171","msg":"trace[824713581] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"306.91218ms","start":"2026-05-26T15:55:43.873286Z","end":"2026-05-26T15:55:44.180198Z","steps":["trace[824713581] 'process raft request' (duration: 306.652463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:44.180291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:43.873267Z","time spent":"306.984952ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:55:49.993481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.885558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:55:49.993592Z","caller":"traceutil/trace.go:171","msg":"trace[547293695] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"663.516232ms","start":"2026-05-26T15:55:49.330057Z","end":"2026-05-26T15:55:49.993573Z","steps":["trace[547293695] 'process raft request' (duration: 209.46329ms)","trace[547293695] 'compare' (duration: 453.759724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:55:49.99365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:49.330029Z","time spent":"663.594364ms","remote":"127.0.0.1:40324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:55:50.039422Z","caller":"traceutil/trace.go:171","msg":"trace[302333186] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"196.913175ms","start":"2026-05-26T15:55:49.842481Z","end":"2026-05-26T15:55:50.039394Z","steps":["trace[302333186] 'read index received' (duration: 195.913677ms)","trace[302333186] 'applied index is now lower than readState.Index' (duration: 998.498µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:55:50.039554Z","caller":"traceutil/trace.go:171","msg":"trace[1303513320] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"316.211979ms","start":"2026-05-26T15:55:49.723317Z","end":"2026-05-26T15:55:50.039529Z","steps":["trace[1303513320] 'process raft request' (duration: 315.271964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:50.0397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:49.72329Z","time spent":"316.317372ms","remote":"127.0.0.1:40220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:55:50.039732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.218093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:55:50.040425Z","caller":"traceutil/trace.go:171","msg":"trace[1606515855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"197.381347ms","start":"2026-05-26T15:55:49.842433Z","end":"2026-05-26T15:55:50.039814Z","steps":["trace[1606515855] 'agreement among raft nodes before linearized reading' (duration: 197.13959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:50.83082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602860089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:55:51.331526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602860089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:55:51.429859Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.319480431s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T15:55:51.492047Z","caller":"traceutil/trace.go:171","msg":"trace[1664957435] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"1.161939142s","start":"2026-05-26T15:55:50.330076Z","end":"2026-05-26T15:55:51.492015Z","steps":["trace[1664957435] 'read index received' (duration: 1.100306221s)","trace[1664957435] 'applied index is now lower than readState.Index' (duration: 61.631711ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:55:51.492144Z","caller":"traceutil/trace.go:171","msg":"trace[944345227] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.381924484s","start":"2026-05-26T15:55:50.110198Z","end":"2026-05-26T15:55:51.492123Z","steps":["trace[944345227] 'process raft request' (duration: 1.32016065s)","trace[944345227] 'compare' (duration: 61.435935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:55:51.492298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055127881s","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-26T15:55:51.492359Z","caller":"traceutil/trace.go:171","msg":"trace[526751952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.055236554s","start":"2026-05-26T15:55:50.437107Z","end":"2026-05-26T15:55:51.492343Z","steps":["trace[526751952] 'agreement among raft nodes before linearized reading' (duration: 1.05509061s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:51.492294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16220918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-26T15:55:51.49241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:50.437079Z","time spent":"1.055318875s","remote":"127.0.0.1:40426","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":"info","ts":"2026-05-26T15:55:51.49265Z","caller":"traceutil/trace.go:171","msg":"trace[515328554] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:592; }","duration":"1.162403975s","start":"2026-05-26T15:55:50.330019Z","end":"2026-05-26T15:55:51.492423Z","steps":["trace[515328554] 'agreement among raft nodes before linearized reading' (duration: 1.162161619s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:51.492814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:50.329996Z","time spent":"1.162802415s","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.100\" "} {"level":"warn","ts":"2026-05-26T15:55:51.492935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:50.110173Z","time spent":"1.382018077s","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T15:55:51.493123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.772476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-26T15:55:51.493164Z","caller":"traceutil/trace.go:171","msg":"trace[509358002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"288.865908ms","start":"2026-05-26T15:55:51.204288Z","end":"2026-05-26T15:55:51.493154Z","steps":["trace[509358002] 'agreement among raft nodes before linearized reading' (duration: 288.702793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:51.493195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.051299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:55:51.493265Z","caller":"traceutil/trace.go:171","msg":"trace[1696749192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"651.147462ms","start":"2026-05-26T15:55:50.842099Z","end":"2026-05-26T15:55:51.493246Z","steps":["trace[1696749192] 'agreement among raft nodes before linearized reading' (duration: 651.027539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:51.49325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.782613ms","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-26T15:55:51.493322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:50.84208Z","time spent":"651.230554ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T15:55:51.493336Z","caller":"traceutil/trace.go:171","msg":"trace[317965082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"497.906957ms","start":"2026-05-26T15:55:50.995409Z","end":"2026-05-26T15:55:51.493316Z","steps":["trace[317965082] 'agreement among raft nodes before linearized reading' (duration: 497.720342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:51.493386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:50.995389Z","time spent":"497.988248ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T15:55:51.493275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039452211s","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-26T15:55:51.493515Z","caller":"traceutil/trace.go:171","msg":"trace[445306647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.039716049s","start":"2026-05-26T15:55:50.453784Z","end":"2026-05-26T15:55:51.4935Z","steps":["trace[445306647] 'agreement among raft nodes before linearized reading' (duration: 1.03939624s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:55:51.493581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:55:50.453763Z","time spent":"1.03980438s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T15:56:15.831893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.590263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lshgr\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-26T15:56:15.831941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.413772ms","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-26T15:56:15.831982Z","caller":"traceutil/trace.go:171","msg":"trace[1339254112] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lshgr; range_end:; response_count:1; response_revision:780; }","duration":"395.683626ms","start":"2026-05-26T15:56:15.436277Z","end":"2026-05-26T15:56:15.83196Z","steps":["trace[1339254112] 'range keys from in-memory index tree' (duration: 395.427099ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:15.832003Z","caller":"traceutil/trace.go:171","msg":"trace[1864798882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"211.505984ms","start":"2026-05-26T15:56:15.620482Z","end":"2026-05-26T15:56:15.831988Z","steps":["trace[1864798882] 'range keys from in-memory index tree' (duration: 211.276118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:15.832025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:15.436211Z","time spent":"395.805119ms","remote":"127.0.0.1:40324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lshgr\" "} {"level":"warn","ts":"2026-05-26T15:56:15.831919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.440166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-26T15:56:15.832095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.121467ms","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-26T15:56:15.832173Z","caller":"traceutil/trace.go:171","msg":"trace[695515885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"216.22585ms","start":"2026-05-26T15:56:15.61592Z","end":"2026-05-26T15:56:15.832146Z","steps":["trace[695515885] 'range keys from in-memory index tree' (duration: 215.988853ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:15.832185Z","caller":"traceutil/trace.go:171","msg":"trace[1454515744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"213.720893ms","start":"2026-05-26T15:56:15.618413Z","end":"2026-05-26T15:56:15.832134Z","steps":["trace[1454515744] 'range keys from in-memory index tree' (duration: 213.287972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:15.832422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.407961ms","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-26T15:56:15.832521Z","caller":"traceutil/trace.go:171","msg":"trace[929967019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"214.542605ms","start":"2026-05-26T15:56:15.617961Z","end":"2026-05-26T15:56:15.832504Z","steps":["trace[929967019] 'range keys from in-memory index tree' (duration: 213.676162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:50.822621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602860941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:56:51.323842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602860941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:56:51.328393Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.256185037s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T15:56:51.407982Z","caller":"traceutil/trace.go:171","msg":"trace[1554424113] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1036; }","duration":"1.085417462s","start":"2026-05-26T15:56:50.322542Z","end":"2026-05-26T15:56:51.40796Z","steps":["trace[1554424113] 'read index received' (duration: 1.006050036s)","trace[1554424113] 'applied index is now lower than readState.Index' (duration: 79.366616ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:56:51.408234Z","caller":"traceutil/trace.go:171","msg":"trace[2040483553] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.34741165s","start":"2026-05-26T15:56:50.060808Z","end":"2026-05-26T15:56:51.40822Z","steps":["trace[2040483553] 'process raft request' (duration: 1.267772477s)","trace[2040483553] 'compare' (duration: 78.941724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:56:51.408255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0856943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:56:51.408317Z","caller":"traceutil/trace.go:171","msg":"trace[1434241903] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:999; }","duration":"1.085801813s","start":"2026-05-26T15:56:50.322495Z","end":"2026-05-26T15:56:51.408297Z","steps":["trace[1434241903] 'agreement among raft nodes before linearized reading' (duration: 1.08570527s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:51.40835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:50.060793Z","time spent":"1.347488382s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:56:51.40838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:50.322476Z","time spent":"1.085885304s","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:56:51.408473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076022271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T15:56:51.408574Z","caller":"traceutil/trace.go:171","msg":"trace[500915388] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:999; }","duration":"1.076119644s","start":"2026-05-26T15:56:50.332415Z","end":"2026-05-26T15:56:51.408535Z","steps":["trace[500915388] 'agreement among raft nodes before linearized reading' (duration: 1.075874258s)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:51.408602Z","caller":"traceutil/trace.go:171","msg":"trace[643476863] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.347345038s","start":"2026-05-26T15:56:50.061242Z","end":"2026-05-26T15:56:51.408587Z","steps":["trace[643476863] 'process raft request' (duration: 1.346588379s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:51.408617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:50.332358Z","time spent":"1.076250317s","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.100\" "} {"level":"warn","ts":"2026-05-26T15:56:51.408692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:50.06122Z","time spent":"1.34742136s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:56:51.408705Z","caller":"traceutil/trace.go:171","msg":"trace[753889708] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"796.978956ms","start":"2026-05-26T15:56:50.611704Z","end":"2026-05-26T15:56:51.408683Z","steps":["trace[753889708] 'process raft request' (duration: 796.221966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:51.408833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:50.611666Z","time spent":"797.079739ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:56:51.40887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.081976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:56:51.408905Z","caller":"traceutil/trace.go:171","msg":"trace[1694776734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:999; }","duration":"770.148008ms","start":"2026-05-26T15:56:50.638746Z","end":"2026-05-26T15:56:51.408894Z","steps":["trace[1694776734] 'agreement among raft nodes before linearized reading' (duration: 770.089696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:51.408935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:50.63873Z","time spent":"770.195918ms","remote":"127.0.0.1:39558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:56:51.408948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.947251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-26T15:56:51.409042Z","caller":"traceutil/trace.go:171","msg":"trace[148686158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"164.095526ms","start":"2026-05-26T15:56:51.244926Z","end":"2026-05-26T15:56:51.409021Z","steps":["trace[148686158] 'agreement among raft nodes before linearized reading' (duration: 163.819658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.529469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.880298ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-26T15:56:52.529695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:51.410788Z","time spent":"1.11890149s","remote":"127.0.0.1:40162","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-26T15:56:52.529738Z","caller":"traceutil/trace.go:171","msg":"trace[1157515010] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1039; }","duration":"815.652218ms","start":"2026-05-26T15:56:51.714069Z","end":"2026-05-26T15:56:52.529722Z","steps":["trace[1157515010] 'read index received' (duration: 352.492692ms)","trace[1157515010] 'applied index is now lower than readState.Index' (duration: 463.158686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:56:52.529919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.835433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:56:52.529971Z","caller":"traceutil/trace.go:171","msg":"trace[605951045] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1001; }","duration":"815.939645ms","start":"2026-05-26T15:56:51.714019Z","end":"2026-05-26T15:56:52.529959Z","steps":["trace[605951045] 'agreement among raft nodes before linearized reading' (duration: 815.842813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.529997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:51.713999Z","time spent":"815.991936ms","remote":"127.0.0.1:40456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-26T15:56:52.53001Z","caller":"traceutil/trace.go:171","msg":"trace[1877065270] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.115518789s","start":"2026-05-26T15:56:51.414476Z","end":"2026-05-26T15:56:52.529994Z","steps":["trace[1877065270] 'process raft request' (duration: 1.115107688s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.530046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.377808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:56:52.530078Z","caller":"traceutil/trace.go:171","msg":"trace[520419621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"688.4716ms","start":"2026-05-26T15:56:51.841598Z","end":"2026-05-26T15:56:52.530069Z","steps":["trace[520419621] 'agreement among raft nodes before linearized reading' (duration: 688.391408ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:52.5301Z","caller":"traceutil/trace.go:171","msg":"trace[548318559] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.057557227s","start":"2026-05-26T15:56:51.472534Z","end":"2026-05-26T15:56:52.530091Z","steps":["trace[548318559] 'process raft request' (duration: 1.057152696s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.530147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.563434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T15:56:52.530223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:51.472513Z","time spent":"1.057607088s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:56:52.530126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:51.414451Z","time spent":"1.115604452s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:56:52.530306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.079169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-26T15:56:52.530102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:51.841578Z","time spent":"688.519212ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T15:56:52.53023Z","caller":"traceutil/trace.go:171","msg":"trace[1609094699] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1001; }","duration":"330.685547ms","start":"2026-05-26T15:56:52.199537Z","end":"2026-05-26T15:56:52.530222Z","steps":["trace[1609094699] 'agreement among raft nodes before linearized reading' (duration: 330.578615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.530698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:52.199514Z","time spent":"331.169381ms","remote":"127.0.0.1:39720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-26T15:56:52.530322Z","caller":"traceutil/trace.go:171","msg":"trace[985839408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"455.126491ms","start":"2026-05-26T15:56:52.075191Z","end":"2026-05-26T15:56:52.530317Z","steps":["trace[985839408] 'agreement among raft nodes before linearized reading' (duration: 455.052819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.530804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:52.07518Z","time spent":"455.617404ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T15:56:52.530366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.368455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-26T15:56:52.530909Z","caller":"traceutil/trace.go:171","msg":"trace[196813161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"471.95544ms","start":"2026-05-26T15:56:52.058945Z","end":"2026-05-26T15:56:52.5309Z","steps":["trace[196813161] 'agreement among raft nodes before linearized reading' (duration: 471.387985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.530936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:52.058908Z","time spent":"472.020113ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T15:56:52.5304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.319852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:56:52.530984Z","caller":"traceutil/trace.go:171","msg":"trace[508087222] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1001; }","duration":"571.936708ms","start":"2026-05-26T15:56:51.959035Z","end":"2026-05-26T15:56:52.530972Z","steps":["trace[508087222] 'agreement among raft nodes before linearized reading' (duration: 571.348813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:52.531062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:51.959014Z","time spent":"572.028171ms","remote":"127.0.0.1:40628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:57:13.969482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.14567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:13.969575Z","caller":"traceutil/trace.go:171","msg":"trace[414129319] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1073; }","duration":"284.269123ms","start":"2026-05-26T15:57:13.685288Z","end":"2026-05-26T15:57:13.969557Z","steps":["trace[414129319] 'count revisions from in-memory index tree' (duration: 284.054798ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:14.916891Z","caller":"traceutil/trace.go:171","msg":"trace[19190841] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"277.111222ms","start":"2026-05-26T15:57:14.639749Z","end":"2026-05-26T15:57:14.91686Z","steps":["trace[19190841] 'process raft request' (duration: 276.942307ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:14.970941Z","caller":"traceutil/trace.go:171","msg":"trace[739990862] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1118; }","duration":"205.556463ms","start":"2026-05-26T15:57:14.765361Z","end":"2026-05-26T15:57:14.970917Z","steps":["trace[739990862] 'read index received' (duration: 151.322605ms)","trace[739990862] 'applied index is now lower than readState.Index' (duration: 54.232858ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:14.971063Z","caller":"traceutil/trace.go:171","msg":"trace[1726998155] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"328.62544ms","start":"2026-05-26T15:57:14.642426Z","end":"2026-05-26T15:57:14.971051Z","steps":["trace[1726998155] 'process raft request' (duration: 328.450566ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:14.971037Z","caller":"traceutil/trace.go:171","msg":"trace[259572027] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"329.908062ms","start":"2026-05-26T15:57:14.641082Z","end":"2026-05-26T15:57:14.97099Z","steps":["trace[259572027] 'process raft request' (duration: 329.667756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:14.971248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:14.641058Z","time spent":"330.090997ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:57:14.971442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:14.642417Z","time spent":"328.705302ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:57:14.971522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.136671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:57:14.971588Z","caller":"traceutil/trace.go:171","msg":"trace[57525625] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1076; }","duration":"179.234374ms","start":"2026-05-26T15:57:14.792336Z","end":"2026-05-26T15:57:14.97157Z","steps":["trace[57525625] 'agreement among raft nodes before linearized reading' (duration: 179.045118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:14.971656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.244029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:14.971713Z","caller":"traceutil/trace.go:171","msg":"trace[183620210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"128.3192ms","start":"2026-05-26T15:57:14.843378Z","end":"2026-05-26T15:57:14.971697Z","steps":["trace[183620210] 'agreement among raft nodes before linearized reading' (duration: 128.212298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:14.971679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.273852ms","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-26T15:57:14.971795Z","caller":"traceutil/trace.go:171","msg":"trace[351318640] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1076; }","duration":"206.458726ms","start":"2026-05-26T15:57:14.765308Z","end":"2026-05-26T15:57:14.971767Z","steps":["trace[351318640] 'agreement among raft nodes before linearized reading' (duration: 206.20062ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:15.769201Z","caller":"traceutil/trace.go:171","msg":"trace[1629791855] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"154.780093ms","start":"2026-05-26T15:57:15.614372Z","end":"2026-05-26T15:57:15.769152Z","steps":["trace[1629791855] 'process raft request' (duration: 89.965636ms)","trace[1629791855] 'compare' (duration: 64.604081ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:17.686678Z","caller":"traceutil/trace.go:171","msg":"trace[1089647317] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"162.384025ms","start":"2026-05-26T15:57:17.524264Z","end":"2026-05-26T15:57:17.686648Z","steps":["trace[1089647317] 'process raft request' (duration: 162.039667ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:19.087118Z","caller":"traceutil/trace.go:171","msg":"trace[162656710] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"147.181071ms","start":"2026-05-26T15:57:18.939892Z","end":"2026-05-26T15:57:19.087073Z","steps":["trace[162656710] 'process raft request' (duration: 146.893543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:34.341434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602861408,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T15:57:34.348876Z","caller":"traceutil/trace.go:171","msg":"trace[171462921] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"507.681791ms","start":"2026-05-26T15:57:33.84117Z","end":"2026-05-26T15:57:34.348852Z","steps":["trace[171462921] 'read index received' (duration: 507.510446ms)","trace[171462921] 'applied index is now lower than readState.Index' (duration: 170.175µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:34.348974Z","caller":"traceutil/trace.go:171","msg":"trace[1659692190] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"608.647996ms","start":"2026-05-26T15:57:33.740293Z","end":"2026-05-26T15:57:34.348941Z","steps":["trace[1659692190] 'process raft request' (duration: 608.41188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:34.349027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.835254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T15:57:34.349086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.945665ms","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-26T15:57:34.349096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:33.740279Z","time spent":"608.753869ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:34.349105Z","caller":"traceutil/trace.go:171","msg":"trace[674614485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"507.962987ms","start":"2026-05-26T15:57:33.841123Z","end":"2026-05-26T15:57:34.349086Z","steps":["trace[674614485] 'agreement among raft nodes before linearized reading' (duration: 507.821394ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:34.349126Z","caller":"traceutil/trace.go:171","msg":"trace[978004111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"502.053277ms","start":"2026-05-26T15:57:33.847061Z","end":"2026-05-26T15:57:34.349114Z","steps":["trace[978004111] 'agreement among raft nodes before linearized reading' (duration: 501.908573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:34.349155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:33.841104Z","time spent":"508.0433ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T15:57:34.349179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:33.847043Z","time spent":"502.12683ms","remote":"127.0.0.1:40426","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-26T15:57:34.349197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.209133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:57:34.349236Z","caller":"traceutil/trace.go:171","msg":"trace[1754992108] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1189; }","duration":"471.290935ms","start":"2026-05-26T15:57:33.877935Z","end":"2026-05-26T15:57:34.349226Z","steps":["trace[1754992108] 'agreement among raft nodes before linearized reading' (duration: 471.218703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:34.349259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:33.877914Z","time spent":"471.339137ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:57:34.349361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.916046ms","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-26T15:57:34.349389Z","caller":"traceutil/trace.go:171","msg":"trace[870353500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"472.964278ms","start":"2026-05-26T15:57:33.876416Z","end":"2026-05-26T15:57:34.34938Z","steps":["trace[870353500] 'agreement among raft nodes before linearized reading' (duration: 472.888256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:34.349414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:33.876404Z","time spent":"473.003168ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T15:57:35.180026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.019006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:35.195206Z","caller":"traceutil/trace.go:171","msg":"trace[208953399] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"841.189804ms","start":"2026-05-26T15:57:34.353965Z","end":"2026-05-26T15:57:35.195155Z","steps":["trace[208953399] 'process raft request' (duration: 826.145172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:35.195303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:34.353936Z","time spent":"841.329828ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:35.19537Z","caller":"traceutil/trace.go:171","msg":"trace[973229609] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"841.740048ms","start":"2026-05-26T15:57:34.353622Z","end":"2026-05-26T15:57:35.195362Z","steps":["trace[973229609] 'process raft request' (duration: 143.321062ms)","trace[973229609] 'compare' (duration: 682.01204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:35.195399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:34.353607Z","time spent":"841.777469ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:49.430332Z","caller":"traceutil/trace.go:171","msg":"trace[1084835828] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"147.143498ms","start":"2026-05-26T15:57:49.283156Z","end":"2026-05-26T15:57:49.430299Z","steps":["trace[1084835828] 'process raft request' (duration: 94.525591ms)","trace[1084835828] 'compare' (duration: 52.376741ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:49.430517Z","caller":"traceutil/trace.go:171","msg":"trace[1965780483] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"147.244521ms","start":"2026-05-26T15:57:49.283245Z","end":"2026-05-26T15:57:49.430489Z","steps":["trace[1965780483] 'process raft request' (duration: 146.966714ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:49.430526Z","caller":"traceutil/trace.go:171","msg":"trace[1580001031] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1298; }","duration":"145.825425ms","start":"2026-05-26T15:57:49.284676Z","end":"2026-05-26T15:57:49.430502Z","steps":["trace[1580001031] 'read index received' (duration: 93.097525ms)","trace[1580001031] 'applied index is now lower than readState.Index' (duration: 52.72695ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:49.430554Z","caller":"traceutil/trace.go:171","msg":"trace[1783066904] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"146.485622ms","start":"2026-05-26T15:57:49.284038Z","end":"2026-05-26T15:57:49.430523Z","steps":["trace[1783066904] 'process raft request' (duration: 146.378849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:49.430719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.01937ms","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-26T15:57:49.430747Z","caller":"traceutil/trace.go:171","msg":"trace[1490346652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"146.084622ms","start":"2026-05-26T15:57:49.284656Z","end":"2026-05-26T15:57:49.43074Z","steps":["trace[1490346652] 'agreement among raft nodes before linearized reading' (duration: 145.921758ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:51.095169Z","caller":"traceutil/trace.go:171","msg":"trace[2002993771] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"252.717891ms","start":"2026-05-26T15:57:50.842428Z","end":"2026-05-26T15:57:51.095146Z","steps":["trace[2002993771] 'read index received' (duration: 252.488615ms)","trace[2002993771] 'applied index is now lower than readState.Index' (duration: 228.336µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:51.095225Z","caller":"traceutil/trace.go:171","msg":"trace[945832614] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"321.890668ms","start":"2026-05-26T15:57:50.773304Z","end":"2026-05-26T15:57:51.095195Z","steps":["trace[945832614] 'process raft request' (duration: 321.671903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.095355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:50.773271Z","time spent":"322.013332ms","remote":"127.0.0.1:40324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:57:51.095325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.871816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:51.095408Z","caller":"traceutil/trace.go:171","msg":"trace[846168123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"252.992869ms","start":"2026-05-26T15:57:50.842394Z","end":"2026-05-26T15:57:51.095387Z","steps":["trace[846168123] 'agreement among raft nodes before linearized reading' (duration: 252.848676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.573646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.064804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:51.573772Z","caller":"traceutil/trace.go:171","msg":"trace[818199484] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"472.087004ms","start":"2026-05-26T15:57:51.101665Z","end":"2026-05-26T15:57:51.573752Z","steps":["trace[818199484] 'process raft request' (duration: 186.833006ms)","trace[818199484] 'compare' (duration: 284.92162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:51.573836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:51.101657Z","time spent":"472.148237ms","remote":"127.0.0.1:40310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:51.637137Z","caller":"traceutil/trace.go:171","msg":"trace[710345307] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1314; }","duration":"354.97407ms","start":"2026-05-26T15:57:51.28214Z","end":"2026-05-26T15:57:51.637114Z","steps":["trace[710345307] 'read index received' (duration: 6.350711ms)","trace[710345307] 'applied index is now lower than readState.Index' (duration: 348.622399ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:51.637406Z","caller":"traceutil/trace.go:171","msg":"trace[772876894] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"528.697654ms","start":"2026-05-26T15:57:51.108646Z","end":"2026-05-26T15:57:51.637344Z","steps":["trace[772876894] 'process raft request' (duration: 528.405977ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:51.637482Z","caller":"traceutil/trace.go:171","msg":"trace[808025653] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"533.921507ms","start":"2026-05-26T15:57:51.103531Z","end":"2026-05-26T15:57:51.637453Z","steps":["trace[808025653] 'process raft request' (duration: 533.400044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.637529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.207888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T15:57:51.637588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:51.103506Z","time spent":"534.017219ms","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:51.637617Z","caller":"traceutil/trace.go:171","msg":"trace[1487426532] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1262; }","duration":"326.342562ms","start":"2026-05-26T15:57:51.311255Z","end":"2026-05-26T15:57:51.637597Z","steps":["trace[1487426532] 'agreement among raft nodes before linearized reading' (duration: 326.177618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.637669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:51.311241Z","time spent":"326.417934ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:57:51.637766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.613076ms","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-26T15:57:51.637658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.226258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-26T15:57:51.637847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.472734ms","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-26T15:57:51.637844Z","caller":"traceutil/trace.go:171","msg":"trace[1619780833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"355.75059ms","start":"2026-05-26T15:57:51.282076Z","end":"2026-05-26T15:57:51.637827Z","steps":["trace[1619780833] 'agreement among raft nodes before linearized reading' (duration: 355.492353ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:51.637875Z","caller":"traceutil/trace.go:171","msg":"trace[708171995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1262; }","duration":"202.539666ms","start":"2026-05-26T15:57:51.435329Z","end":"2026-05-26T15:57:51.637869Z","steps":["trace[708171995] 'agreement among raft nodes before linearized reading' (duration: 202.462054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.637785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.422976ms","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-26T15:57:51.63789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:51.282056Z","time spent":"355.826361ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-26T15:57:51.637906Z","caller":"traceutil/trace.go:171","msg":"trace[929358453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"197.56504ms","start":"2026-05-26T15:57:51.440331Z","end":"2026-05-26T15:57:51.637896Z","steps":["trace[929358453] 'agreement among raft nodes before linearized reading' (duration: 197.364955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.637926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.519135ms","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-26T15:57:51.637857Z","caller":"traceutil/trace.go:171","msg":"trace[1811831588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"202.458744ms","start":"2026-05-26T15:57:51.435379Z","end":"2026-05-26T15:57:51.637838Z","steps":["trace[1811831588] 'agreement among raft nodes before linearized reading' (duration: 202.135456ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:51.637974Z","caller":"traceutil/trace.go:171","msg":"trace[1107429865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"202.602997ms","start":"2026-05-26T15:57:51.435356Z","end":"2026-05-26T15:57:51.637959Z","steps":["trace[1107429865] 'agreement among raft nodes before linearized reading' (duration: 202.471924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:51.638482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:51.108636Z","time spent":"528.877029ms","remote":"127.0.0.1:40628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:57:52.548329Z","caller":"traceutil/trace.go:171","msg":"trace[1473508665] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"147.855137ms","start":"2026-05-26T15:57:52.400453Z","end":"2026-05-26T15:57:52.548308Z","steps":["trace[1473508665] 'process raft request' (duration: 147.756305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:53.004101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.336124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:53.004186Z","caller":"traceutil/trace.go:171","msg":"trace[1560929004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"160.659742ms","start":"2026-05-26T15:57:52.843503Z","end":"2026-05-26T15:57:53.004163Z","steps":["trace[1560929004] 'range keys from in-memory index tree' (duration: 160.226631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:54.0332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.746691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:54.033299Z","caller":"traceutil/trace.go:171","msg":"trace[216784292] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"158.424754ms","start":"2026-05-26T15:57:53.874857Z","end":"2026-05-26T15:57:54.033282Z","steps":["trace[216784292] 'read index received' (duration: 7.491129ms)","trace[216784292] 'applied index is now lower than readState.Index' (duration: 150.932335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:54.033381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.515877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:54.033411Z","caller":"traceutil/trace.go:171","msg":"trace[1648133341] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1276; }","duration":"158.573838ms","start":"2026-05-26T15:57:53.874828Z","end":"2026-05-26T15:57:54.033402Z","steps":["trace[1648133341] 'agreement among raft nodes before linearized reading' (duration: 158.500207ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:54.033775Z","caller":"traceutil/trace.go:171","msg":"trace[239000932] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"245.417305ms","start":"2026-05-26T15:57:53.788328Z","end":"2026-05-26T15:57:54.033746Z","steps":["trace[239000932] 'process raft request' (duration: 94.060539ms)","trace[239000932] 'compare' (duration: 150.619708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:54.732502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.286936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:57:54.732599Z","caller":"traceutil/trace.go:171","msg":"trace[398766092] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"586.445801ms","start":"2026-05-26T15:57:54.146135Z","end":"2026-05-26T15:57:54.732581Z","steps":["trace[398766092] 'read index received' (duration: 274.052553ms)","trace[398766092] 'applied index is now lower than readState.Index' (duration: 312.391907ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:54.732813Z","caller":"traceutil/trace.go:171","msg":"trace[390593832] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"644.66159ms","start":"2026-05-26T15:57:54.088137Z","end":"2026-05-26T15:57:54.732798Z","steps":["trace[390593832] 'process raft request' (duration: 332.005005ms)","trace[390593832] 'compare' (duration: 312.147182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:54.73289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:54.088123Z","time spent":"644.718931ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:57:54.73304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.904742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:54.733072Z","caller":"traceutil/trace.go:171","msg":"trace[25081870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"586.965184ms","start":"2026-05-26T15:57:54.146099Z","end":"2026-05-26T15:57:54.733064Z","steps":["trace[25081870] 'agreement among raft nodes before linearized reading' (duration: 586.909692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:54.733093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:54.146084Z","time spent":"587.004774ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T15:57:54.750777Z","caller":"traceutil/trace.go:171","msg":"trace[2070581553] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"544.491015ms","start":"2026-05-26T15:57:54.206268Z","end":"2026-05-26T15:57:54.750759Z","steps":["trace[2070581553] 'process raft request' (duration: 544.400863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:54.750887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:54.20624Z","time spent":"544.595808ms","remote":"127.0.0.1:40204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:57:54.975685Z","caller":"traceutil/trace.go:171","msg":"trace[842270559] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"243.041665ms","start":"2026-05-26T15:57:54.732624Z","end":"2026-05-26T15:57:54.975666Z","steps":["trace[842270559] 'read index received' (duration: 18.273164ms)","trace[842270559] 'applied index is now lower than readState.Index' (duration: 224.767581ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:57:54.975772Z","caller":"traceutil/trace.go:171","msg":"trace[1854358642] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"421.90618ms","start":"2026-05-26T15:57:54.553836Z","end":"2026-05-26T15:57:54.975743Z","steps":["trace[1854358642] 'process raft request' (duration: 407.392302ms)","trace[1854358642] 'compare' (duration: 13.82034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:57:54.975924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.487905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:54.975952Z","caller":"traceutil/trace.go:171","msg":"trace[639859800] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1281; }","duration":"629.68959ms","start":"2026-05-26T15:57:54.346256Z","end":"2026-05-26T15:57:54.975946Z","steps":["trace[639859800] 'agreement among raft nodes before linearized reading' (duration: 629.489235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:57:54.975971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:54.346234Z","time spent":"629.732281ms","remote":"127.0.0.1:40368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:57:54.976026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:57:54.553811Z","time spent":"422.139807ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:57:54.979561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.73741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:57:54.979591Z","caller":"traceutil/trace.go:171","msg":"trace[1116323339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"137.805412ms","start":"2026-05-26T15:57:54.841779Z","end":"2026-05-26T15:57:54.979584Z","steps":["trace[1116323339] 'agreement among raft nodes before linearized reading' (duration: 137.75262ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:57:54.979697Z","caller":"traceutil/trace.go:171","msg":"trace[1711006825] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"225.018697ms","start":"2026-05-26T15:57:54.754672Z","end":"2026-05-26T15:57:54.979691Z","steps":["trace[1711006825] 'process raft request' (duration: 224.814012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:03.89823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.477957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:03.898284Z","caller":"traceutil/trace.go:171","msg":"trace[562152854] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1342; }","duration":"152.58318ms","start":"2026-05-26T15:58:03.745689Z","end":"2026-05-26T15:58:03.898272Z","steps":["trace[562152854] 'count revisions from in-memory index tree' (duration: 152.374935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:04.342851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602861728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:58:04.843025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033911455602861728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T15:58:05.153152Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.405447034s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T15:58:05.153568Z","caller":"traceutil/trace.go:171","msg":"trace[861465548] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"1.311110361s","start":"2026-05-26T15:58:03.842434Z","end":"2026-05-26T15:58:05.153544Z","steps":["trace[861465548] 'read index received' (duration: 1.310876955s)","trace[861465548] 'applied index is now lower than readState.Index' (duration: 231.886µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:58:05.153703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311252635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:05.153768Z","caller":"traceutil/trace.go:171","msg":"trace[1501874418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"1.311363297s","start":"2026-05-26T15:58:03.842393Z","end":"2026-05-26T15:58:05.153756Z","steps":["trace[1501874418] 'agreement among raft nodes before linearized reading' (duration: 1.311242834s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.153822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.842375Z","time spent":"1.31143906s","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T15:58:05.154155Z","caller":"traceutil/trace.go:171","msg":"trace[60083069] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.405984787s","start":"2026-05-26T15:58:03.747603Z","end":"2026-05-26T15:58:05.153588Z","steps":["trace[60083069] 'process raft request' (duration: 1.405785502s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.154262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.747578Z","time spent":"1.406616573s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:58:05.644169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.801064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:58:05.644361Z","caller":"traceutil/trace.go:171","msg":"trace[1591039643] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"608.087372ms","start":"2026-05-26T15:58:05.036243Z","end":"2026-05-26T15:58:05.644331Z","steps":["trace[1591039643] 'process raft request' (duration: 192.047191ms)","trace[1591039643] 'compare' (duration: 415.691432ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:05.644392Z","caller":"traceutil/trace.go:171","msg":"trace[2013989731] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"490.741946ms","start":"2026-05-26T15:58:05.15363Z","end":"2026-05-26T15:58:05.644372Z","steps":["trace[2013989731] 'read index received' (duration: 74.674805ms)","trace[2013989731] 'applied index is now lower than readState.Index' (duration: 416.065031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:58:05.644536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:05.036219Z","time spent":"608.205365ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:58:05.647865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.671655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:05.647905Z","caller":"traceutil/trace.go:171","msg":"trace[360477339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"492.754247ms","start":"2026-05-26T15:58:05.15514Z","end":"2026-05-26T15:58:05.647894Z","steps":["trace[360477339] 'agreement among raft nodes before linearized reading' (duration: 492.695535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.647922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098114679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-26T15:58:05.647925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438224845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T15:58:05.647956Z","caller":"traceutil/trace.go:171","msg":"trace[603600257] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1344; }","duration":"1.098179711s","start":"2026-05-26T15:58:04.549768Z","end":"2026-05-26T15:58:05.647948Z","steps":["trace[603600257] 'agreement among raft nodes before linearized reading' (duration: 1.094782795s)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:05.647962Z","caller":"traceutil/trace.go:171","msg":"trace[114824105] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"1.438298386s","start":"2026-05-26T15:58:04.209653Z","end":"2026-05-26T15:58:05.647952Z","steps":["trace[114824105] 'agreement among raft nodes before linearized reading' (duration: 1.43491495s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.647979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:04.549752Z","time spent":"1.098222592s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-26T15:58:05.647988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:04.209635Z","time spent":"1.438346998s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-26T15:58:05.647999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770346358s","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-26T15:58:05.648018Z","caller":"traceutil/trace.go:171","msg":"trace[1756101118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.770382099s","start":"2026-05-26T15:58:03.877631Z","end":"2026-05-26T15:58:05.648013Z","steps":["trace[1756101118] 'agreement among raft nodes before linearized reading' (duration: 1.766945522s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.648035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.877621Z","time spent":"1.770409329s","remote":"127.0.0.1:40426","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-26T15:58:05.647931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:05.155119Z","time spent":"492.805249ms","remote":"127.0.0.1:40130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T15:58:05.648026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.297854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-26T15:58:05.648133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774982645s","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-26T15:58:05.648162Z","caller":"traceutil/trace.go:171","msg":"trace[1225660403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.77520621s","start":"2026-05-26T15:58:03.872951Z","end":"2026-05-26T15:58:05.648157Z","steps":["trace[1225660403] 'agreement among raft nodes before linearized reading' (duration: 1.77163956s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.648179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.872763Z","time spent":"1.775412296s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-26T15:58:05.648224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.421217ms","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":"warn","ts":"2026-05-26T15:58:05.64825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77515445s","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-26T15:58:05.648257Z","caller":"traceutil/trace.go:171","msg":"trace[400287994] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1344; }","duration":"309.503679ms","start":"2026-05-26T15:58:05.338746Z","end":"2026-05-26T15:58:05.648249Z","steps":["trace[400287994] 'agreement among raft nodes before linearized reading' (duration: 309.445787ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:05.648276Z","caller":"traceutil/trace.go:171","msg":"trace[163454481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"1.77519966s","start":"2026-05-26T15:58:03.873063Z","end":"2026-05-26T15:58:05.648263Z","steps":["trace[163454481] 'agreement among raft nodes before linearized reading' (duration: 1.771537157s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.648277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:05.33872Z","time spent":"309.55211ms","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-26T15:58:05.648293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.873044Z","time spent":"1.775245822s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T15:58:05.648298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781454769s","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-26T15:58:05.648346Z","caller":"traceutil/trace.go:171","msg":"trace[1831163474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"1.781528581s","start":"2026-05-26T15:58:03.866804Z","end":"2026-05-26T15:58:05.648333Z","steps":["trace[1831163474] 'agreement among raft nodes before linearized reading' (duration: 1.777805167s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.648379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.86679Z","time spent":"1.781580922s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T15:58:05.648448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775983391s","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-26T15:58:05.648624Z","caller":"traceutil/trace.go:171","msg":"trace[1162826451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"1.776124165s","start":"2026-05-26T15:58:03.872416Z","end":"2026-05-26T15:58:05.64854Z","steps":["trace[1162826451] 'agreement among raft nodes before linearized reading' (duration: 1.772209785s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.648927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:03.872216Z","time spent":"1.77669112s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T15:58:05.653438Z","caller":"traceutil/trace.go:171","msg":"trace[1646314894] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"907.725632ms","start":"2026-05-26T15:58:04.745672Z","end":"2026-05-26T15:58:05.653398Z","steps":["trace[1646314894] 'agreement among raft nodes before linearized reading' (duration: 898.841296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.653544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:04.745654Z","time spent":"907.863035ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-26T15:58:05.654073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431413052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:58:05.654116Z","caller":"traceutil/trace.go:171","msg":"trace[264136592] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1344; }","duration":"1.431502314s","start":"2026-05-26T15:58:04.222604Z","end":"2026-05-26T15:58:05.654107Z","steps":["trace[264136592] 'agreement among raft nodes before linearized reading' (duration: 1.421953561s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:05.654182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:58:04.222586Z","time spent":"1.431583747s","remote":"127.0.0.1:40310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-26T16:01:15.946765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.850551ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T16:01:15.946957Z","caller":"traceutil/trace.go:171","msg":"trace[1117479518] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"103.715403ms","start":"2026-05-26T16:01:15.843218Z","end":"2026-05-26T16:01:15.946933Z","steps":["trace[1117479518] 'read index received' (duration: 615.555µs)","trace[1117479518] 'applied index is now lower than readState.Index' (duration: 103.097828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:15.947067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.841806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:15.94716Z","caller":"traceutil/trace.go:171","msg":"trace[1000414948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2218; }","duration":"103.982779ms","start":"2026-05-26T16:01:15.843165Z","end":"2026-05-26T16:01:15.947148Z","steps":["trace[1000414948] 'agreement among raft nodes before linearized reading' (duration: 103.832876ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:18.407289Z","caller":"traceutil/trace.go:171","msg":"trace[981015133] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"115.982193ms","start":"2026-05-26T16:01:18.291282Z","end":"2026-05-26T16:01:18.407264Z","steps":["trace[981015133] 'process raft request' (duration: 115.84598ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:18.720495Z","caller":"traceutil/trace.go:171","msg":"trace[467270438] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"146.007546ms","start":"2026-05-26T16:01:18.574465Z","end":"2026-05-26T16:01:18.720473Z","steps":["trace[467270438] 'process raft request' (duration: 145.866693ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:18.740399Z","caller":"traceutil/trace.go:171","msg":"trace[1798583200] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2326; }","duration":"139.251135ms","start":"2026-05-26T16:01:18.601124Z","end":"2026-05-26T16:01:18.740375Z","steps":["trace[1798583200] 'read index received' (duration: 119.176156ms)","trace[1798583200] 'applied index is now lower than readState.Index' (duration: 20.074089ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:18.740511Z","caller":"traceutil/trace.go:171","msg":"trace[456148510] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"164.818544ms","start":"2026-05-26T16:01:18.575681Z","end":"2026-05-26T16:01:18.740499Z","steps":["trace[456148510] 'process raft request' (duration: 164.572507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:18.74075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.608514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-26T16:01:18.740789Z","caller":"traceutil/trace.go:171","msg":"trace[1246553190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2232; }","duration":"139.687237ms","start":"2026-05-26T16:01:18.601091Z","end":"2026-05-26T16:01:18.740779Z","steps":["trace[1246553190] 'agreement among raft nodes before linearized reading' (duration: 139.531803ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:19.025759Z","caller":"traceutil/trace.go:171","msg":"trace[1959799849] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"198.97118ms","start":"2026-05-26T16:01:18.826764Z","end":"2026-05-26T16:01:19.025735Z","steps":["trace[1959799849] 'process raft request' (duration: 198.889958ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:19.02589Z","caller":"traceutil/trace.go:171","msg":"trace[144236862] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"243.568253ms","start":"2026-05-26T16:01:18.782297Z","end":"2026-05-26T16:01:19.025866Z","steps":["trace[144236862] 'process raft request' (duration: 160.245028ms)","trace[144236862] 'compare' (duration: 82.905395ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:19.026074Z","caller":"traceutil/trace.go:171","msg":"trace[966511714] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2330; }","duration":"206.504772ms","start":"2026-05-26T16:01:18.819559Z","end":"2026-05-26T16:01:19.026063Z","steps":["trace[966511714] 'read index received' (duration: 122.996542ms)","trace[966511714] 'applied index is now lower than readState.Index' (duration: 83.50703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:19.026235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.405217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-26T16:01:19.026295Z","caller":"traceutil/trace.go:171","msg":"trace[129868561] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2236; }","duration":"267.50413ms","start":"2026-05-26T16:01:18.75878Z","end":"2026-05-26T16:01:19.026284Z","steps":["trace[129868561] 'agreement among raft nodes before linearized reading' (duration: 267.340896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:20.66841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.047874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:20.669478Z","caller":"traceutil/trace.go:171","msg":"trace[1615144171] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"223.349729ms","start":"2026-05-26T16:01:20.44611Z","end":"2026-05-26T16:01:20.66946Z","steps":["trace[1615144171] 'process raft request' (duration: 223.249427ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:20.669512Z","caller":"traceutil/trace.go:171","msg":"trace[1712834849] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"256.902891ms","start":"2026-05-26T16:01:20.412575Z","end":"2026-05-26T16:01:20.669478Z","steps":["trace[1712834849] 'process raft request' (duration: 130.698967ms)","trace[1712834849] 'compare' (duration: 124.88081ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:21.160975Z","caller":"traceutil/trace.go:171","msg":"trace[630028560] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"106.822591ms","start":"2026-05-26T16:01:21.054122Z","end":"2026-05-26T16:01:21.160945Z","steps":["trace[630028560] 'process raft request' (duration: 106.760989ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:21.161536Z","caller":"traceutil/trace.go:171","msg":"trace[806939169] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"106.854752ms","start":"2026-05-26T16:01:21.054096Z","end":"2026-05-26T16:01:21.160951Z","steps":["trace[806939169] 'process raft request' (duration: 50.221615ms)","trace[806939169] 'compare' (duration: 56.455793ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:21.845862Z","caller":"traceutil/trace.go:171","msg":"trace[1415203978] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"197.672358ms","start":"2026-05-26T16:01:21.648168Z","end":"2026-05-26T16:01:21.84584Z","steps":["trace[1415203978] 'process raft request' (duration: 197.548235ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:22.99933Z","caller":"traceutil/trace.go:171","msg":"trace[2081009137] linearizableReadLoop","detail":"{readStateIndex:2349; appliedIndex:2348; }","duration":"137.985733ms","start":"2026-05-26T16:01:22.861325Z","end":"2026-05-26T16:01:22.99931Z","steps":["trace[2081009137] 'read index received' (duration: 132.279568ms)","trace[2081009137] 'applied index is now lower than readState.Index' (duration: 5.705485ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:22.999421Z","caller":"traceutil/trace.go:171","msg":"trace[80343313] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"144.425096ms","start":"2026-05-26T16:01:22.854965Z","end":"2026-05-26T16:01:22.99939Z","steps":["trace[80343313] 'process raft request' (duration: 138.69782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:22.999661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.600996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T16:01:22.999691Z","caller":"traceutil/trace.go:171","msg":"trace[1450948642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2252; }","duration":"108.671758ms","start":"2026-05-26T16:01:22.891012Z","end":"2026-05-26T16:01:22.999684Z","steps":["trace[1450948642] 'agreement among raft nodes before linearized reading' (duration: 108.550505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:22.999852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.531836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-26T16:01:22.999878Z","caller":"traceutil/trace.go:171","msg":"trace[1890318629] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2252; }","duration":"138.586808ms","start":"2026-05-26T16:01:22.861282Z","end":"2026-05-26T16:01:22.999869Z","steps":["trace[1890318629] 'agreement among raft nodes before linearized reading' (duration: 138.230089ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:23.235864Z","caller":"traceutil/trace.go:171","msg":"trace[1556496444] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"230.517622ms","start":"2026-05-26T16:01:23.005318Z","end":"2026-05-26T16:01:23.235836Z","steps":["trace[1556496444] 'process raft request' (duration: 137.657295ms)","trace[1556496444] 'compare' (duration: 92.701393ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:23.236067Z","caller":"traceutil/trace.go:171","msg":"trace[1247493009] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"230.474341ms","start":"2026-05-26T16:01:23.005577Z","end":"2026-05-26T16:01:23.236051Z","steps":["trace[1247493009] 'process raft request' (duration: 230.219344ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:23.577628Z","caller":"traceutil/trace.go:171","msg":"trace[1531745251] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"337.056736ms","start":"2026-05-26T16:01:23.240535Z","end":"2026-05-26T16:01:23.577592Z","steps":["trace[1531745251] 'process raft request' (duration: 336.252735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:23.577947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:23.240519Z","time spent":"337.1905ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:24.892854Z","caller":"traceutil/trace.go:171","msg":"trace[719786880] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"169.8686ms","start":"2026-05-26T16:01:24.722957Z","end":"2026-05-26T16:01:24.892826Z","steps":["trace[719786880] 'process raft request' (duration: 169.516011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:25.396825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.541063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-26T16:01:25.396924Z","caller":"traceutil/trace.go:171","msg":"trace[273498647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2260; }","duration":"156.689107ms","start":"2026-05-26T16:01:25.240213Z","end":"2026-05-26T16:01:25.396902Z","steps":["trace[273498647] 'range keys from in-memory index tree' (duration: 156.364619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:25.39728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.269726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T16:01:25.397371Z","caller":"traceutil/trace.go:171","msg":"trace[179246243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2260; }","duration":"156.392849ms","start":"2026-05-26T16:01:25.240963Z","end":"2026-05-26T16:01:25.397356Z","steps":["trace[179246243] 'range keys from in-memory index tree' (duration: 156.148733ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:26.043489Z","caller":"traceutil/trace.go:171","msg":"trace[1970848726] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"642.624481ms","start":"2026-05-26T16:01:25.400844Z","end":"2026-05-26T16:01:26.043468Z","steps":["trace[1970848726] 'process raft request' (duration: 642.389465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:26.043705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:25.400826Z","time spent":"642.737344ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:26.34132Z","caller":"traceutil/trace.go:171","msg":"trace[729722356] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"940.113833ms","start":"2026-05-26T16:01:25.401177Z","end":"2026-05-26T16:01:26.341291Z","steps":["trace[729722356] 'process raft request' (duration: 899.81801ms)","trace[729722356] 'compare' (duration: 39.871262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:26.341588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:25.401164Z","time spent":"940.318858ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:26.341375Z","caller":"traceutil/trace.go:171","msg":"trace[1880505528] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"939.299733ms","start":"2026-05-26T16:01:25.402032Z","end":"2026-05-26T16:01:26.341332Z","steps":["trace[1880505528] 'process raft request' (duration: 939.073857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:26.341906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:25.402022Z","time spent":"939.751494ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:26.402765Z","caller":"traceutil/trace.go:171","msg":"trace[490535235] linearizableReadLoop","detail":"{readStateIndex:2360; appliedIndex:2357; }","duration":"819.806069ms","start":"2026-05-26T16:01:25.582932Z","end":"2026-05-26T16:01:26.402738Z","steps":["trace[490535235] 'read index received' (duration: 460.401346ms)","trace[490535235] 'applied index is now lower than readState.Index' (duration: 359.403063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:26.402964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.617021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:26.403037Z","caller":"traceutil/trace.go:171","msg":"trace[1788734708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2263; }","duration":"560.698083ms","start":"2026-05-26T16:01:25.842316Z","end":"2026-05-26T16:01:26.403014Z","steps":["trace[1788734708] 'agreement among raft nodes before linearized reading' (duration: 560.57491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:26.403026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.463687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T16:01:26.403077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:25.842256Z","time spent":"560.813266ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T16:01:26.403076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.091316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-05-26T16:01:26.403134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.685164ms","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-26T16:01:26.40315Z","caller":"traceutil/trace.go:171","msg":"trace[1095689870] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2263; }","duration":"363.548359ms","start":"2026-05-26T16:01:26.039526Z","end":"2026-05-26T16:01:26.403074Z","steps":["trace[1095689870] 'agreement among raft nodes before linearized reading' (duration: 363.417446ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:26.403163Z","caller":"traceutil/trace.go:171","msg":"trace[1251045368] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2263; }","duration":"820.273682ms","start":"2026-05-26T16:01:25.582874Z","end":"2026-05-26T16:01:26.403148Z","steps":["trace[1251045368] 'agreement among raft nodes before linearized reading' (duration: 820.009945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:26.403158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.905074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-26T16:01:26.403199Z","caller":"traceutil/trace.go:171","msg":"trace[712215256] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2263; }","duration":"408.827377ms","start":"2026-05-26T16:01:25.994358Z","end":"2026-05-26T16:01:26.403185Z","steps":["trace[712215256] 'agreement among raft nodes before linearized reading' (duration: 408.638812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:26.403215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:25.582794Z","time spent":"820.406605ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-26T16:01:26.403224Z","caller":"traceutil/trace.go:171","msg":"trace[1538801106] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2263; }","duration":"237.004316ms","start":"2026-05-26T16:01:26.166207Z","end":"2026-05-26T16:01:26.403211Z","steps":["trace[1538801106] 'agreement among raft nodes before linearized reading' (duration: 236.731189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:26.403217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:26.039483Z","time spent":"363.717823ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-26T16:01:26.40323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:25.994335Z","time spent":"408.890309ms","remote":"127.0.0.1:40480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-26T16:01:26.403512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.853415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T16:01:26.403627Z","caller":"traceutil/trace.go:171","msg":"trace[583023971] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2263; }","duration":"152.035989ms","start":"2026-05-26T16:01:26.251564Z","end":"2026-05-26T16:01:26.4036Z","steps":["trace[583023971] 'agreement among raft nodes before linearized reading' (duration: 151.407154ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:26.676443Z","caller":"traceutil/trace.go:171","msg":"trace[288587] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"268.127175ms","start":"2026-05-26T16:01:26.408292Z","end":"2026-05-26T16:01:26.676419Z","steps":["trace[288587] 'process raft request' (duration: 267.92616ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:26.695372Z","caller":"traceutil/trace.go:171","msg":"trace[1051924947] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"285.043196ms","start":"2026-05-26T16:01:26.410315Z","end":"2026-05-26T16:01:26.695358Z","steps":["trace[1051924947] 'process raft request' (duration: 285.013925ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:26.695414Z","caller":"traceutil/trace.go:171","msg":"trace[791468776] transaction","detail":"{read_only:false; response_revision:2265; number_of_response:1; }","duration":"286.842621ms","start":"2026-05-26T16:01:26.408539Z","end":"2026-05-26T16:01:26.695382Z","steps":["trace[791468776] 'process raft request' (duration: 286.717748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:27.125584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.090867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:27.125687Z","caller":"traceutil/trace.go:171","msg":"trace[259153093] linearizableReadLoop","detail":"{readStateIndex:2366; appliedIndex:2365; }","duration":"128.715877ms","start":"2026-05-26T16:01:26.996957Z","end":"2026-05-26T16:01:27.125672Z","steps":["trace[259153093] 'read index received' (duration: 28.86µs)","trace[259153093] 'applied index is now lower than readState.Index' (duration: 128.685767ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:27.125787Z","caller":"traceutil/trace.go:171","msg":"trace[1461596396] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"227.70306ms","start":"2026-05-26T16:01:26.898064Z","end":"2026-05-26T16:01:27.125767Z","steps":["trace[1461596396] 'process raft request' (duration: 94.304265ms)","trace[1461596396] 'compare' (duration: 132.913253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:27.126329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.365274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-26T16:01:27.126399Z","caller":"traceutil/trace.go:171","msg":"trace[989536957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2268; }","duration":"129.472277ms","start":"2026-05-26T16:01:26.996911Z","end":"2026-05-26T16:01:27.126383Z","steps":["trace[989536957] 'agreement among raft nodes before linearized reading' (duration: 129.252221ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:27.232905Z","caller":"traceutil/trace.go:171","msg":"trace[525633242] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"101.727352ms","start":"2026-05-26T16:01:27.131149Z","end":"2026-05-26T16:01:27.232876Z","steps":["trace[525633242] 'process raft request' (duration: 101.164318ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:27.2766Z","caller":"traceutil/trace.go:171","msg":"trace[6819601] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"145.426962ms","start":"2026-05-26T16:01:27.131147Z","end":"2026-05-26T16:01:27.276574Z","steps":["trace[6819601] 'process raft request' (duration: 145.304699ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:28.301605Z","caller":"traceutil/trace.go:171","msg":"trace[650973937] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"182.557634ms","start":"2026-05-26T16:01:28.119013Z","end":"2026-05-26T16:01:28.301571Z","steps":["trace[650973937] 'process raft request' (duration: 87.679906ms)","trace[650973937] 'compare' (duration: 94.687723ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:28.886401Z","caller":"traceutil/trace.go:171","msg":"trace[1745844597] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"203.181498ms","start":"2026-05-26T16:01:28.68319Z","end":"2026-05-26T16:01:28.886372Z","steps":["trace[1745844597] 'process raft request' (duration: 202.969992ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:29.479878Z","caller":"traceutil/trace.go:171","msg":"trace[1247269239] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"144.723564ms","start":"2026-05-26T16:01:29.335134Z","end":"2026-05-26T16:01:29.479858Z","steps":["trace[1247269239] 'process raft request' (duration: 105.505418ms)","trace[1247269239] 'compare' (duration: 39.057672ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:29.480019Z","caller":"traceutil/trace.go:171","msg":"trace[721692285] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"107.568621ms","start":"2026-05-26T16:01:29.372426Z","end":"2026-05-26T16:01:29.479995Z","steps":["trace[721692285] 'process raft request' (duration: 107.396937ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:30.298719Z","caller":"traceutil/trace.go:171","msg":"trace[940125635] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"177.238899ms","start":"2026-05-26T16:01:30.121446Z","end":"2026-05-26T16:01:30.298685Z","steps":["trace[940125635] 'process raft request' (duration: 176.975022ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:30.591997Z","caller":"traceutil/trace.go:171","msg":"trace[706831303] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"113.974674ms","start":"2026-05-26T16:01:30.477995Z","end":"2026-05-26T16:01:30.59197Z","steps":["trace[706831303] 'process raft request' (duration: 113.936963ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:30.592088Z","caller":"traceutil/trace.go:171","msg":"trace[326959520] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"114.136338ms","start":"2026-05-26T16:01:30.47792Z","end":"2026-05-26T16:01:30.592057Z","steps":["trace[326959520] 'process raft request' (duration: 113.959163ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:30.592216Z","caller":"traceutil/trace.go:171","msg":"trace[716461727] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"117.782069ms","start":"2026-05-26T16:01:30.474221Z","end":"2026-05-26T16:01:30.592003Z","steps":["trace[716461727] 'process raft request' (duration: 114.27908ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:31.243145Z","caller":"traceutil/trace.go:171","msg":"trace[496707764] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"118.578019ms","start":"2026-05-26T16:01:31.124486Z","end":"2026-05-26T16:01:31.243064Z","steps":["trace[496707764] 'process raft request' (duration: 74.902811ms)","trace[496707764] 'compare' (duration: 43.509374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:31.625697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.408247ms","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-26T16:01:31.625761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.156658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T16:01:31.625812Z","caller":"traceutil/trace.go:171","msg":"trace[1097463366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2286; }","duration":"140.233729ms","start":"2026-05-26T16:01:31.485564Z","end":"2026-05-26T16:01:31.625798Z","steps":["trace[1097463366] 'range keys from in-memory index tree' (duration: 139.953602ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:31.62581Z","caller":"traceutil/trace.go:171","msg":"trace[1555970280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2286; }","duration":"239.574631ms","start":"2026-05-26T16:01:31.386212Z","end":"2026-05-26T16:01:31.625787Z","steps":["trace[1555970280] 'range keys from in-memory index tree' (duration: 239.212272ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:31.794248Z","caller":"traceutil/trace.go:171","msg":"trace[162081712] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"164.017914ms","start":"2026-05-26T16:01:31.630207Z","end":"2026-05-26T16:01:31.794225Z","steps":["trace[162081712] 'process raft request' (duration: 163.791038ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:31.79456Z","caller":"traceutil/trace.go:171","msg":"trace[2064094214] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"164.316202ms","start":"2026-05-26T16:01:31.630224Z","end":"2026-05-26T16:01:31.79454Z","steps":["trace[2064094214] 'process raft request' (duration: 163.965553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:32.023175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.959716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:32.023424Z","caller":"traceutil/trace.go:171","msg":"trace[1927596548] linearizableReadLoop","detail":"{readStateIndex:2389; appliedIndex:2387; }","duration":"181.393435ms","start":"2026-05-26T16:01:31.84201Z","end":"2026-05-26T16:01:32.023404Z","steps":["trace[1927596548] 'read index received' (duration: 18.13121ms)","trace[1927596548] 'applied index is now lower than readState.Index' (duration: 163.260765ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:32.023604Z","caller":"traceutil/trace.go:171","msg":"trace[29840225] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"219.722888ms","start":"2026-05-26T16:01:31.803869Z","end":"2026-05-26T16:01:32.023592Z","steps":["trace[29840225] 'process raft request' (duration: 219.402919ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:32.023754Z","caller":"traceutil/trace.go:171","msg":"trace[404841480] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"222.609671ms","start":"2026-05-26T16:01:31.801017Z","end":"2026-05-26T16:01:32.023626Z","steps":["trace[404841480] 'process raft request' (duration: 59.0723ms)","trace[404841480] 'compare' (duration: 162.874644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:32.023894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.864906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:32.023972Z","caller":"traceutil/trace.go:171","msg":"trace[1105329165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2290; }","duration":"181.99543ms","start":"2026-05-26T16:01:31.841959Z","end":"2026-05-26T16:01:32.023954Z","steps":["trace[1105329165] 'agreement among raft nodes before linearized reading' (duration: 181.839176ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:32.183571Z","caller":"traceutil/trace.go:171","msg":"trace[1548008692] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"154.716549ms","start":"2026-05-26T16:01:32.028826Z","end":"2026-05-26T16:01:32.183543Z","steps":["trace[1548008692] 'process raft request' (duration: 111.326026ms)","trace[1548008692] 'compare' (duration: 43.262059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:32.448141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.507387ms","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-26T16:01:32.448226Z","caller":"traceutil/trace.go:171","msg":"trace[1672558294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2291; }","duration":"144.672001ms","start":"2026-05-26T16:01:32.303535Z","end":"2026-05-26T16:01:32.448207Z","steps":["trace[1672558294] 'range keys from in-memory index tree' (duration: 144.26428ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:32.833602Z","caller":"traceutil/trace.go:171","msg":"trace[1515361998] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"204.317325ms","start":"2026-05-26T16:01:32.629257Z","end":"2026-05-26T16:01:32.833574Z","steps":["trace[1515361998] 'process raft request' (duration: 118.487407ms)","trace[1515361998] 'compare' (duration: 85.722656ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:33.917302Z","caller":"traceutil/trace.go:171","msg":"trace[661488529] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"114.914527ms","start":"2026-05-26T16:01:33.802365Z","end":"2026-05-26T16:01:33.917279Z","steps":["trace[661488529] 'process raft request' (duration: 114.726572ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:34.004162Z","caller":"traceutil/trace.go:171","msg":"trace[1345527395] linearizableReadLoop","detail":"{readStateIndex:2398; appliedIndex:2396; }","duration":"161.645573ms","start":"2026-05-26T16:01:33.842491Z","end":"2026-05-26T16:01:34.004136Z","steps":["trace[1345527395] 'read index received' (duration: 74.729868ms)","trace[1345527395] 'applied index is now lower than readState.Index' (duration: 86.914415ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:34.004287Z","caller":"traceutil/trace.go:171","msg":"trace[699140983] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"199.275649ms","start":"2026-05-26T16:01:33.804987Z","end":"2026-05-26T16:01:34.004262Z","steps":["trace[699140983] 'process raft request' (duration: 156.740148ms)","trace[699140983] 'compare' (duration: 42.293905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:34.004373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.853228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:34.00446Z","caller":"traceutil/trace.go:171","msg":"trace[1821091638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2299; }","duration":"161.991032ms","start":"2026-05-26T16:01:33.842447Z","end":"2026-05-26T16:01:34.004438Z","steps":["trace[1821091638] 'agreement among raft nodes before linearized reading' (duration: 161.833568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:34.856593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.433528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T16:01:34.856676Z","caller":"traceutil/trace.go:171","msg":"trace[1448926348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2300; }","duration":"163.553692ms","start":"2026-05-26T16:01:34.6931Z","end":"2026-05-26T16:01:34.856654Z","steps":["trace[1448926348] 'range keys from in-memory index tree' (duration: 163.223003ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:35.454993Z","caller":"traceutil/trace.go:171","msg":"trace[1917409599] linearizableReadLoop","detail":"{readStateIndex:2403; appliedIndex:2402; }","duration":"103.24541ms","start":"2026-05-26T16:01:35.35172Z","end":"2026-05-26T16:01:35.454965Z","steps":["trace[1917409599] 'read index received' (duration: 102.934082ms)","trace[1917409599] 'applied index is now lower than readState.Index' (duration: 310.028µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:35.455061Z","caller":"traceutil/trace.go:171","msg":"trace[1285356412] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"126.903072ms","start":"2026-05-26T16:01:35.328125Z","end":"2026-05-26T16:01:35.455028Z","steps":["trace[1285356412] 'process raft request' (duration: 126.640695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:35.455258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.512168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:35.455335Z","caller":"traceutil/trace.go:171","msg":"trace[233525514] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2304; }","duration":"103.60821ms","start":"2026-05-26T16:01:35.351703Z","end":"2026-05-26T16:01:35.455311Z","steps":["trace[233525514] 'agreement among raft nodes before linearized reading' (duration: 103.371424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:35.647924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.402904ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T16:01:36.649908Z","caller":"traceutil/trace.go:171","msg":"trace[492763730] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"100.670755ms","start":"2026-05-26T16:01:36.549217Z","end":"2026-05-26T16:01:36.649888Z","steps":["trace[492763730] 'process raft request' (duration: 100.517151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:37.069343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.905423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-26T16:01:37.069377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.355215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:37.069423Z","caller":"traceutil/trace.go:171","msg":"trace[716343601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2307; }","duration":"145.998625ms","start":"2026-05-26T16:01:36.923407Z","end":"2026-05-26T16:01:37.069406Z","steps":["trace[716343601] 'range keys from in-memory index tree' (duration: 145.75046ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:37.069426Z","caller":"traceutil/trace.go:171","msg":"trace[334492997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2307; }","duration":"226.438478ms","start":"2026-05-26T16:01:36.842973Z","end":"2026-05-26T16:01:37.069412Z","steps":["trace[334492997] 'range keys from in-memory index tree' (duration: 226.283754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:37.070932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.426708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T16:01:37.071173Z","caller":"traceutil/trace.go:171","msg":"trace[162765699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2307; }","duration":"125.114265ms","start":"2026-05-26T16:01:36.94588Z","end":"2026-05-26T16:01:37.070994Z","steps":["trace[162765699] 'range keys from in-memory index tree' (duration: 123.421183ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:37.270754Z","caller":"traceutil/trace.go:171","msg":"trace[2104160447] transaction","detail":"{read_only:false; response_revision:2308; number_of_response:1; }","duration":"196.745485ms","start":"2026-05-26T16:01:37.07398Z","end":"2026-05-26T16:01:37.270726Z","steps":["trace[2104160447] 'process raft request' (duration: 196.492188ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:37.271058Z","caller":"traceutil/trace.go:171","msg":"trace[923362145] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"197.046483ms","start":"2026-05-26T16:01:37.073998Z","end":"2026-05-26T16:01:37.271044Z","steps":["trace[923362145] 'process raft request' (duration: 196.650763ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:37.316926Z","caller":"traceutil/trace.go:171","msg":"trace[88565386] linearizableReadLoop","detail":"{readStateIndex:2411; appliedIndex:2407; }","duration":"128.115262ms","start":"2026-05-26T16:01:37.188793Z","end":"2026-05-26T16:01:37.316908Z","steps":["trace[88565386] 'read index received' (duration: 81.693394ms)","trace[88565386] 'applied index is now lower than readState.Index' (duration: 46.420958ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:37.317019Z","caller":"traceutil/trace.go:171","msg":"trace[66739713] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"242.490315ms","start":"2026-05-26T16:01:37.074473Z","end":"2026-05-26T16:01:37.316963Z","steps":["trace[66739713] 'process raft request' (duration: 242.311951ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:37.317116Z","caller":"traceutil/trace.go:171","msg":"trace[1610119045] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"240.679749ms","start":"2026-05-26T16:01:37.076366Z","end":"2026-05-26T16:01:37.317046Z","steps":["trace[1610119045] 'process raft request' (duration: 240.505755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:37.317387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.578024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-26T16:01:37.317654Z","caller":"traceutil/trace.go:171","msg":"trace[1821921890] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2311; }","duration":"128.817559ms","start":"2026-05-26T16:01:37.188747Z","end":"2026-05-26T16:01:37.317564Z","steps":["trace[1821921890] 'agreement among raft nodes before linearized reading' (duration: 128.324447ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:37.44063Z","caller":"traceutil/trace.go:171","msg":"trace[457910850] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"117.844511ms","start":"2026-05-26T16:01:37.322758Z","end":"2026-05-26T16:01:37.440602Z","steps":["trace[457910850] 'process raft request' (duration: 76.02674ms)","trace[457910850] 'compare' (duration: 41.710979ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:38.173941Z","caller":"traceutil/trace.go:171","msg":"trace[1441934865] linearizableReadLoop","detail":"{readStateIndex:2415; appliedIndex:2414; }","duration":"113.927752ms","start":"2026-05-26T16:01:38.059987Z","end":"2026-05-26T16:01:38.173914Z","steps":["trace[1441934865] 'read index received' (duration: 113.683106ms)","trace[1441934865] 'applied index is now lower than readState.Index' (duration: 243.466µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:38.173989Z","caller":"traceutil/trace.go:171","msg":"trace[608576965] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"193.739297ms","start":"2026-05-26T16:01:37.980223Z","end":"2026-05-26T16:01:38.173962Z","steps":["trace[608576965] 'process raft request' (duration: 193.548682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:38.174197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.192958ms","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-26T16:01:38.174255Z","caller":"traceutil/trace.go:171","msg":"trace[1476265648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2315; }","duration":"114.295811ms","start":"2026-05-26T16:01:38.059945Z","end":"2026-05-26T16:01:38.174241Z","steps":["trace[1476265648] 'agreement among raft nodes before linearized reading' (duration: 114.112886ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:38.500462Z","caller":"traceutil/trace.go:171","msg":"trace[1184879310] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"121.931226ms","start":"2026-05-26T16:01:38.378508Z","end":"2026-05-26T16:01:38.50044Z","steps":["trace[1184879310] 'process raft request' (duration: 121.797603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:38.821613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.392408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:01:38.821861Z","caller":"traceutil/trace.go:171","msg":"trace[1310172915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2317; }","duration":"167.659916ms","start":"2026-05-26T16:01:38.654134Z","end":"2026-05-26T16:01:38.821794Z","steps":["trace[1310172915] 'range keys from in-memory index tree' (duration: 167.162692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:39.562639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.032037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:39.562824Z","caller":"traceutil/trace.go:171","msg":"trace[238515863] linearizableReadLoop","detail":"{readStateIndex:2420; appliedIndex:2419; }","duration":"195.354549ms","start":"2026-05-26T16:01:39.367452Z","end":"2026-05-26T16:01:39.562806Z","steps":["trace[238515863] 'read index received' (duration: 60.049635ms)","trace[238515863] 'applied index is now lower than readState.Index' (duration: 135.303344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:39.562991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.533134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-26T16:01:39.563059Z","caller":"traceutil/trace.go:171","msg":"trace[33422165] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2320; }","duration":"195.601985ms","start":"2026-05-26T16:01:39.367447Z","end":"2026-05-26T16:01:39.563049Z","steps":["trace[33422165] 'agreement among raft nodes before linearized reading' (duration: 195.437571ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:39.563148Z","caller":"traceutil/trace.go:171","msg":"trace[1727049571] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"236.597525ms","start":"2026-05-26T16:01:39.326519Z","end":"2026-05-26T16:01:39.563117Z","steps":["trace[1727049571] 'process raft request' (duration: 100.986743ms)","trace[1727049571] 'compare' (duration: 134.855123ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:39.762154Z","caller":"traceutil/trace.go:171","msg":"trace[1215068936] transaction","detail":"{read_only:false; response_revision:2321; number_of_response:1; }","duration":"266.804041ms","start":"2026-05-26T16:01:39.49533Z","end":"2026-05-26T16:01:39.762134Z","steps":["trace[1215068936] 'process raft request' (duration: 248.718753ms)","trace[1215068936] 'compare' (duration: 18.007966ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:39.782419Z","caller":"traceutil/trace.go:171","msg":"trace[1351888770] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"260.539003ms","start":"2026-05-26T16:01:39.521855Z","end":"2026-05-26T16:01:39.782394Z","steps":["trace[1351888770] 'process raft request' (duration: 260.356669ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:39.782584Z","caller":"traceutil/trace.go:171","msg":"trace[119820714] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"214.920404ms","start":"2026-05-26T16:01:39.567634Z","end":"2026-05-26T16:01:39.782554Z","steps":["trace[119820714] 'process raft request' (duration: 214.715789ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:41.459331Z","caller":"traceutil/trace.go:171","msg":"trace[1309665644] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"165.679536ms","start":"2026-05-26T16:01:41.293627Z","end":"2026-05-26T16:01:41.459307Z","steps":["trace[1309665644] 'process raft request' (duration: 165.534562ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:41.642833Z","caller":"traceutil/trace.go:171","msg":"trace[1269780627] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"159.774574ms","start":"2026-05-26T16:01:41.483033Z","end":"2026-05-26T16:01:41.642807Z","steps":["trace[1269780627] 'process raft request' (duration: 102.569853ms)","trace[1269780627] 'compare' (duration: 56.728359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:42.178037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.778698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:42.178142Z","caller":"traceutil/trace.go:171","msg":"trace[1564427753] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"531.569312ms","start":"2026-05-26T16:01:41.646554Z","end":"2026-05-26T16:01:42.178123Z","steps":["trace[1564427753] 'process raft request' (duration: 246.424825ms)","trace[1564427753] 'compare' (duration: 284.380958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:42.178199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:41.646534Z","time spent":"531.637103ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:01:42.490566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.28561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:42.490653Z","caller":"traceutil/trace.go:171","msg":"trace[1414971490] linearizableReadLoop","detail":"{readStateIndex:2432; appliedIndex:2430; }","duration":"703.683201ms","start":"2026-05-26T16:01:41.786955Z","end":"2026-05-26T16:01:42.490638Z","steps":["trace[1414971490] 'read index received' (duration: 106.108103ms)","trace[1414971490] 'applied index is now lower than readState.Index' (duration: 597.573808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:42.491053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.819973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:42.491129Z","caller":"traceutil/trace.go:171","msg":"trace[1798438135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2331; }","duration":"647.927396ms","start":"2026-05-26T16:01:41.843191Z","end":"2026-05-26T16:01:42.491118Z","steps":["trace[1798438135] 'agreement among raft nodes before linearized reading' (duration: 647.827914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.491161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:41.843167Z","time spent":"647.986597ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T16:01:42.491154Z","caller":"traceutil/trace.go:171","msg":"trace[541319411] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"724.162171ms","start":"2026-05-26T16:01:41.766962Z","end":"2026-05-26T16:01:42.491124Z","steps":["trace[541319411] 'process raft request' (duration: 611.230345ms)","trace[541319411] 'compare' (duration: 112.146087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:42.49118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.999944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T16:01:42.491272Z","caller":"traceutil/trace.go:171","msg":"trace[983920852] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2331; }","duration":"170.120248ms","start":"2026-05-26T16:01:42.321134Z","end":"2026-05-26T16:01:42.491254Z","steps":["trace[983920852] 'agreement among raft nodes before linearized reading' (duration: 169.912983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.491309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:41.766934Z","time spent":"724.298904ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:01:42.491317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.49418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-26T16:01:42.491388Z","caller":"traceutil/trace.go:171","msg":"trace[693713697] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2331; }","duration":"215.612023ms","start":"2026-05-26T16:01:42.275759Z","end":"2026-05-26T16:01:42.491371Z","steps":["trace[693713697] 'agreement among raft nodes before linearized reading' (duration: 215.438579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.491402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.843193ms","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-26T16:01:42.491575Z","caller":"traceutil/trace.go:171","msg":"trace[1856908584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2331; }","duration":"306.049218ms","start":"2026-05-26T16:01:42.185513Z","end":"2026-05-26T16:01:42.491562Z","steps":["trace[1856908584] 'agreement among raft nodes before linearized reading' (duration: 305.800632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.491608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:42.185493Z","time spent":"306.106889ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T16:01:42.491905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.478052ms","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-26T16:01:42.49197Z","caller":"traceutil/trace.go:171","msg":"trace[1678663337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2331; }","duration":"275.587485ms","start":"2026-05-26T16:01:42.216368Z","end":"2026-05-26T16:01:42.491955Z","steps":["trace[1678663337] 'agreement among raft nodes before linearized reading' (duration: 274.746694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.492543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.113831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-26T16:01:42.492599Z","caller":"traceutil/trace.go:171","msg":"trace[349918844] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2331; }","duration":"705.678482ms","start":"2026-05-26T16:01:41.786907Z","end":"2026-05-26T16:01:42.492585Z","steps":["trace[349918844] 'agreement among raft nodes before linearized reading' (duration: 703.964768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.492636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:41.786891Z","time spent":"705.732874ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-26T16:01:42.794984Z","caller":"traceutil/trace.go:171","msg":"trace[2112634595] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"299.565383ms","start":"2026-05-26T16:01:42.495394Z","end":"2026-05-26T16:01:42.794959Z","steps":["trace[2112634595] 'process raft request' (duration: 299.396589ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:42.908659Z","caller":"traceutil/trace.go:171","msg":"trace[425249661] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"413.177927ms","start":"2026-05-26T16:01:42.495443Z","end":"2026-05-26T16:01:42.908621Z","steps":["trace[425249661] 'process raft request' (duration: 375.638214ms)","trace[425249661] 'compare' (duration: 37.337128ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:42.908884Z","caller":"traceutil/trace.go:171","msg":"trace[896477296] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"412.072859ms","start":"2026-05-26T16:01:42.496768Z","end":"2026-05-26T16:01:42.908841Z","steps":["trace[896477296] 'process raft request' (duration: 411.852124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.908988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:42.495423Z","time spent":"413.384251ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:01:42.909033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:42.496763Z","time spent":"412.183852ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:42.908694Z","caller":"traceutil/trace.go:171","msg":"trace[870839312] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"412.52909ms","start":"2026-05-26T16:01:42.496132Z","end":"2026-05-26T16:01:42.908661Z","steps":["trace[870839312] 'process raft request' (duration: 412.394296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.909612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:42.496111Z","time spent":"413.459124ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:42.909092Z","caller":"traceutil/trace.go:171","msg":"trace[1717308559] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"412.194222ms","start":"2026-05-26T16:01:42.496879Z","end":"2026-05-26T16:01:42.909073Z","steps":["trace[1717308559] 'process raft request' (duration: 411.835673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.909879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:42.496874Z","time spent":"412.970872ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:42.908686Z","caller":"traceutil/trace.go:171","msg":"trace[872326953] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"412.317925ms","start":"2026-05-26T16:01:42.496343Z","end":"2026-05-26T16:01:42.908661Z","steps":["trace[872326953] 'process raft request' (duration: 412.234873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:42.910025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:42.496334Z","time spent":"413.647539ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:43.181464Z","caller":"traceutil/trace.go:171","msg":"trace[1864848024] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"266.768222ms","start":"2026-05-26T16:01:42.91467Z","end":"2026-05-26T16:01:43.181439Z","steps":["trace[1864848024] 'process raft request' (duration: 266.608378ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:44.296373Z","caller":"traceutil/trace.go:171","msg":"trace[425949706] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"108.925975ms","start":"2026-05-26T16:01:44.18742Z","end":"2026-05-26T16:01:44.296346Z","steps":["trace[425949706] 'process raft request' (duration: 108.775611ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:44.604069Z","caller":"traceutil/trace.go:171","msg":"trace[478394060] transaction","detail":"{read_only:false; response_revision:2342; number_of_response:1; }","duration":"106.553235ms","start":"2026-05-26T16:01:44.497487Z","end":"2026-05-26T16:01:44.604041Z","steps":["trace[478394060] 'process raft request' (duration: 106.152925ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:45.119238Z","caller":"traceutil/trace.go:171","msg":"trace[1827337229] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"203.050623ms","start":"2026-05-26T16:01:44.916161Z","end":"2026-05-26T16:01:45.119212Z","steps":["trace[1827337229] 'process raft request' (duration: 202.888929ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:45.16143Z","caller":"traceutil/trace.go:171","msg":"trace[906097706] transaction","detail":"{read_only:false; response_revision:2345; number_of_response:1; }","duration":"244.078035ms","start":"2026-05-26T16:01:44.91733Z","end":"2026-05-26T16:01:45.161408Z","steps":["trace[906097706] 'process raft request' (duration: 244.033484ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:45.16144Z","caller":"traceutil/trace.go:171","msg":"trace[733878455] transaction","detail":"{read_only:false; response_revision:2344; number_of_response:1; }","duration":"244.186118ms","start":"2026-05-26T16:01:44.917233Z","end":"2026-05-26T16:01:45.161419Z","steps":["trace[733878455] 'process raft request' (duration: 243.961693ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:45.306496Z","caller":"traceutil/trace.go:171","msg":"trace[1283854222] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"116.66705ms","start":"2026-05-26T16:01:45.189803Z","end":"2026-05-26T16:01:45.30647Z","steps":["trace[1283854222] 'process raft request' (duration: 96.408656ms)","trace[1283854222] 'compare' (duration: 20.06412ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:45.619151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.129635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-26T16:01:45.619226Z","caller":"traceutil/trace.go:171","msg":"trace[1056270578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2346; }","duration":"134.249318ms","start":"2026-05-26T16:01:45.484959Z","end":"2026-05-26T16:01:45.619208Z","steps":["trace[1056270578] 'range keys from in-memory index tree' (duration: 133.892279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:45.866738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.387114ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T16:01:45.86715Z","caller":"traceutil/trace.go:171","msg":"trace[1340742225] transaction","detail":"{read_only:false; response_revision:2347; number_of_response:1; }","duration":"243.609074ms","start":"2026-05-26T16:01:45.623467Z","end":"2026-05-26T16:01:45.867076Z","steps":["trace[1340742225] 'process raft request' (duration: 243.366928ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:46.498491Z","caller":"traceutil/trace.go:171","msg":"trace[24684664] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"193.575404ms","start":"2026-05-26T16:01:46.304892Z","end":"2026-05-26T16:01:46.498468Z","steps":["trace[24684664] 'process raft request' (duration: 193.42702ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:46.900419Z","caller":"traceutil/trace.go:171","msg":"trace[1522185387] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"290.838133ms","start":"2026-05-26T16:01:46.609536Z","end":"2026-05-26T16:01:46.900374Z","steps":["trace[1522185387] 'process raft request' (duration: 290.588676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:47.434635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.473996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:47.434753Z","caller":"traceutil/trace.go:171","msg":"trace[1902152070] linearizableReadLoop","detail":"{readStateIndex:2454; appliedIndex:2453; }","duration":"125.097955ms","start":"2026-05-26T16:01:47.309636Z","end":"2026-05-26T16:01:47.434734Z","steps":["trace[1902152070] 'read index received' (duration: 8.26752ms)","trace[1902152070] 'applied index is now lower than readState.Index' (duration: 116.828815ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:47.434827Z","caller":"traceutil/trace.go:171","msg":"trace[1263237221] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"259.324203ms","start":"2026-05-26T16:01:47.175476Z","end":"2026-05-26T16:01:47.4348Z","steps":["trace[1263237221] 'process raft request' (duration: 142.58883ms)","trace[1263237221] 'compare' (duration: 116.22846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:47.43493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.29249ms","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-26T16:01:47.435183Z","caller":"traceutil/trace.go:171","msg":"trace[1425718794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2352; }","duration":"125.447504ms","start":"2026-05-26T16:01:47.309586Z","end":"2026-05-26T16:01:47.435034Z","steps":["trace[1425718794] 'agreement among raft nodes before linearized reading' (duration: 125.200998ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:48.171687Z","caller":"traceutil/trace.go:171","msg":"trace[179460220] linearizableReadLoop","detail":"{readStateIndex:2457; appliedIndex:2456; }","duration":"257.441755ms","start":"2026-05-26T16:01:47.914224Z","end":"2026-05-26T16:01:48.171666Z","steps":["trace[179460220] 'read index received' (duration: 257.298461ms)","trace[179460220] 'applied index is now lower than readState.Index' (duration: 142.404µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:48.171789Z","caller":"traceutil/trace.go:171","msg":"trace[1660017744] transaction","detail":"{read_only:false; response_revision:2355; number_of_response:1; }","duration":"297.902702ms","start":"2026-05-26T16:01:47.873879Z","end":"2026-05-26T16:01:48.171782Z","steps":["trace[1660017744] 'process raft request' (duration: 297.647015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:48.172002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.765623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-26T16:01:48.172034Z","caller":"traceutil/trace.go:171","msg":"trace[1416405528] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2355; }","duration":"257.850995ms","start":"2026-05-26T16:01:47.914175Z","end":"2026-05-26T16:01:48.172026Z","steps":["trace[1416405528] 'agreement among raft nodes before linearized reading' (duration: 257.733412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:48.172259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.947818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-26T16:01:48.172338Z","caller":"traceutil/trace.go:171","msg":"trace[172929386] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2355; }","duration":"258.06225ms","start":"2026-05-26T16:01:47.914252Z","end":"2026-05-26T16:01:48.172314Z","steps":["trace[172929386] 'agreement among raft nodes before linearized reading' (duration: 257.863875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:48.319926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.196248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:48.320361Z","caller":"traceutil/trace.go:171","msg":"trace[576900223] transaction","detail":"{read_only:false; response_revision:2356; number_of_response:1; }","duration":"143.924773ms","start":"2026-05-26T16:01:48.176403Z","end":"2026-05-26T16:01:48.320328Z","steps":["trace[576900223] 'process raft request' (duration: 42.239893ms)","trace[576900223] 'compare' (duration: 101.065815ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:48.320501Z","caller":"traceutil/trace.go:171","msg":"trace[506835145] transaction","detail":"{read_only:false; response_revision:2357; number_of_response:1; }","duration":"142.187449ms","start":"2026-05-26T16:01:48.178265Z","end":"2026-05-26T16:01:48.320453Z","steps":["trace[506835145] 'process raft request' (duration: 141.970834ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:48.811837Z","caller":"traceutil/trace.go:171","msg":"trace[2082298867] transaction","detail":"{read_only:false; response_revision:2359; number_of_response:1; }","duration":"250.510658ms","start":"2026-05-26T16:01:48.561297Z","end":"2026-05-26T16:01:48.811807Z","steps":["trace[2082298867] 'process raft request' (duration: 188.255398ms)","trace[2082298867] 'compare' (duration: 62.116336ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:49.096773Z","caller":"traceutil/trace.go:171","msg":"trace[783311181] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"190.69697ms","start":"2026-05-26T16:01:48.906052Z","end":"2026-05-26T16:01:49.096749Z","steps":["trace[783311181] 'process raft request' (duration: 190.558636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:49.706796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.342158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:01:49.706894Z","caller":"traceutil/trace.go:171","msg":"trace[165608340] linearizableReadLoop","detail":"{readStateIndex:2463; appliedIndex:2462; }","duration":"487.388011ms","start":"2026-05-26T16:01:49.21949Z","end":"2026-05-26T16:01:49.706878Z","steps":["trace[165608340] 'read index received' (duration: 343.828688ms)","trace[165608340] 'applied index is now lower than readState.Index' (duration: 143.558193ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:49.707005Z","caller":"traceutil/trace.go:171","msg":"trace[1462275547] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"524.680258ms","start":"2026-05-26T16:01:49.182311Z","end":"2026-05-26T16:01:49.706991Z","steps":["trace[1462275547] 'process raft request' (duration: 381.059323ms)","trace[1462275547] 'compare' (duration: 143.236925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:49.707069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:49.182287Z","time spent":"524.750689ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:01:49.70733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.226203ms","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-26T16:01:49.70738Z","caller":"traceutil/trace.go:171","msg":"trace[366901175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2361; }","duration":"269.313875ms","start":"2026-05-26T16:01:49.438052Z","end":"2026-05-26T16:01:49.707366Z","steps":["trace[366901175] 'agreement among raft nodes before linearized reading' (duration: 269.155731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:49.70738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.352983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:01:49.707415Z","caller":"traceutil/trace.go:171","msg":"trace[1232086889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2361; }","duration":"192.417584ms","start":"2026-05-26T16:01:49.514987Z","end":"2026-05-26T16:01:49.707405Z","steps":["trace[1232086889] 'agreement among raft nodes before linearized reading' (duration: 192.321192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:49.707463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.953126ms","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-26T16:01:49.707632Z","caller":"traceutil/trace.go:171","msg":"trace[21295768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2361; }","duration":"488.119979ms","start":"2026-05-26T16:01:49.219451Z","end":"2026-05-26T16:01:49.707571Z","steps":["trace[21295768] 'agreement among raft nodes before linearized reading' (duration: 487.806151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:49.707746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:49.219436Z","time spent":"488.289914ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-26T16:01:49.898917Z","caller":"traceutil/trace.go:171","msg":"trace[1636442143] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"186.32008ms","start":"2026-05-26T16:01:49.712568Z","end":"2026-05-26T16:01:49.898888Z","steps":["trace[1636442143] 'process raft request' (duration: 113.666426ms)","trace[1636442143] 'compare' (duration: 72.431078ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:49.899018Z","caller":"traceutil/trace.go:171","msg":"trace[533336095] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"186.275088ms","start":"2026-05-26T16:01:49.712694Z","end":"2026-05-26T16:01:49.898969Z","steps":["trace[533336095] 'process raft request' (duration: 186.103303ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:50.352226Z","caller":"traceutil/trace.go:171","msg":"trace[870110047] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"136.194916ms","start":"2026-05-26T16:01:50.216001Z","end":"2026-05-26T16:01:50.352196Z","steps":["trace[870110047] 'process raft request' (duration: 135.754365ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:50.779726Z","caller":"traceutil/trace.go:171","msg":"trace[960540955] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"243.543001ms","start":"2026-05-26T16:01:50.536155Z","end":"2026-05-26T16:01:50.779698Z","steps":["trace[960540955] 'process raft request' (duration: 159.58273ms)","trace[960540955] 'compare' (duration: 83.839108ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:51.318794Z","caller":"traceutil/trace.go:171","msg":"trace[1141450962] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"217.235573ms","start":"2026-05-26T16:01:51.101533Z","end":"2026-05-26T16:01:51.318769Z","steps":["trace[1141450962] 'process raft request' (duration: 216.872944ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:52.080823Z","caller":"traceutil/trace.go:171","msg":"trace[472386031] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"171.202946ms","start":"2026-05-26T16:01:51.909596Z","end":"2026-05-26T16:01:52.080799Z","steps":["trace[472386031] 'process raft request' (duration: 171.052712ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:52.135361Z","caller":"traceutil/trace.go:171","msg":"trace[1933114576] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"101.25406ms","start":"2026-05-26T16:01:52.034081Z","end":"2026-05-26T16:01:52.135335Z","steps":["trace[1933114576] 'process raft request' (duration: 101.201269ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:52.135409Z","caller":"traceutil/trace.go:171","msg":"trace[1000897894] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"225.131855ms","start":"2026-05-26T16:01:51.910258Z","end":"2026-05-26T16:01:52.135389Z","steps":["trace[1000897894] 'process raft request' (duration: 224.888199ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:53.602399Z","caller":"traceutil/trace.go:171","msg":"trace[1778510089] linearizableReadLoop","detail":"{readStateIndex:2481; appliedIndex:2480; }","duration":"276.988971ms","start":"2026-05-26T16:01:53.325384Z","end":"2026-05-26T16:01:53.602373Z","steps":["trace[1778510089] 'read index received' (duration: 276.428816ms)","trace[1778510089] 'applied index is now lower than readState.Index' (duration: 556.175µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:53.602441Z","caller":"traceutil/trace.go:171","msg":"trace[1821621277] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"277.724001ms","start":"2026-05-26T16:01:53.324674Z","end":"2026-05-26T16:01:53.602398Z","steps":["trace[1821621277] 'process raft request' (duration: 277.166716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:53.602614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.211147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-26T16:01:53.602705Z","caller":"traceutil/trace.go:171","msg":"trace[341010187] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2378; }","duration":"277.35474ms","start":"2026-05-26T16:01:53.32533Z","end":"2026-05-26T16:01:53.602685Z","steps":["trace[341010187] 'agreement among raft nodes before linearized reading' (duration: 277.134684ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:53.62604Z","caller":"traceutil/trace.go:171","msg":"trace[312487828] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"298.729793ms","start":"2026-05-26T16:01:53.327293Z","end":"2026-05-26T16:01:53.626023Z","steps":["trace[312487828] 'process raft request' (duration: 298.583759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:53.626069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.675805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T16:01:53.62612Z","caller":"traceutil/trace.go:171","msg":"trace[219064553] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2379; }","duration":"126.766858ms","start":"2026-05-26T16:01:53.49934Z","end":"2026-05-26T16:01:53.626107Z","steps":["trace[219064553] 'agreement among raft nodes before linearized reading' (duration: 126.631624ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:53.749509Z","caller":"traceutil/trace.go:171","msg":"trace[774028061] transaction","detail":"{read_only:false; response_revision:2380; number_of_response:1; }","duration":"142.546108ms","start":"2026-05-26T16:01:53.60693Z","end":"2026-05-26T16:01:53.749476Z","steps":["trace[774028061] 'process raft request' (duration: 97.998187ms)","trace[774028061] 'compare' (duration: 44.347356ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:53.975481Z","caller":"traceutil/trace.go:171","msg":"trace[1484371456] linearizableReadLoop","detail":"{readStateIndex:2485; appliedIndex:2484; }","duration":"132.460363ms","start":"2026-05-26T16:01:53.842974Z","end":"2026-05-26T16:01:53.975434Z","steps":["trace[1484371456] 'read index received' (duration: 78.652127ms)","trace[1484371456] 'applied index is now lower than readState.Index' (duration: 53.807296ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:53.975523Z","caller":"traceutil/trace.go:171","msg":"trace[2118402453] transaction","detail":"{read_only:false; response_revision:2382; number_of_response:1; }","duration":"156.921893ms","start":"2026-05-26T16:01:53.818564Z","end":"2026-05-26T16:01:53.975486Z","steps":["trace[2118402453] 'process raft request' (duration: 103.111228ms)","trace[2118402453] 'compare' (duration: 53.665602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:53.975645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.694449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:53.975715Z","caller":"traceutil/trace.go:171","msg":"trace[264975591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2382; }","duration":"132.874704ms","start":"2026-05-26T16:01:53.842822Z","end":"2026-05-26T16:01:53.975697Z","steps":["trace[264975591] 'agreement among raft nodes before linearized reading' (duration: 132.755511ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:54.362601Z","caller":"traceutil/trace.go:171","msg":"trace[26647701] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"381.875352ms","start":"2026-05-26T16:01:53.980699Z","end":"2026-05-26T16:01:54.362574Z","steps":["trace[26647701] 'process raft request' (duration: 381.584855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:54.362753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:01:53.980673Z","time spent":"381.994696ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:01:54.363037Z","caller":"traceutil/trace.go:171","msg":"trace[372788557] linearizableReadLoop","detail":"{readStateIndex:2486; appliedIndex:2486; }","duration":"278.260413ms","start":"2026-05-26T16:01:54.084508Z","end":"2026-05-26T16:01:54.362769Z","steps":["trace[372788557] 'read index received' (duration: 278.250903ms)","trace[372788557] 'applied index is now lower than readState.Index' (duration: 7.97µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:54.363433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.901789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T16:01:54.36352Z","caller":"traceutil/trace.go:171","msg":"trace[1341112565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2383; }","duration":"279.027832ms","start":"2026-05-26T16:01:54.084465Z","end":"2026-05-26T16:01:54.363493Z","steps":["trace[1341112565] 'agreement among raft nodes before linearized reading' (duration: 278.816417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:54.363613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.118889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-26T16:01:54.363667Z","caller":"traceutil/trace.go:171","msg":"trace[1243894460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2383; }","duration":"224.214691ms","start":"2026-05-26T16:01:54.13944Z","end":"2026-05-26T16:01:54.363654Z","steps":["trace[1243894460] 'agreement among raft nodes before linearized reading' (duration: 224.038487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:54.36366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.753463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:01:54.363802Z","caller":"traceutil/trace.go:171","msg":"trace[1081677937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2383; }","duration":"218.993999ms","start":"2026-05-26T16:01:54.144791Z","end":"2026-05-26T16:01:54.363785Z","steps":["trace[1081677937] 'agreement among raft nodes before linearized reading' (duration: 218.696241ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:54.603431Z","caller":"traceutil/trace.go:171","msg":"trace[371620372] linearizableReadLoop","detail":"{readStateIndex:2488; appliedIndex:2487; }","duration":"229.890395ms","start":"2026-05-26T16:01:54.373506Z","end":"2026-05-26T16:01:54.603397Z","steps":["trace[371620372] 'read index received' (duration: 74.911232ms)","trace[371620372] 'applied index is now lower than readState.Index' (duration: 154.978033ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:01:54.603462Z","caller":"traceutil/trace.go:171","msg":"trace[148527758] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"234.916653ms","start":"2026-05-26T16:01:54.368504Z","end":"2026-05-26T16:01:54.603421Z","steps":["trace[148527758] 'process raft request' (duration: 211.589551ms)","trace[148527758] 'compare' (duration: 23.153797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:01:54.603644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.11335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-26T16:01:54.604378Z","caller":"traceutil/trace.go:171","msg":"trace[73541894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2385; }","duration":"230.87006ms","start":"2026-05-26T16:01:54.373483Z","end":"2026-05-26T16:01:54.604353Z","steps":["trace[73541894] 'agreement among raft nodes before linearized reading' (duration: 230.011748ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:01:56.127929Z","caller":"traceutil/trace.go:171","msg":"trace[344779516] transaction","detail":"{read_only:false; response_revision:2389; number_of_response:1; }","duration":"143.171134ms","start":"2026-05-26T16:01:55.984649Z","end":"2026-05-26T16:01:56.12782Z","steps":["trace[344779516] 'process raft request' (duration: 142.968719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:01:56.56008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.996136ms","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-26T16:01:56.560192Z","caller":"traceutil/trace.go:171","msg":"trace[747748578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2390; }","duration":"107.194321ms","start":"2026-05-26T16:01:56.452973Z","end":"2026-05-26T16:01:56.560167Z","steps":["trace[747748578] 'range keys from in-memory index tree' (duration: 106.7521ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:02:20.912936Z","caller":"traceutil/trace.go:171","msg":"trace[223400421] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"130.388894ms","start":"2026-05-26T16:02:20.782526Z","end":"2026-05-26T16:02:20.912915Z","steps":["trace[223400421] 'process raft request' (duration: 130.147967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:02:22.403592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.693513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T16:02:22.403668Z","caller":"traceutil/trace.go:171","msg":"trace[1011244098] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2535; }","duration":"295.783956ms","start":"2026-05-26T16:02:22.107868Z","end":"2026-05-26T16:02:22.403652Z","steps":["trace[1011244098] 'count revisions from in-memory index tree' (duration: 295.577591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:02:22.403693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.958318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-26T16:02:22.40375Z","caller":"traceutil/trace.go:171","msg":"trace[232974687] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2535; }","duration":"127.056781ms","start":"2026-05-26T16:02:22.276681Z","end":"2026-05-26T16:02:22.403738Z","steps":["trace[232974687] 'range keys from in-memory index tree' (duration: 126.804044ms)"],"step_count":1}