{"level":"warn","ts":"2026-08-01T18:47:47.553223Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-01T18:47:47.5533Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-08-01T18:47:47.553362Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-01T18:47:47.553371Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-01T18:47:47.553388Z","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-08-01T18:47:47.554231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-08-01T18:47:47.554308Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-08-01T18:47:47.558429Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.919221ms"} {"level":"info","ts":"2026-08-01T18:47:47.564349Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-08-01T18:47:47.564407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-08-01T18:47:47.564425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-08-01T18:47:47.564436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-01T18:47:47.564445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-08-01T18:47:47.564481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-08-01T18:47:47.568323Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-01T18:47:47.57456Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-01T18:47:47.575806Z","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-08-01T18:47:47.579508Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-01T18:47:47.581644Z","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-08-01T18:47:47.581777Z","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-08-01T18:47:47.581814Z","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-08-01T18:47:47.582011Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-01T18:47:47.588506Z","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-08-01T18:47:47.588826Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-01T18:47:47.58888Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-01T18:47:47.588995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-08-01T18:47:47.589198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-01T18:47:47.589216Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-01T18:47:47.589246Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-01T18:47:47.865289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-08-01T18:47:47.865334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-01T18:47:47.865355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-08-01T18:47:47.865366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-08-01T18:47:47.865371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-01T18:47:47.865382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-08-01T18:47:47.865389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-01T18:47:47.866945Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:47:47.868321Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:47:47.868378Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:47:47.868394Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-01T18:47:47.868425Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-01T18:47:47.868541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-01T18:47:47.868508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-01T18:47:47.868889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-01T18:47:47.868913Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-01T18:47:47.870594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-08-01T18:47:47.872003Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-01T18:48:13.062704Z","caller":"traceutil/trace.go:171","msg":"trace[625541255] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"194.968879ms","start":"2026-08-01T18:48:12.867708Z","end":"2026-08-01T18:48:13.062677Z","steps":["trace[625541255] 'read index received' (duration: 194.757054ms)","trace[625541255] 'applied index is now lower than readState.Index' (duration: 210.974µs)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:13.062858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.111522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:48:13.062921Z","caller":"traceutil/trace.go:171","msg":"trace[1393645460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"195.231915ms","start":"2026-08-01T18:48:12.867666Z","end":"2026-08-01T18:48:13.062898Z","steps":["trace[1393645460] 'agreement among raft nodes before linearized reading' (duration: 195.119243ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:13.062962Z","caller":"traceutil/trace.go:171","msg":"trace[689883056] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"200.028256ms","start":"2026-08-01T18:48:12.862895Z","end":"2026-08-01T18:48:13.062924Z","steps":["trace[689883056] 'process raft request' (duration: 199.638956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:13.520148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.161081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:48:13.520252Z","caller":"traceutil/trace.go:171","msg":"trace[702495697] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"196.952385ms","start":"2026-08-01T18:48:13.323284Z","end":"2026-08-01T18:48:13.520237Z","steps":["trace[702495697] 'read index received' (duration: 57.522µs)","trace[702495697] 'applied index is now lower than readState.Index' (duration: 196.893593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:13.520338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.057497ms","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-08-01T18:48:13.520324Z","caller":"traceutil/trace.go:171","msg":"trace[2037370332] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"326.988555ms","start":"2026-08-01T18:48:13.193304Z","end":"2026-08-01T18:48:13.520292Z","steps":["trace[2037370332] 'process raft request' (duration: 83.145229ms)","trace[2037370332] 'compare' (duration: 243.074018ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:13.520379Z","caller":"traceutil/trace.go:171","msg":"trace[570570689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"197.134949ms","start":"2026-08-01T18:48:13.323235Z","end":"2026-08-01T18:48:13.52037Z","steps":["trace[570570689] 'agreement among raft nodes before linearized reading' (duration: 197.066997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:13.520477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:13.193275Z","time spent":"327.121628ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:26.255646Z","caller":"traceutil/trace.go:171","msg":"trace[858473156] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"304.972017ms","start":"2026-08-01T18:48:25.950652Z","end":"2026-08-01T18:48:26.255625Z","steps":["trace[858473156] 'process raft request' (duration: 304.886005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:26.255956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:25.950627Z","time spent":"305.278594ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-01T18:48:26.262959Z","caller":"traceutil/trace.go:171","msg":"trace[741048804] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"308.029628ms","start":"2026-08-01T18:48:25.954907Z","end":"2026-08-01T18:48:26.262936Z","steps":["trace[741048804] 'process raft request' (duration: 307.576066ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263032Z","caller":"traceutil/trace.go:171","msg":"trace[1726036940] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"293.307408ms","start":"2026-08-01T18:48:25.969701Z","end":"2026-08-01T18:48:26.263008Z","steps":["trace[1726036940] 'process raft request' (duration: 293.173635ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263064Z","caller":"traceutil/trace.go:171","msg":"trace[957198340] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"293.283168ms","start":"2026-08-01T18:48:25.969741Z","end":"2026-08-01T18:48:26.263025Z","steps":["trace[957198340] 'process raft request' (duration: 293.162065ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263165Z","caller":"traceutil/trace.go:171","msg":"trace[2139074667] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"298.862896ms","start":"2026-08-01T18:48:25.964095Z","end":"2026-08-01T18:48:26.262958Z","steps":["trace[2139074667] 'process raft request' (duration: 298.750264ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.26333Z","caller":"traceutil/trace.go:171","msg":"trace[1333988062] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"288.868425ms","start":"2026-08-01T18:48:25.974435Z","end":"2026-08-01T18:48:26.263303Z","steps":["trace[1333988062] 'process raft request' (duration: 288.508957ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263467Z","caller":"traceutil/trace.go:171","msg":"trace[749708146] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"265.855404ms","start":"2026-08-01T18:48:25.997571Z","end":"2026-08-01T18:48:26.263427Z","steps":["trace[749708146] 'process raft request' (duration: 265.657609ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263508Z","caller":"traceutil/trace.go:171","msg":"trace[1320865279] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"282.730573ms","start":"2026-08-01T18:48:25.980763Z","end":"2026-08-01T18:48:26.263494Z","steps":["trace[1320865279] 'process raft request' (duration: 282.217702ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263558Z","caller":"traceutil/trace.go:171","msg":"trace[1550328530] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"308.58273ms","start":"2026-08-01T18:48:25.954964Z","end":"2026-08-01T18:48:26.263547Z","steps":["trace[1550328530] 'process raft request' (duration: 307.856484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:26.264483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:25.95496Z","time spent":"309.419249ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-01T18:48:26.264719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:25.954887Z","time spent":"308.16917ms","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-01T18:48:26.263561Z","caller":"traceutil/trace.go:171","msg":"trace[1267846497] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"256.809005ms","start":"2026-08-01T18:48:26.006737Z","end":"2026-08-01T18:48:26.263546Z","steps":["trace[1267846497] 'process raft request' (duration: 256.539679ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.263616Z","caller":"traceutil/trace.go:171","msg":"trace[1498051945] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"239.294382ms","start":"2026-08-01T18:48:26.02431Z","end":"2026-08-01T18:48:26.263604Z","steps":["trace[1498051945] 'process raft request' (duration: 239.019006ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:26.271551Z","caller":"traceutil/trace.go:171","msg":"trace[2103160291] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"134.394921ms","start":"2026-08-01T18:48:26.137141Z","end":"2026-08-01T18:48:26.271536Z","steps":["trace[2103160291] 'process raft request' (duration: 134.104894ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:32.326263Z","caller":"traceutil/trace.go:171","msg":"trace[2141419250] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"278.600592ms","start":"2026-08-01T18:48:32.047638Z","end":"2026-08-01T18:48:32.326239Z","steps":["trace[2141419250] 'process raft request' (duration: 277.843715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:32.833046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.253782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:48:32.83329Z","caller":"traceutil/trace.go:171","msg":"trace[294556450] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"454.330788ms","start":"2026-08-01T18:48:32.378902Z","end":"2026-08-01T18:48:32.833232Z","steps":["trace[294556450] 'process raft request' (duration: 348.81744ms)","trace[294556450] 'compare' (duration: 105.079338ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:32.833441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:32.378877Z","time spent":"454.490102ms","remote":"127.0.0.1:35800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-01T18:48:33.025213Z","caller":"traceutil/trace.go:171","msg":"trace[1935348167] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"160.389014ms","start":"2026-08-01T18:48:32.8648Z","end":"2026-08-01T18:48:33.025189Z","steps":["trace[1935348167] 'read index received' (duration: 160.140159ms)","trace[1935348167] 'applied index is now lower than readState.Index' (duration: 247.905µs)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:33.025391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.548059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:48:33.025473Z","caller":"traceutil/trace.go:171","msg":"trace[96429350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"160.691092ms","start":"2026-08-01T18:48:32.864763Z","end":"2026-08-01T18:48:33.025454Z","steps":["trace[96429350] 'agreement among raft nodes before linearized reading' (duration: 160.523278ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:33.025887Z","caller":"traceutil/trace.go:171","msg":"trace[502970754] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"172.480171ms","start":"2026-08-01T18:48:32.853388Z","end":"2026-08-01T18:48:33.025868Z","steps":["trace[502970754] 'process raft request' (duration: 171.628371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:34.154712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451575572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:48:34.655102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451575572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:48:35.156226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451575572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:48:35.166874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.014578463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-01T18:48:35.16696Z","caller":"traceutil/trace.go:171","msg":"trace[1251565607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"2.014710986s","start":"2026-08-01T18:48:33.152223Z","end":"2026-08-01T18:48:35.166934Z","steps":["trace[1251565607] 'range keys from in-memory index tree' (duration: 2.014385249s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.167039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:33.152204Z","time spent":"2.014820728s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-01T18:48:35.190492Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.92492382s","expected-duration":"1s"} {"level":"info","ts":"2026-08-01T18:48:35.191467Z","caller":"traceutil/trace.go:171","msg":"trace[829164796] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.929021114s","start":"2026-08-01T18:48:33.262424Z","end":"2026-08-01T18:48:35.191445Z","steps":["trace[829164796] 'process raft request' (duration: 1.92840877s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.191618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:33.262397Z","time spent":"1.929120506s","remote":"127.0.0.1:35456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:35.193564Z","caller":"traceutil/trace.go:171","msg":"trace[1920307752] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"974.153847ms","start":"2026-08-01T18:48:34.219381Z","end":"2026-08-01T18:48:35.193535Z","steps":["trace[1920307752] 'process raft request' (duration: 974.007353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.193767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:34.219353Z","time spent":"974.286861ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:48:35.19374Z","caller":"traceutil/trace.go:171","msg":"trace[2036844031] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"1.540112774s","start":"2026-08-01T18:48:33.653597Z","end":"2026-08-01T18:48:35.19371Z","steps":["trace[2036844031] 'read index received' (duration: 1.537064823s)","trace[2036844031] 'applied index is now lower than readState.Index' (duration: 3.046791ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:35.193948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540342319s","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-08-01T18:48:35.193988Z","caller":"traceutil/trace.go:171","msg":"trace[1219590484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"1.540431421s","start":"2026-08-01T18:48:33.653546Z","end":"2026-08-01T18:48:35.193977Z","steps":["trace[1219590484] 'agreement among raft nodes before linearized reading' (duration: 1.540248257s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.19403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:33.653523Z","time spent":"1.540498952s","remote":"127.0.0.1:35608","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-08-01T18:48:35.194148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538708532s","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-08-01T18:48:35.19418Z","caller":"traceutil/trace.go:171","msg":"trace[53222800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"1.538762414s","start":"2026-08-01T18:48:33.65541Z","end":"2026-08-01T18:48:35.194172Z","steps":["trace[53222800] 'agreement among raft nodes before linearized reading' (duration: 1.53862643s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.194228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:33.6554Z","time spent":"1.538819775s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:48:35.194253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327054553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:48:35.194304Z","caller":"traceutil/trace.go:171","msg":"trace[498994733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.327151025s","start":"2026-08-01T18:48:33.86714Z","end":"2026-08-01T18:48:35.194291Z","steps":["trace[498994733] 'agreement among raft nodes before linearized reading' (duration: 1.327078564s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.194341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:33.867111Z","time spent":"1.327222566s","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:48:35.194445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512241585s","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-08-01T18:48:35.197988Z","caller":"traceutil/trace.go:171","msg":"trace[1703011674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"1.515820877s","start":"2026-08-01T18:48:33.682153Z","end":"2026-08-01T18:48:35.197974Z","steps":["trace[1703011674] 'agreement among raft nodes before linearized reading' (duration: 1.512229195s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:35.198031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:33.682114Z","time spent":"1.515901619s","remote":"127.0.0.1:35608","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":"info","ts":"2026-08-01T18:48:37.89876Z","caller":"traceutil/trace.go:171","msg":"trace[1707558086] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"121.011812ms","start":"2026-08-01T18:48:37.777717Z","end":"2026-08-01T18:48:37.898729Z","steps":["trace[1707558086] 'process raft request' (duration: 120.803748ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:37.901851Z","caller":"traceutil/trace.go:171","msg":"trace[90346305] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"116.407516ms","start":"2026-08-01T18:48:37.785423Z","end":"2026-08-01T18:48:37.90183Z","steps":["trace[90346305] 'process raft request' (duration: 116.230612ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:39.242965Z","caller":"traceutil/trace.go:171","msg":"trace[1709010872] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"259.850453ms","start":"2026-08-01T18:48:38.98309Z","end":"2026-08-01T18:48:39.24294Z","steps":["trace[1709010872] 'process raft request' (duration: 259.73162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:45.413886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.711565ms","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-08-01T18:48:45.413966Z","caller":"traceutil/trace.go:171","msg":"trace[83844757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"106.800596ms","start":"2026-08-01T18:48:45.307137Z","end":"2026-08-01T18:48:45.413938Z","steps":["trace[83844757] 'agreement among raft nodes before linearized reading' (duration: 106.638183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:45.413871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.051266ms","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-08-01T18:48:45.414052Z","caller":"traceutil/trace.go:171","msg":"trace[1521976980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"108.288782ms","start":"2026-08-01T18:48:45.305757Z","end":"2026-08-01T18:48:45.414045Z","steps":["trace[1521976980] 'agreement among raft nodes before linearized reading' (duration: 107.957844ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:51.0143Z","caller":"traceutil/trace.go:171","msg":"trace[1058611788] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"241.950993ms","start":"2026-08-01T18:48:50.772326Z","end":"2026-08-01T18:48:51.014277Z","steps":["trace[1058611788] 'read index received' (duration: 241.81623ms)","trace[1058611788] 'applied index is now lower than readState.Index' (duration: 133.753µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:48:51.014321Z","caller":"traceutil/trace.go:171","msg":"trace[426571763] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"295.482509ms","start":"2026-08-01T18:48:50.718816Z","end":"2026-08-01T18:48:51.014299Z","steps":["trace[426571763] 'process raft request' (duration: 295.317526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:51.014541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.190738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-01T18:48:51.014692Z","caller":"traceutil/trace.go:171","msg":"trace[1741063563] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:762; }","duration":"242.323291ms","start":"2026-08-01T18:48:50.772313Z","end":"2026-08-01T18:48:51.014636Z","steps":["trace[1741063563] 'agreement among raft nodes before linearized reading' (duration: 242.093576ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:48:51.331294Z","caller":"traceutil/trace.go:171","msg":"trace[1745549184] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"316.861139ms","start":"2026-08-01T18:48:51.01441Z","end":"2026-08-01T18:48:51.331271Z","steps":["trace[1745549184] 'read index received' (duration: 316.733566ms)","trace[1745549184] 'applied index is now lower than readState.Index' (duration: 126.533µs)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:48:51.331421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.606541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:48:51.331451Z","caller":"traceutil/trace.go:171","msg":"trace[382203304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"463.696623ms","start":"2026-08-01T18:48:50.867746Z","end":"2026-08-01T18:48:51.331442Z","steps":["trace[382203304] 'agreement among raft nodes before linearized reading' (duration: 463.618361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:51.331482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:50.867723Z","time spent":"463.752643ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-01T18:48:51.331487Z","caller":"traceutil/trace.go:171","msg":"trace[1345797778] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"461.721769ms","start":"2026-08-01T18:48:50.869629Z","end":"2026-08-01T18:48:51.331351Z","steps":["trace[1345797778] 'process raft request' (duration: 461.501004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:51.332007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.694786ms","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-08-01T18:48:51.332063Z","caller":"traceutil/trace.go:171","msg":"trace[2048001050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"423.790728ms","start":"2026-08-01T18:48:50.908261Z","end":"2026-08-01T18:48:51.332052Z","steps":["trace[2048001050] 'agreement among raft nodes before linearized reading' (duration: 423.170674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:48:51.332099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:50.908245Z","time spent":"423.84343ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-01T18:48:51.332225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:51.015626Z","time spent":"316.595133ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-01T18:48:51.332478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:48:50.869612Z","time spent":"462.030295ms","remote":"127.0.0.1:35578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:21.365954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:21.629268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:20.775815Z","time spent":"853.449592ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-01T18:49:21.764979Z","caller":"traceutil/trace.go:171","msg":"trace[161830374] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"746.044242ms","start":"2026-08-01T18:49:21.018916Z","end":"2026-08-01T18:49:21.76496Z","steps":["trace[161830374] 'process raft request' (duration: 655.986788ms)","trace[161830374] 'compare' (duration: 89.905361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:21.765115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:21.018898Z","time spent":"746.132564ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:21.765208Z","caller":"traceutil/trace.go:171","msg":"trace[1336239337] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"134.961272ms","start":"2026-08-01T18:49:21.630228Z","end":"2026-08-01T18:49:21.76519Z","steps":["trace[1336239337] 'process raft request' (duration: 134.88184ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:21.765206Z","caller":"traceutil/trace.go:171","msg":"trace[422403276] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"323.050611ms","start":"2026-08-01T18:49:21.442131Z","end":"2026-08-01T18:49:21.765181Z","steps":["trace[422403276] 'process raft request' (duration: 322.770635ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:21.765326Z","caller":"traceutil/trace.go:171","msg":"trace[6915457] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"899.836155ms","start":"2026-08-01T18:49:20.865474Z","end":"2026-08-01T18:49:21.76531Z","steps":["trace[6915457] 'read index received' (duration: 763.673376ms)","trace[6915457] 'applied index is now lower than readState.Index' (duration: 136.161429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:21.765364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:21.442107Z","time spent":"323.161974ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:21.765453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.976788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:21.765476Z","caller":"traceutil/trace.go:171","msg":"trace[1697595146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"900.039559ms","start":"2026-08-01T18:49:20.86543Z","end":"2026-08-01T18:49:21.76547Z","steps":["trace[1697595146] 'agreement among raft nodes before linearized reading' (duration: 899.957088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:21.765494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:20.865408Z","time spent":"900.082781ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:49:21.765606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.997298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:21.765624Z","caller":"traceutil/trace.go:171","msg":"trace[4158179] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:958; }","duration":"343.044759ms","start":"2026-08-01T18:49:21.422573Z","end":"2026-08-01T18:49:21.765618Z","steps":["trace[4158179] 'agreement among raft nodes before linearized reading' (duration: 343.015888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:21.76564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:21.422558Z","time spent":"343.07737ms","remote":"127.0.0.1:35564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:49:21.765873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.549364ms","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-08-01T18:49:21.765881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.121186ms","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-08-01T18:49:21.765892Z","caller":"traceutil/trace.go:171","msg":"trace[1709785928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"293.591836ms","start":"2026-08-01T18:49:21.472295Z","end":"2026-08-01T18:49:21.765887Z","steps":["trace[1709785928] 'agreement among raft nodes before linearized reading' (duration: 293.528634ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:21.765907Z","caller":"traceutil/trace.go:171","msg":"trace[1978477659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"214.187587ms","start":"2026-08-01T18:49:21.551712Z","end":"2026-08-01T18:49:21.7659Z","steps":["trace[1978477659] 'agreement among raft nodes before linearized reading' (duration: 214.093205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:21.766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.098989ms","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-08-01T18:49:21.766015Z","caller":"traceutil/trace.go:171","msg":"trace[453270670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"164.151091ms","start":"2026-08-01T18:49:21.601859Z","end":"2026-08-01T18:49:21.76601Z","steps":["trace[453270670] 'agreement among raft nodes before linearized reading' (duration: 164.10525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:21.766082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.537038ms","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-08-01T18:49:21.766098Z","caller":"traceutil/trace.go:171","msg":"trace[1590089352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"167.575169ms","start":"2026-08-01T18:49:21.598519Z","end":"2026-08-01T18:49:21.766094Z","steps":["trace[1590089352] 'agreement among raft nodes before linearized reading' (duration: 167.533068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:21.766187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.01808ms","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-08-01T18:49:21.766218Z","caller":"traceutil/trace.go:171","msg":"trace[554653587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"172.096932ms","start":"2026-08-01T18:49:21.594112Z","end":"2026-08-01T18:49:21.766209Z","steps":["trace[554653587] 'agreement among raft nodes before linearized reading' (duration: 171.976129ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:21.906572Z","caller":"traceutil/trace.go:171","msg":"trace[1122694833] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"136.896896ms","start":"2026-08-01T18:49:21.769649Z","end":"2026-08-01T18:49:21.906546Z","steps":["trace[1122694833] 'process raft request' (duration: 124.383289ms)","trace[1122694833] 'compare' (duration: 12.350562ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:21.906828Z","caller":"traceutil/trace.go:171","msg":"trace[735077525] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"137.142212ms","start":"2026-08-01T18:49:21.769662Z","end":"2026-08-01T18:49:21.906804Z","steps":["trace[735077525] 'process raft request' (duration: 136.847165ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:21.906984Z","caller":"traceutil/trace.go:171","msg":"trace[755314240] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"137.239413ms","start":"2026-08-01T18:49:21.769734Z","end":"2026-08-01T18:49:21.906974Z","steps":["trace[755314240] 'process raft request' (duration: 136.972167ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:21.907078Z","caller":"traceutil/trace.go:171","msg":"trace[1887409842] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:999; }","duration":"136.1391ms","start":"2026-08-01T18:49:21.770928Z","end":"2026-08-01T18:49:21.907068Z","steps":["trace[1887409842] 'read index received' (duration: 26.295963ms)","trace[1887409842] 'applied index is now lower than readState.Index' (duration: 109.842297ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:21.907177Z","caller":"traceutil/trace.go:171","msg":"trace[1678351585] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"137.126552ms","start":"2026-08-01T18:49:21.770042Z","end":"2026-08-01T18:49:21.907168Z","steps":["trace[1678351585] 'process raft request' (duration: 136.890427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:21.907289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.341824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-01T18:49:21.907373Z","caller":"traceutil/trace.go:171","msg":"trace[1829998079] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:963; }","duration":"136.457936ms","start":"2026-08-01T18:49:21.770904Z","end":"2026-08-01T18:49:21.907362Z","steps":["trace[1829998079] 'agreement among raft nodes before linearized reading' (duration: 136.321353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:28.391813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:28.892323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576416,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-01T18:49:29.092085Z","caller":"traceutil/trace.go:171","msg":"trace[1709340688] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"1.200754759s","start":"2026-08-01T18:49:27.891301Z","end":"2026-08-01T18:49:29.092056Z","steps":["trace[1709340688] 'read index received' (duration: 1.200581124s)","trace[1709340688] 'applied index is now lower than readState.Index' (duration: 173.005µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:29.092195Z","caller":"traceutil/trace.go:171","msg":"trace[1606372527] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.223130502s","start":"2026-08-01T18:49:27.869015Z","end":"2026-08-01T18:49:29.092145Z","steps":["trace[1606372527] 'process raft request' (duration: 1.222924587s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.092358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154046789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-01T18:49:29.092363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.73255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:29.092387Z","caller":"traceutil/trace.go:171","msg":"trace[986362531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"1.154108331s","start":"2026-08-01T18:49:27.938269Z","end":"2026-08-01T18:49:29.092378Z","steps":["trace[986362531] 'agreement among raft nodes before linearized reading' (duration: 1.154040979s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.092251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200934783s","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-08-01T18:49:29.092413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:27.938246Z","time spent":"1.154161601s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-01T18:49:29.092413Z","caller":"traceutil/trace.go:171","msg":"trace[556011689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"224.833612ms","start":"2026-08-01T18:49:28.867566Z","end":"2026-08-01T18:49:29.092399Z","steps":["trace[556011689] 'agreement among raft nodes before linearized reading' (duration: 224.73163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.092407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:27.868991Z","time spent":"1.223320486s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:29.092432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154203482s","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-08-01T18:49:29.092454Z","caller":"traceutil/trace.go:171","msg":"trace[261804353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"1.201166829s","start":"2026-08-01T18:49:27.891259Z","end":"2026-08-01T18:49:29.092426Z","steps":["trace[261804353] 'agreement among raft nodes before linearized reading' (duration: 1.200897383s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:29.092486Z","caller":"traceutil/trace.go:171","msg":"trace[487023102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"1.154283645s","start":"2026-08-01T18:49:27.938188Z","end":"2026-08-01T18:49:29.092471Z","steps":["trace[487023102] 'agreement among raft nodes before linearized reading' (duration: 1.154136031s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.092527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:27.938178Z","time spent":"1.154342666s","remote":"127.0.0.1:35608","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-08-01T18:49:29.092522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:27.891243Z","time spent":"1.20126749s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-01T18:49:29.092673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153495706s","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-08-01T18:49:29.092694Z","caller":"traceutil/trace.go:171","msg":"trace[1588540107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"1.153530878s","start":"2026-08-01T18:49:27.939157Z","end":"2026-08-01T18:49:29.092688Z","steps":["trace[1588540107] 'agreement among raft nodes before linearized reading' (duration: 1.153117728s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.092694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157995769s","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-08-01T18:49:29.092785Z","caller":"traceutil/trace.go:171","msg":"trace[667823647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:981; }","duration":"1.158124133s","start":"2026-08-01T18:49:27.934653Z","end":"2026-08-01T18:49:29.092778Z","steps":["trace[667823647] 'agreement among raft nodes before linearized reading' (duration: 1.157618701s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.09238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.310137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:49:29.0928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:27.934635Z","time spent":"1.158160184s","remote":"127.0.0.1:35608","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-08-01T18:49:29.092711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:27.93915Z","time spent":"1.153557128s","remote":"127.0.0.1:35608","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-08-01T18:49:29.092823Z","caller":"traceutil/trace.go:171","msg":"trace[573769155] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:981; }","duration":"859.800108ms","start":"2026-08-01T18:49:28.233011Z","end":"2026-08-01T18:49:29.092811Z","steps":["trace[573769155] 'agreement among raft nodes before linearized reading' (duration: 859.276296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:29.093821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:28.232994Z","time spent":"860.807111ms","remote":"127.0.0.1:35444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:49:29.610048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:30.110431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:30.208872Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.112386664s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-01T18:49:30.933216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.091179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:49:30.933399Z","caller":"traceutil/trace.go:171","msg":"trace[1584150036] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.836967555s","start":"2026-08-01T18:49:29.096415Z","end":"2026-08-01T18:49:30.933382Z","steps":["trace[1584150036] 'process raft request' (duration: 1.836889143s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:30.93346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.096399Z","time spent":"1.837031286s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:30.933607Z","caller":"traceutil/trace.go:171","msg":"trace[53370323] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.837184269s","start":"2026-08-01T18:49:29.096392Z","end":"2026-08-01T18:49:30.933576Z","steps":["trace[53370323] 'process raft request' (duration: 1.112660781s)","trace[53370323] 'compare' (duration: 723.874614ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:30.933757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.096367Z","time spent":"1.837312243s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:30.957058Z","caller":"traceutil/trace.go:171","msg":"trace[1851879477] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.860313639s","start":"2026-08-01T18:49:29.096721Z","end":"2026-08-01T18:49:30.957035Z","steps":["trace[1851879477] 'process raft request' (duration: 1.860158726s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:30.957223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.096701Z","time spent":"1.860430191s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:30.957336Z","caller":"traceutil/trace.go:171","msg":"trace[769005561] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.860415592s","start":"2026-08-01T18:49:29.096903Z","end":"2026-08-01T18:49:30.957318Z","steps":["trace[769005561] 'process raft request' (duration: 1.860087544s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:30.957358Z","caller":"traceutil/trace.go:171","msg":"trace[1207007469] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.860310399s","start":"2026-08-01T18:49:29.097031Z","end":"2026-08-01T18:49:30.957341Z","steps":["trace[1207007469] 'process raft request' (duration: 1.860180706s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:30.957363Z","caller":"traceutil/trace.go:171","msg":"trace[1236956070] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1023; }","duration":"1.84816779s","start":"2026-08-01T18:49:29.109174Z","end":"2026-08-01T18:49:30.957341Z","steps":["trace[1236956070] 'read index received' (duration: 1.099890758s)","trace[1236956070] 'applied index is now lower than readState.Index' (duration: 748.275722ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:30.957423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.096893Z","time spent":"1.860484523s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:30.957458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.097027Z","time spent":"1.8603698s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:30.957534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.848361244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:30.957574Z","caller":"traceutil/trace.go:171","msg":"trace[787927905] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:986; }","duration":"1.848423695s","start":"2026-08-01T18:49:29.109138Z","end":"2026-08-01T18:49:30.957562Z","steps":["trace[787927905] 'agreement among raft nodes before linearized reading' (duration: 1.848361914s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:30.957604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.109127Z","time spent":"1.848469637s","remote":"127.0.0.1:35708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:49:30.957648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.337618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-01T18:49:30.957676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018666277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:49:30.957695Z","caller":"traceutil/trace.go:171","msg":"trace[1571736719] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:986; }","duration":"475.425132ms","start":"2026-08-01T18:49:30.482256Z","end":"2026-08-01T18:49:30.957681Z","steps":["trace[1571736719] 'agreement among raft nodes before linearized reading' (duration: 475.304098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:30.95769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.38855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-01T18:49:30.95774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:30.482236Z","time spent":"475.492783ms","remote":"127.0.0.1:35682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-08-01T18:49:30.957721Z","caller":"traceutil/trace.go:171","msg":"trace[656533831] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:986; }","duration":"1.01877664s","start":"2026-08-01T18:49:29.938933Z","end":"2026-08-01T18:49:30.95771Z","steps":["trace[656533831] 'agreement among raft nodes before linearized reading' (duration: 1.018594485s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:30.95775Z","caller":"traceutil/trace.go:171","msg":"trace[1661158069] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:986; }","duration":"182.491691ms","start":"2026-08-01T18:49:30.775243Z","end":"2026-08-01T18:49:30.957735Z","steps":["trace[1661158069] 'agreement among raft nodes before linearized reading' (duration: 182.291676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:30.957789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:29.938917Z","time spent":"1.018860082s","remote":"127.0.0.1:35430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":93,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-01T18:49:36.393298Z","caller":"traceutil/trace.go:171","msg":"trace[2110624660] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"164.599439ms","start":"2026-08-01T18:49:36.228676Z","end":"2026-08-01T18:49:36.393275Z","steps":["trace[2110624660] 'process raft request' (duration: 164.463086ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:41.236624Z","caller":"traceutil/trace.go:171","msg":"trace[1729432472] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"100.921703ms","start":"2026-08-01T18:49:41.135665Z","end":"2026-08-01T18:49:41.236587Z","steps":["trace[1729432472] 'process raft request' (duration: 84.868471ms)","trace[1729432472] 'compare' (duration: 15.720135ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:47.37896Z","caller":"traceutil/trace.go:171","msg":"trace[1463712406] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"108.724583ms","start":"2026-08-01T18:49:47.270204Z","end":"2026-08-01T18:49:47.378929Z","steps":["trace[1463712406] 'process raft request' (duration: 96.318491ms)","trace[1463712406] 'compare' (duration: 12.217758ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:53.086711Z","caller":"traceutil/trace.go:171","msg":"trace[44817040] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"354.713849ms","start":"2026-08-01T18:49:52.731968Z","end":"2026-08-01T18:49:53.086682Z","steps":["trace[44817040] 'process raft request' (duration: 354.593916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:53.086855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:52.731929Z","time spent":"354.860192ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:53.76832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:54.268768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:54.769929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:49:54.830155Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.706673943s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-01T18:49:54.982007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.450641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:49:54.982222Z","caller":"traceutil/trace.go:171","msg":"trace[1530239754] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1102; }","duration":"1.714231429s","start":"2026-08-01T18:49:53.267969Z","end":"2026-08-01T18:49:54.982201Z","steps":["trace[1530239754] 'read index received' (duration: 1.562418201s)","trace[1530239754] 'applied index is now lower than readState.Index' (duration: 151.812128ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:54.982322Z","caller":"traceutil/trace.go:171","msg":"trace[1537249261] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"1.858949554s","start":"2026-08-01T18:49:53.123358Z","end":"2026-08-01T18:49:54.982307Z","steps":["trace[1537249261] 'process raft request' (duration: 1.707022211s)","trace[1537249261] 'compare' (duration: 151.042112ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:54.982424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.123332Z","time spent":"1.859018435s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:54.982703Z","caller":"traceutil/trace.go:171","msg":"trace[2132034738] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.69276219s","start":"2026-08-01T18:49:53.289929Z","end":"2026-08-01T18:49:54.982691Z","steps":["trace[2132034738] 'process raft request' (duration: 1.692184916s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.982755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.289907Z","time spent":"1.692820051s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:54.982941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714976796s","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-08-01T18:49:54.982969Z","caller":"traceutil/trace.go:171","msg":"trace[1974400394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"1.715035918s","start":"2026-08-01T18:49:53.267925Z","end":"2026-08-01T18:49:54.98296Z","steps":["trace[1974400394] 'agreement among raft nodes before linearized reading' (duration: 1.714931445s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.982998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.267907Z","time spent":"1.715084748s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-01T18:49:54.983193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715123419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-01T18:49:54.98327Z","caller":"traceutil/trace.go:171","msg":"trace[39069048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1057; }","duration":"1.715249772s","start":"2026-08-01T18:49:53.268001Z","end":"2026-08-01T18:49:54.983251Z","steps":["trace[39069048] 'agreement among raft nodes before linearized reading' (duration: 1.715041627s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.983314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.267975Z","time spent":"1.715331164s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:49:54.983467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.049707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:49:54.983502Z","caller":"traceutil/trace.go:171","msg":"trace[1039389472] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1057; }","duration":"371.137089ms","start":"2026-08-01T18:49:54.612355Z","end":"2026-08-01T18:49:54.983492Z","steps":["trace[1039389472] 'agreement among raft nodes before linearized reading' (duration: 371.078768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.98353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:54.612336Z","time spent":"371.18651ms","remote":"127.0.0.1:35672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:49:54.983547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115513751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:54.983595Z","caller":"traceutil/trace.go:171","msg":"trace[867721739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"1.115596563s","start":"2026-08-01T18:49:53.867982Z","end":"2026-08-01T18:49:54.983579Z","steps":["trace[867721739] 'agreement among raft nodes before linearized reading' (duration: 1.115509581s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.983627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.867963Z","time spent":"1.115657394s","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:49:54.983627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563565907s","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-08-01T18:49:54.983691Z","caller":"traceutil/trace.go:171","msg":"trace[834353569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"1.563658408s","start":"2026-08-01T18:49:53.420017Z","end":"2026-08-01T18:49:54.983675Z","steps":["trace[834353569] 'agreement among raft nodes before linearized reading' (duration: 1.563462384s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.98372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569026836s","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-08-01T18:49:54.983732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.420007Z","time spent":"1.563715989s","remote":"127.0.0.1:35608","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-08-01T18:49:54.98375Z","caller":"traceutil/trace.go:171","msg":"trace[1333679428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"1.569099688s","start":"2026-08-01T18:49:53.414641Z","end":"2026-08-01T18:49:54.983741Z","steps":["trace[1333679428] 'agreement among raft nodes before linearized reading' (duration: 1.569019696s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:54.983782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:53.41462Z","time spent":"1.569155498s","remote":"127.0.0.1:35608","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":"info","ts":"2026-08-01T18:49:55.144624Z","caller":"traceutil/trace.go:171","msg":"trace[543926237] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"156.513701ms","start":"2026-08-01T18:49:54.988077Z","end":"2026-08-01T18:49:55.144591Z","steps":["trace[543926237] 'process raft request' (duration: 106.204079ms)","trace[543926237] 'compare' (duration: 50.171279ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:55.520509Z","caller":"traceutil/trace.go:171","msg":"trace[1225877193] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"532.124639ms","start":"2026-08-01T18:49:54.988355Z","end":"2026-08-01T18:49:55.520479Z","steps":["trace[1225877193] 'process raft request' (duration: 448.679259ms)","trace[1225877193] 'compare' (duration: 83.179674ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:55.520658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:54.988339Z","time spent":"532.244521ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:55.520785Z","caller":"traceutil/trace.go:171","msg":"trace[1500887323] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1104; }","duration":"526.775131ms","start":"2026-08-01T18:49:54.993986Z","end":"2026-08-01T18:49:55.520761Z","steps":["trace[1500887323] 'read index received' (duration: 100.455693ms)","trace[1500887323] 'applied index is now lower than readState.Index' (duration: 426.318528ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:55.520932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.929905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:55.520955Z","caller":"traceutil/trace.go:171","msg":"trace[835485055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"526.981516ms","start":"2026-08-01T18:49:54.993967Z","end":"2026-08-01T18:49:55.520948Z","steps":["trace[835485055] 'agreement among raft nodes before linearized reading' (duration: 526.874733ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:55.520724Z","caller":"traceutil/trace.go:171","msg":"trace[1456467629] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"532.2108ms","start":"2026-08-01T18:49:54.98848Z","end":"2026-08-01T18:49:55.52069Z","steps":["trace[1456467629] 'process raft request' (duration: 531.922274ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:55.521004Z","caller":"traceutil/trace.go:171","msg":"trace[1732103986] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"532.123819ms","start":"2026-08-01T18:49:54.988873Z","end":"2026-08-01T18:49:55.520997Z","steps":["trace[1732103986] 'process raft request' (duration: 531.752731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:55.521071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:54.988862Z","time spent":"532.159589ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:55.521119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:54.988448Z","time spent":"532.570328ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:55.520977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:54.993958Z","time spent":"527.012866ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:49:58.130459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.288604057s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-01T18:49:58.6895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.281398137s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-08-01T18:49:58.690063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249611422s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:58.690157Z","caller":"traceutil/trace.go:171","msg":"trace[126137244] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1061; }","duration":"1.249716304s","start":"2026-08-01T18:49:57.440427Z","end":"2026-08-01T18:49:58.690144Z","steps":["trace[126137244] 'range keys from in-memory index tree' (duration: 1.249595971s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:58.690297Z","caller":"traceutil/trace.go:171","msg":"trace[1503942023] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"2.227871663s","start":"2026-08-01T18:49:56.462397Z","end":"2026-08-01T18:49:58.690268Z","steps":["trace[1503942023] 'read index received' (duration: 54.821µs)","trace[1503942023] 'applied index is now lower than readState.Index' (duration: 2.227813951s)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:49:58.69042Z","caller":"traceutil/trace.go:171","msg":"trace[27950687] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.921633077s","start":"2026-08-01T18:49:56.768766Z","end":"2026-08-01T18:49:58.690399Z","steps":["trace[27950687] 'process raft request' (duration: 1.9208498s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:58.690539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:56.768738Z","time spent":"1.92173647s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:58.80937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.346990385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:58.809553Z","caller":"traceutil/trace.go:171","msg":"trace[633228217] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1062; }","duration":"2.347148628s","start":"2026-08-01T18:49:56.462291Z","end":"2026-08-01T18:49:58.80944Z","steps":["trace[633228217] 'agreement among raft nodes before linearized reading' (duration: 2.228056016s)","trace[633228217] 'count revisions from in-memory index tree' (duration: 118.926378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:58.809627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:56.462271Z","time spent":"2.347327973s","remote":"127.0.0.1:35246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:49:58.810347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.210645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:49:58.810491Z","caller":"traceutil/trace.go:171","msg":"trace[683017222] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"120.081034ms","start":"2026-08-01T18:49:58.690391Z","end":"2026-08-01T18:49:58.810472Z","steps":["trace[683017222] 'read index received' (duration: 4.758335ms)","trace[683017222] 'applied index is now lower than readState.Index' (duration: 115.320609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:58.81058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943409036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:58.810632Z","caller":"traceutil/trace.go:171","msg":"trace[42563512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"1.943505428s","start":"2026-08-01T18:49:56.867113Z","end":"2026-08-01T18:49:58.810619Z","steps":["trace[42563512] 'agreement among raft nodes before linearized reading' (duration: 1.943416016s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:58.810674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:56.86709Z","time spent":"1.94357459s","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:49:58.81081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285341516s","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-08-01T18:49:58.810856Z","caller":"traceutil/trace.go:171","msg":"trace[1537233840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.285430008s","start":"2026-08-01T18:49:57.525415Z","end":"2026-08-01T18:49:58.810845Z","steps":["trace[1537233840] 'agreement among raft nodes before linearized reading' (duration: 1.285276734s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:58.81083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1213913s","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":"warn","ts":"2026-08-01T18:49:58.810874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285983809s","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-08-01T18:49:58.810906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285629822s","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":"warn","ts":"2026-08-01T18:49:58.810941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269073689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:58.810952Z","caller":"traceutil/trace.go:171","msg":"trace[2106999481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"1.286105252s","start":"2026-08-01T18:49:57.52483Z","end":"2026-08-01T18:49:58.810935Z","steps":["trace[2106999481] 'agreement among raft nodes before linearized reading' (duration: 1.285896527s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:58.810957Z","caller":"traceutil/trace.go:171","msg":"trace[52138375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.285701603s","start":"2026-08-01T18:49:57.525242Z","end":"2026-08-01T18:49:58.810944Z","steps":["trace[52138375] 'agreement among raft nodes before linearized reading' (duration: 1.285542139s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:58.810936Z","caller":"traceutil/trace.go:171","msg":"trace[1282427626] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1063; }","duration":"1.121551943s","start":"2026-08-01T18:49:57.689366Z","end":"2026-08-01T18:49:58.810918Z","steps":["trace[1282427626] 'agreement among raft nodes before linearized reading' (duration: 1.1213903s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:58.810975Z","caller":"traceutil/trace.go:171","msg":"trace[1582869190] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1063; }","duration":"1.26915849s","start":"2026-08-01T18:49:57.541808Z","end":"2026-08-01T18:49:58.810966Z","steps":["trace[1582869190] 'agreement among raft nodes before linearized reading' (duration: 1.269103349s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:58.810996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:57.525234Z","time spent":"1.285754425s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-01T18:49:58.810993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:57.524811Z","time spent":"1.286174354s","remote":"127.0.0.1:35608","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-08-01T18:49:58.811026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:57.541784Z","time spent":"1.269235241s","remote":"127.0.0.1:35704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:49:58.811036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:57.689346Z","time spent":"1.121676537s","remote":"127.0.0.1:35482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-08-01T18:49:58.811118Z","caller":"traceutil/trace.go:171","msg":"trace[1186302898] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"1.821733507s","start":"2026-08-01T18:49:56.98937Z","end":"2026-08-01T18:49:58.811104Z","steps":["trace[1186302898] 'process raft request' (duration: 1.705925418s)","trace[1186302898] 'compare' (duration: 114.046411ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:58.811143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.823457825s","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-08-01T18:49:58.810983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662106916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-01T18:49:58.81119Z","caller":"traceutil/trace.go:171","msg":"trace[1767597284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"1.823540327s","start":"2026-08-01T18:49:56.987637Z","end":"2026-08-01T18:49:58.811177Z","steps":["trace[1767597284] 'agreement among raft nodes before linearized reading' (duration: 1.823371153s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:58.811199Z","caller":"traceutil/trace.go:171","msg":"trace[1661478393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"1.662354782s","start":"2026-08-01T18:49:57.148832Z","end":"2026-08-01T18:49:58.811187Z","steps":["trace[1661478393] 'agreement among raft nodes before linearized reading' (duration: 1.662055666s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:58.81121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:56.98935Z","time spent":"1.821800988s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:49:58.811233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:57.148817Z","time spent":"1.662407253s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:49:58.811223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:56.987619Z","time spent":"1.823597388s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-01T18:49:58.810888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:57.525393Z","time spent":"1.285487988s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-01T18:49:59.148061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.666383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:49:59.148171Z","caller":"traceutil/trace.go:171","msg":"trace[247594977] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"332.770807ms","start":"2026-08-01T18:49:58.815382Z","end":"2026-08-01T18:49:59.148153Z","steps":["trace[247594977] 'process raft request' (duration: 114.94565ms)","trace[247594977] 'compare' (duration: 217.573611ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:59.148228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:58.815369Z","time spent":"332.833399ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:59.216951Z","caller":"traceutil/trace.go:171","msg":"trace[1496544712] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"401.242259ms","start":"2026-08-01T18:49:58.815687Z","end":"2026-08-01T18:49:59.216929Z","steps":["trace[1496544712] 'process raft request' (duration: 401.112376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:59.217166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:58.815663Z","time spent":"401.369991ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:59.217222Z","caller":"traceutil/trace.go:171","msg":"trace[1275237577] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"401.061265ms","start":"2026-08-01T18:49:58.816144Z","end":"2026-08-01T18:49:59.217205Z","steps":["trace[1275237577] 'process raft request' (duration: 400.746348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:59.2173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:58.816128Z","time spent":"401.132036ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:59.217429Z","caller":"traceutil/trace.go:171","msg":"trace[440780824] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1111; }","duration":"398.286374ms","start":"2026-08-01T18:49:58.819125Z","end":"2026-08-01T18:49:59.217412Z","steps":["trace[440780824] 'read index received' (duration: 111.190999ms)","trace[440780824] 'applied index is now lower than readState.Index' (duration: 287.094585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:49:59.217563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.423507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:49:59.217588Z","caller":"traceutil/trace.go:171","msg":"trace[1584050404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"398.479428ms","start":"2026-08-01T18:49:58.819102Z","end":"2026-08-01T18:49:59.217581Z","steps":["trace[1584050404] 'agreement among raft nodes before linearized reading' (duration: 398.377475ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:49:59.217564Z","caller":"traceutil/trace.go:171","msg":"trace[2102087390] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"401.337311ms","start":"2026-08-01T18:49:58.816193Z","end":"2026-08-01T18:49:59.21753Z","steps":["trace[2102087390] 'process raft request' (duration: 400.940302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:59.217744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:58.81618Z","time spent":"401.472303ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:49:59.217772Z","caller":"traceutil/trace.go:171","msg":"trace[878918380] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"401.532274ms","start":"2026-08-01T18:49:58.816223Z","end":"2026-08-01T18:49:59.217755Z","steps":["trace[878918380] 'process raft request' (duration: 401.106495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:49:59.217612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:58.819095Z","time spent":"398.510298ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:49:59.217877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:49:58.816214Z","time spent":"401.621026ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:01.290569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:00.778031Z","time spent":"512.533439ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-01T18:50:01.818934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.255128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:50:01.819148Z","caller":"traceutil/trace.go:171","msg":"trace[854478808] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1116; }","duration":"667.024247ms","start":"2026-08-01T18:50:01.152104Z","end":"2026-08-01T18:50:01.819128Z","steps":["trace[854478808] 'read index received' (duration: 138.303413ms)","trace[854478808] 'applied index is now lower than readState.Index' (duration: 528.719614ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:01.819166Z","caller":"traceutil/trace.go:171","msg":"trace[1215415446] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"1.002184576s","start":"2026-08-01T18:50:00.816958Z","end":"2026-08-01T18:50:01.819143Z","steps":["trace[1215415446] 'process raft request' (duration: 596.626123ms)","trace[1215415446] 'compare' (duration: 404.841508ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:01.819296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:00.816929Z","time spent":"1.002298599s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:01.819362Z","caller":"traceutil/trace.go:171","msg":"trace[1590269262] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"527.343364ms","start":"2026-08-01T18:50:01.291999Z","end":"2026-08-01T18:50:01.819342Z","steps":["trace[1590269262] 'process raft request' (duration: 527.035207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:01.819441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.342113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-01T18:50:01.81944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.719077ms","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-08-01T18:50:01.819459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.467773ms","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-08-01T18:50:01.819477Z","caller":"traceutil/trace.go:171","msg":"trace[1585339169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"667.416415ms","start":"2026-08-01T18:50:01.152052Z","end":"2026-08-01T18:50:01.819468Z","steps":["trace[1585339169] 'agreement among raft nodes before linearized reading' (duration: 667.16858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:01.819461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.29198Z","time spent":"527.436105ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:01.819512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.152033Z","time spent":"667.473236ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-01T18:50:01.819477Z","caller":"traceutil/trace.go:171","msg":"trace[1447162002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"597.776917ms","start":"2026-08-01T18:50:01.221694Z","end":"2026-08-01T18:50:01.819471Z","steps":["trace[1447162002] 'agreement among raft nodes before linearized reading' (duration: 597.698146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:01.819552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.221685Z","time spent":"597.860009ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-01T18:50:01.819512Z","caller":"traceutil/trace.go:171","msg":"trace[1160724968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"598.571155ms","start":"2026-08-01T18:50:01.220929Z","end":"2026-08-01T18:50:01.8195Z","steps":["trace[1160724968] 'agreement among raft nodes before linearized reading' (duration: 598.414552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:01.819581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.220909Z","time spent":"598.666287ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-01T18:50:01.819597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.207707ms","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-08-01T18:50:01.819627Z","caller":"traceutil/trace.go:171","msg":"trace[745613985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1070; }","duration":"598.257538ms","start":"2026-08-01T18:50:01.221362Z","end":"2026-08-01T18:50:01.819619Z","steps":["trace[745613985] 'agreement among raft nodes before linearized reading' (duration: 598.180436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:01.819656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.221352Z","time spent":"598.297189ms","remote":"127.0.0.1:35608","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-08-01T18:50:01.81971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.649955ms","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-08-01T18:50:01.819801Z","caller":"traceutil/trace.go:171","msg":"trace[1621544639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"597.777028ms","start":"2026-08-01T18:50:01.222001Z","end":"2026-08-01T18:50:01.819778Z","steps":["trace[1621544639] 'agreement among raft nodes before linearized reading' (duration: 597.438991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:01.81986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.221994Z","time spent":"597.852579ms","remote":"127.0.0.1:35608","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":"info","ts":"2026-08-01T18:50:02.323813Z","caller":"traceutil/trace.go:171","msg":"trace[1728570347] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"499.187476ms","start":"2026-08-01T18:50:01.824596Z","end":"2026-08-01T18:50:02.323783Z","steps":["trace[1728570347] 'process raft request' (duration: 426.506332ms)","trace[1728570347] 'compare' (duration: 71.920017ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:02.323818Z","caller":"traceutil/trace.go:171","msg":"trace[574560876] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"499.127775ms","start":"2026-08-01T18:50:01.824658Z","end":"2026-08-01T18:50:02.323786Z","steps":["trace[574560876] 'process raft request' (duration: 498.865619ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:02.323888Z","caller":"traceutil/trace.go:171","msg":"trace[225432444] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"499.158316ms","start":"2026-08-01T18:50:01.82471Z","end":"2026-08-01T18:50:02.323868Z","steps":["trace[225432444] 'process raft request' (duration: 498.866549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:02.323969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.824586Z","time spent":"499.308278ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:02.323983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.824688Z","time spent":"499.244077ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:02.324001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.82465Z","time spent":"499.257307ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:02.324264Z","caller":"traceutil/trace.go:171","msg":"trace[382094536] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1119; }","duration":"499.476432ms","start":"2026-08-01T18:50:01.824768Z","end":"2026-08-01T18:50:02.324245Z","steps":["trace[382094536] 'read index received' (duration: 49.767561ms)","trace[382094536] 'applied index is now lower than readState.Index' (duration: 449.707451ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:02.324313Z","caller":"traceutil/trace.go:171","msg":"trace[860736201] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"499.37337ms","start":"2026-08-01T18:50:01.824927Z","end":"2026-08-01T18:50:02.3243Z","steps":["trace[860736201] 'process raft request' (duration: 499.165215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:02.324378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.824896Z","time spent":"499.446832ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:02.324471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.136144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:50:02.324514Z","caller":"traceutil/trace.go:171","msg":"trace[375992247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"457.252926ms","start":"2026-08-01T18:50:01.867251Z","end":"2026-08-01T18:50:02.324504Z","steps":["trace[375992247] 'agreement among raft nodes before linearized reading' (duration: 457.156794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:02.324582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.86723Z","time spent":"457.342219ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:50:02.324556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.770919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-01T18:50:02.324669Z","caller":"traceutil/trace.go:171","msg":"trace[1616143974] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1075; }","duration":"499.910432ms","start":"2026-08-01T18:50:01.82474Z","end":"2026-08-01T18:50:02.324651Z","steps":["trace[1616143974] 'agreement among raft nodes before linearized reading' (duration: 499.640086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:02.324762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:01.824732Z","time spent":"500.013864ms","remote":"127.0.0.1:35614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-01T18:50:16.543111Z","caller":"traceutil/trace.go:171","msg":"trace[6822330] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"142.972755ms","start":"2026-08-01T18:50:16.400106Z","end":"2026-08-01T18:50:16.543079Z","steps":["trace[6822330] 'read index received' (duration: 142.842122ms)","trace[6822330] 'applied index is now lower than readState.Index' (duration: 129.323µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:16.543274Z","caller":"traceutil/trace.go:171","msg":"trace[1788565283] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"153.908855ms","start":"2026-08-01T18:50:16.389284Z","end":"2026-08-01T18:50:16.543193Z","steps":["trace[1788565283] 'process raft request' (duration: 153.640819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:16.544803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.672792ms","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-08-01T18:50:16.544849Z","caller":"traceutil/trace.go:171","msg":"trace[87194264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1169; }","duration":"144.766365ms","start":"2026-08-01T18:50:16.400072Z","end":"2026-08-01T18:50:16.544838Z","steps":["trace[87194264] 'agreement among raft nodes before linearized reading' (duration: 143.161359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:16.544865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.167146ms","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-08-01T18:50:16.544915Z","caller":"traceutil/trace.go:171","msg":"trace[194310235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1169; }","duration":"141.234987ms","start":"2026-08-01T18:50:16.403663Z","end":"2026-08-01T18:50:16.544898Z","steps":["trace[194310235] 'agreement among raft nodes before linearized reading' (duration: 139.591561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:16.545039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.346285ms","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-08-01T18:50:16.545064Z","caller":"traceutil/trace.go:171","msg":"trace[354976270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"123.402616ms","start":"2026-08-01T18:50:16.421655Z","end":"2026-08-01T18:50:16.545058Z","steps":["trace[354976270] 'agreement among raft nodes before linearized reading' (duration: 121.637057ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:16.745395Z","caller":"traceutil/trace.go:171","msg":"trace[1650897853] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"196.014959ms","start":"2026-08-01T18:50:16.549363Z","end":"2026-08-01T18:50:16.745378Z","steps":["trace[1650897853] 'process raft request' (duration: 191.544691ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:16.745423Z","caller":"traceutil/trace.go:171","msg":"trace[1213603874] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"195.813944ms","start":"2026-08-01T18:50:16.549592Z","end":"2026-08-01T18:50:16.745406Z","steps":["trace[1213603874] 'process raft request' (duration: 195.476117ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:16.745471Z","caller":"traceutil/trace.go:171","msg":"trace[691429304] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"195.63446ms","start":"2026-08-01T18:50:16.549807Z","end":"2026-08-01T18:50:16.745441Z","steps":["trace[691429304] 'process raft request' (duration: 195.307693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:18.455591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:50:18.956542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:50:19.457238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451576870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-01T18:50:19.665012Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.767199501s","expected-duration":"1s"} {"level":"info","ts":"2026-08-01T18:50:19.665519Z","caller":"traceutil/trace.go:171","msg":"trace[795886168] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"1.767834345s","start":"2026-08-01T18:50:17.897658Z","end":"2026-08-01T18:50:19.665492Z","steps":["trace[795886168] 'process raft request' (duration: 1.76758722s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.665673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:17.89763Z","time spent":"1.767955188s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:19.682021Z","caller":"traceutil/trace.go:171","msg":"trace[906013593] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1226; }","duration":"1.72742832s","start":"2026-08-01T18:50:17.954576Z","end":"2026-08-01T18:50:19.682004Z","steps":["trace[906013593] 'read index received' (duration: 1.710755814s)","trace[906013593] 'applied index is now lower than readState.Index' (duration: 16.671616ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:19.682095Z","caller":"traceutil/trace.go:171","msg":"trace[717128920] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"618.496453ms","start":"2026-08-01T18:50:19.063577Z","end":"2026-08-01T18:50:19.682073Z","steps":["trace[717128920] 'process raft request' (duration: 615.896046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.682207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134898328s","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-08-01T18:50:19.682234Z","caller":"traceutil/trace.go:171","msg":"trace[696519637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"1.134967798s","start":"2026-08-01T18:50:18.547259Z","end":"2026-08-01T18:50:19.682226Z","steps":["trace[696519637] 'agreement among raft nodes before linearized reading' (duration: 1.134842476s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.682235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.727655485s","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-08-01T18:50:19.68226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:18.547243Z","time spent":"1.13501236s","remote":"127.0.0.1:35608","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-08-01T18:50:19.682247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.294394ms","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-08-01T18:50:19.682277Z","caller":"traceutil/trace.go:171","msg":"trace[1244513040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"1.727754247s","start":"2026-08-01T18:50:17.95451Z","end":"2026-08-01T18:50:19.682264Z","steps":["trace[1244513040] 'agreement among raft nodes before linearized reading' (duration: 1.727582364s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.682306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:17.954486Z","time spent":"1.727812318s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-01T18:50:19.682295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.219917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:50:19.682321Z","caller":"traceutil/trace.go:171","msg":"trace[1938331212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"932.357675ms","start":"2026-08-01T18:50:18.749926Z","end":"2026-08-01T18:50:19.682283Z","steps":["trace[1938331212] 'agreement among raft nodes before linearized reading' (duration: 932.199081ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:19.682399Z","caller":"traceutil/trace.go:171","msg":"trace[805200235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"815.360739ms","start":"2026-08-01T18:50:18.867023Z","end":"2026-08-01T18:50:19.682384Z","steps":["trace[805200235] 'agreement among raft nodes before linearized reading' (duration: 815.223286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.682394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:18.74992Z","time spent":"932.463558ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-01T18:50:19.682438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:18.867005Z","time spent":"815.424361ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:50:19.682466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.845426ms","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-08-01T18:50:19.682554Z","caller":"traceutil/trace.go:171","msg":"trace[184045661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1174; }","duration":"932.948138ms","start":"2026-08-01T18:50:18.749585Z","end":"2026-08-01T18:50:19.682533Z","steps":["trace[184045661] 'agreement among raft nodes before linearized reading' (duration: 932.675442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.68259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:19.063549Z","time spent":"618.630996ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:19.682605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:18.749525Z","time spent":"933.07055ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:50:19.682776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.154382ms","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-08-01T18:50:19.682814Z","caller":"traceutil/trace.go:171","msg":"trace[112729410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1174; }","duration":"933.201263ms","start":"2026-08-01T18:50:18.749603Z","end":"2026-08-01T18:50:19.682805Z","steps":["trace[112729410] 'agreement among raft nodes before linearized reading' (duration: 932.59105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:19.68284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:18.749599Z","time spent":"933.234974ms","remote":"127.0.0.1:35608","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":"info","ts":"2026-08-01T18:50:31.87118Z","caller":"traceutil/trace.go:171","msg":"trace[840393522] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"147.911044ms","start":"2026-08-01T18:50:31.723252Z","end":"2026-08-01T18:50:31.871163Z","steps":["trace[840393522] 'process raft request' (duration: 147.593237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:34.588733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.955929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:50:34.588921Z","caller":"traceutil/trace.go:171","msg":"trace[1946947323] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"647.774804ms","start":"2026-08-01T18:50:33.941131Z","end":"2026-08-01T18:50:34.588906Z","steps":["trace[1946947323] 'read index received' (duration: 326.610981ms)","trace[1946947323] 'applied index is now lower than readState.Index' (duration: 321.162923ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:34.589078Z","caller":"traceutil/trace.go:171","msg":"trace[1801109996] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"608.846921ms","start":"2026-08-01T18:50:33.980188Z","end":"2026-08-01T18:50:34.589035Z","steps":["trace[1801109996] 'process raft request' (duration: 608.685227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:34.589198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.314323ms","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-08-01T18:50:34.589218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:33.980174Z","time spent":"608.969093ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:34.589252Z","caller":"traceutil/trace.go:171","msg":"trace[1703620127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"641.400695ms","start":"2026-08-01T18:50:33.947838Z","end":"2026-08-01T18:50:34.589239Z","steps":["trace[1703620127] 'agreement among raft nodes before linearized reading' (duration: 641.216201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:34.589284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:33.947829Z","time spent":"641.449846ms","remote":"127.0.0.1:35608","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-08-01T18:50:34.589329Z","caller":"traceutil/trace.go:171","msg":"trace[549665343] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"665.719319ms","start":"2026-08-01T18:50:33.923595Z","end":"2026-08-01T18:50:34.589314Z","steps":["trace[549665343] 'process raft request' (duration: 344.115196ms)","trace[549665343] 'compare' (duration: 320.651982ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:34.589348Z","caller":"traceutil/trace.go:171","msg":"trace[1047342779] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"658.898928ms","start":"2026-08-01T18:50:33.930428Z","end":"2026-08-01T18:50:34.589327Z","steps":["trace[1047342779] 'process raft request' (duration: 658.393377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:34.589413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:33.92357Z","time spent":"665.79743ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:34.589434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:33.93042Z","time spent":"658.97106ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:34.589437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.284925ms","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-08-01T18:50:34.589414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.356681ms","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":"info","ts":"2026-08-01T18:50:34.589487Z","caller":"traceutil/trace.go:171","msg":"trace[1072414555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"648.364897ms","start":"2026-08-01T18:50:33.941111Z","end":"2026-08-01T18:50:34.589476Z","steps":["trace[1072414555] 'agreement among raft nodes before linearized reading' (duration: 648.198664ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:34.589505Z","caller":"traceutil/trace.go:171","msg":"trace[676074759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1232; }","duration":"214.469314ms","start":"2026-08-01T18:50:34.375017Z","end":"2026-08-01T18:50:34.589486Z","steps":["trace[676074759] 'agreement among raft nodes before linearized reading' (duration: 214.30622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:34.589518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:33.941104Z","time spent":"648.408888ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-01T18:50:35.095616Z","caller":"traceutil/trace.go:171","msg":"trace[1161366004] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"502.271445ms","start":"2026-08-01T18:50:34.593322Z","end":"2026-08-01T18:50:35.095593Z","steps":["trace[1161366004] 'process raft request' (duration: 502.128772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:35.09575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:34.593306Z","time spent":"502.375797ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:35.148415Z","caller":"traceutil/trace.go:171","msg":"trace[1444441492] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"554.919619ms","start":"2026-08-01T18:50:34.59343Z","end":"2026-08-01T18:50:35.14835Z","steps":["trace[1444441492] 'process raft request' (duration: 554.696633ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:35.148268Z","caller":"traceutil/trace.go:171","msg":"trace[214250646] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"478.161625ms","start":"2026-08-01T18:50:34.670086Z","end":"2026-08-01T18:50:35.148247Z","steps":["trace[214250646] 'read index received' (duration: 425.246775ms)","trace[214250646] 'applied index is now lower than readState.Index' (duration: 52.91418ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:35.148557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.436452ms","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":"warn","ts":"2026-08-01T18:50:35.148608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.979882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:50:35.148638Z","caller":"traceutil/trace.go:171","msg":"trace[369210172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"281.039323ms","start":"2026-08-01T18:50:34.86759Z","end":"2026-08-01T18:50:35.14863Z","steps":["trace[369210172] 'agreement among raft nodes before linearized reading' (duration: 280.978202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:35.148601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:34.593402Z","time spent":"555.113714ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:35.148633Z","caller":"traceutil/trace.go:171","msg":"trace[17687824] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1234; }","duration":"478.553894ms","start":"2026-08-01T18:50:34.670063Z","end":"2026-08-01T18:50:35.148616Z","steps":["trace[17687824] 'agreement among raft nodes before linearized reading' (duration: 478.38854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:35.148906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:34.670053Z","time spent":"478.837831ms","remote":"127.0.0.1:35636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-08-01T18:50:36.351412Z","caller":"traceutil/trace.go:171","msg":"trace[617409350] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"462.050712ms","start":"2026-08-01T18:50:35.889339Z","end":"2026-08-01T18:50:36.35139Z","steps":["trace[617409350] 'process raft request' (duration: 431.338388ms)","trace[617409350] 'compare' (duration: 30.627972ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:36.351516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:35.889319Z","time spent":"462.147744ms","remote":"127.0.0.1:35430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-01T18:50:37.164631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.825993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:50:37.164867Z","caller":"traceutil/trace.go:171","msg":"trace[937954952] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.275158431s","start":"2026-08-01T18:50:35.889685Z","end":"2026-08-01T18:50:37.164843Z","steps":["trace[937954952] 'process raft request' (duration: 625.044152ms)","trace[937954952] 'compare' (duration: 649.67482ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:37.164979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:35.889675Z","time spent":"1.275240993s","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:37.16513Z","caller":"traceutil/trace.go:171","msg":"trace[1133298237] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.247648358s","start":"2026-08-01T18:50:35.917474Z","end":"2026-08-01T18:50:37.165123Z","steps":["trace[1133298237] 'process raft request' (duration: 1.247247929s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.165175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:35.917439Z","time spent":"1.247713819s","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:37.355746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.893714ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-01T18:50:37.355867Z","caller":"traceutil/trace.go:171","msg":"trace[554857615] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1295; }","duration":"1.426043948s","start":"2026-08-01T18:50:35.929799Z","end":"2026-08-01T18:50:37.355843Z","steps":["trace[554857615] 'read index received' (duration: 390.942553ms)","trace[554857615] 'applied index is now lower than readState.Index' (duration: 1.035099305s)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:37.356079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426261704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-01T18:50:37.356138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00379461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-01T18:50:37.356165Z","caller":"traceutil/trace.go:171","msg":"trace[1111330247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"1.426357906s","start":"2026-08-01T18:50:35.929786Z","end":"2026-08-01T18:50:37.356144Z","steps":["trace[1111330247] 'agreement among raft nodes before linearized reading' (duration: 1.426128131s)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:37.356177Z","caller":"traceutil/trace.go:171","msg":"trace[1485554104] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1241; }","duration":"1.003880092s","start":"2026-08-01T18:50:36.352284Z","end":"2026-08-01T18:50:37.356164Z","steps":["trace[1485554104] 'agreement among raft nodes before linearized reading' (duration: 1.003778189s)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.356209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:35.929738Z","time spent":"1.426462669s","remote":"127.0.0.1:35608","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-08-01T18:50:37.356215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:36.352264Z","time spent":"1.003941394s","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-01T18:50:37.356358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.120702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-01T18:50:37.356372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.80357ms","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-08-01T18:50:37.356407Z","caller":"traceutil/trace.go:171","msg":"trace[489565256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"762.235474ms","start":"2026-08-01T18:50:36.59416Z","end":"2026-08-01T18:50:37.356396Z","steps":["trace[489565256] 'agreement among raft nodes before linearized reading' (duration: 762.07244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.356436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:36.594148Z","time spent":"762.283875ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-01T18:50:37.356437Z","caller":"traceutil/trace.go:171","msg":"trace[1621001870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"763.898312ms","start":"2026-08-01T18:50:36.592523Z","end":"2026-08-01T18:50:37.356421Z","steps":["trace[1621001870] 'agreement among raft nodes before linearized reading' (duration: 763.713648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.356455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.674072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:50:37.356455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.812095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-01T18:50:37.35648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:36.592509Z","time spent":"763.962294ms","remote":"127.0.0.1:35608","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":"info","ts":"2026-08-01T18:50:37.356503Z","caller":"traceutil/trace.go:171","msg":"trace[1673425395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"488.760944ms","start":"2026-08-01T18:50:36.867729Z","end":"2026-08-01T18:50:37.35649Z","steps":["trace[1673425395] 'agreement among raft nodes before linearized reading' (duration: 488.679312ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:37.356509Z","caller":"traceutil/trace.go:171","msg":"trace[777197457] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1241; }","duration":"164.874256ms","start":"2026-08-01T18:50:37.191622Z","end":"2026-08-01T18:50:37.356497Z","steps":["trace[777197457] 'agreement among raft nodes before linearized reading' (duration: 164.780884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.356535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:36.867711Z","time spent":"488.818246ms","remote":"127.0.0.1:35356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:50:37.356629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.855242ms","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-08-01T18:50:37.356712Z","caller":"traceutil/trace.go:171","msg":"trace[304087264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"203.962064ms","start":"2026-08-01T18:50:37.152717Z","end":"2026-08-01T18:50:37.356679Z","steps":["trace[304087264] 'agreement among raft nodes before linearized reading' (duration: 203.794521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.356849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.432192ms","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-08-01T18:50:37.356886Z","caller":"traceutil/trace.go:171","msg":"trace[615225055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"257.501604ms","start":"2026-08-01T18:50:37.099374Z","end":"2026-08-01T18:50:37.356876Z","steps":["trace[615225055] 'agreement among raft nodes before linearized reading' (duration: 256.705656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:37.652982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.547812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-01T18:50:37.653295Z","caller":"traceutil/trace.go:171","msg":"trace[249261628] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"289.307044ms","start":"2026-08-01T18:50:37.363971Z","end":"2026-08-01T18:50:37.653278Z","steps":["trace[249261628] 'process raft request' (duration: 289.267534ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:37.653544Z","caller":"traceutil/trace.go:171","msg":"trace[1070422898] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"291.502344ms","start":"2026-08-01T18:50:37.362031Z","end":"2026-08-01T18:50:37.653533Z","steps":["trace[1070422898] 'process raft request' (duration: 291.040554ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:37.653522Z","caller":"traceutil/trace.go:171","msg":"trace[342264062] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"292.883947ms","start":"2026-08-01T18:50:37.360582Z","end":"2026-08-01T18:50:37.653466Z","steps":["trace[342264062] 'process raft request' (duration: 99.774662ms)","trace[342264062] 'compare' (duration: 192.337736ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:37.653637Z","caller":"traceutil/trace.go:171","msg":"trace[1283988471] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"290.304016ms","start":"2026-08-01T18:50:37.363325Z","end":"2026-08-01T18:50:37.653629Z","steps":["trace[1283988471] 'process raft request' (duration: 289.880397ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:49.363715Z","caller":"traceutil/trace.go:171","msg":"trace[459915190] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"116.831553ms","start":"2026-08-01T18:50:49.246859Z","end":"2026-08-01T18:50:49.36369Z","steps":["trace[459915190] 'process raft request' (duration: 116.661689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:50.768695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.047796ms","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-08-01T18:50:50.768808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.061524ms","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-08-01T18:50:50.768839Z","caller":"traceutil/trace.go:171","msg":"trace[1137702342] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1337; }","duration":"878.137835ms","start":"2026-08-01T18:50:49.890692Z","end":"2026-08-01T18:50:50.76883Z","steps":["trace[1137702342] 'range keys from in-memory index tree' (duration: 877.727326ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:50.76882Z","caller":"traceutil/trace.go:171","msg":"trace[939595949] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"835.214521ms","start":"2026-08-01T18:50:49.933583Z","end":"2026-08-01T18:50:50.768797Z","steps":["trace[939595949] 'range keys from in-memory index tree' (duration: 834.869372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:50.768864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:49.890672Z","time spent":"878.186227ms","remote":"127.0.0.1:35608","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":"warn","ts":"2026-08-01T18:50:50.76888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:49.933568Z","time spent":"835.298402ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-01T18:50:51.279743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451577217,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-01T18:50:51.393338Z","caller":"traceutil/trace.go:171","msg":"trace[1107945025] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"987.459775ms","start":"2026-08-01T18:50:50.405852Z","end":"2026-08-01T18:50:51.393312Z","steps":["trace[1107945025] 'process raft request' (duration: 987.303951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.393492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:50.405826Z","time spent":"987.587928ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:51.447812Z","caller":"traceutil/trace.go:171","msg":"trace[1095564869] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"675.143904ms","start":"2026-08-01T18:50:50.772645Z","end":"2026-08-01T18:50:51.447789Z","steps":["trace[1095564869] 'process raft request' (duration: 674.952359ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:51.448085Z","caller":"traceutil/trace.go:171","msg":"trace[625896385] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1397; }","duration":"669.393412ms","start":"2026-08-01T18:50:50.778669Z","end":"2026-08-01T18:50:51.448062Z","steps":["trace[625896385] 'read index received' (duration: 614.535001ms)","trace[625896385] 'applied index is now lower than readState.Index' (duration: 54.857521ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:51.448113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:50.772629Z","time spent":"675.253816ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-01T18:50:51.448143Z","caller":"traceutil/trace.go:171","msg":"trace[150469079] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"674.389447ms","start":"2026-08-01T18:50:50.773718Z","end":"2026-08-01T18:50:51.448107Z","steps":["trace[150469079] 'process raft request' (duration: 674.029059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.448264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.633823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-01T18:50:51.448301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.613597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-01T18:50:51.448319Z","caller":"traceutil/trace.go:171","msg":"trace[226231428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"580.730185ms","start":"2026-08-01T18:50:50.867576Z","end":"2026-08-01T18:50:51.448306Z","steps":["trace[226231428] 'agreement among raft nodes before linearized reading' (duration: 580.641263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.448318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:50.773709Z","time spent":"674.515809ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:51.44835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:50.867556Z","time spent":"580.786967ms","remote":"127.0.0.1:35354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:50:51.448402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.628811ms","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-08-01T18:50:51.448337Z","caller":"traceutil/trace.go:171","msg":"trace[565894910] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:1341; }","duration":"669.707229ms","start":"2026-08-01T18:50:50.77862Z","end":"2026-08-01T18:50:51.448327Z","steps":["trace[565894910] 'agreement among raft nodes before linearized reading' (duration: 669.564416ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:51.448442Z","caller":"traceutil/trace.go:171","msg":"trace[744406211] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"279.709743ms","start":"2026-08-01T18:50:51.168717Z","end":"2026-08-01T18:50:51.448427Z","steps":["trace[744406211] 'agreement among raft nodes before linearized reading' (duration: 279.59018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.448472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:50.77861Z","time spent":"669.849022ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-08-01T18:50:51.758275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.793778ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-08-01T18:50:51.758454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:51.450004Z","time spent":"308.443414ms","remote":"127.0.0.1:35382","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-01T18:50:51.758586Z","caller":"traceutil/trace.go:171","msg":"trace[1110299095] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"305.71799ms","start":"2026-08-01T18:50:51.45285Z","end":"2026-08-01T18:50:51.758568Z","steps":["trace[1110299095] 'process raft request' (duration: 305.573007ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:51.758608Z","caller":"traceutil/trace.go:171","msg":"trace[104162363] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1401; }","duration":"308.196308ms","start":"2026-08-01T18:50:51.450384Z","end":"2026-08-01T18:50:51.75858Z","steps":["trace[104162363] 'read index received' (duration: 81.037591ms)","trace[104162363] 'applied index is now lower than readState.Index' (duration: 227.156227ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:50:51.758691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:51.452821Z","time spent":"305.807893ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:50:51.758768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.397752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:50:51.758822Z","caller":"traceutil/trace.go:171","msg":"trace[239819521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"308.479184ms","start":"2026-08-01T18:50:51.450327Z","end":"2026-08-01T18:50:51.758806Z","steps":["trace[239819521] 'agreement among raft nodes before linearized reading' (duration: 308.356721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.760376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:50:51.450316Z","time spent":"310.039519ms","remote":"127.0.0.1:35356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-01T18:50:51.758861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.371667ms","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-08-01T18:50:51.760769Z","caller":"traceutil/trace.go:171","msg":"trace[934895630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1342; }","duration":"160.319453ms","start":"2026-08-01T18:50:51.600437Z","end":"2026-08-01T18:50:51.760757Z","steps":["trace[934895630] 'agreement among raft nodes before linearized reading' (duration: 158.284866ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:51.87042Z","caller":"traceutil/trace.go:171","msg":"trace[66952928] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"108.849299ms","start":"2026-08-01T18:50:51.761545Z","end":"2026-08-01T18:50:51.870394Z","steps":["trace[66952928] 'process raft request' (duration: 104.962369ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:50:51.88295Z","caller":"traceutil/trace.go:171","msg":"trace[747637224] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"115.4105ms","start":"2026-08-01T18:50:51.767519Z","end":"2026-08-01T18:50:51.882929Z","steps":["trace[747637224] 'read index received' (duration: 99.003343ms)","trace[747637224] 'applied index is now lower than readState.Index' (duration: 16.406317ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:50:51.883021Z","caller":"traceutil/trace.go:171","msg":"trace[696092000] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"118.608404ms","start":"2026-08-01T18:50:51.764406Z","end":"2026-08-01T18:50:51.883014Z","steps":["trace[696092000] 'process raft request' (duration: 118.381819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.883276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.236639ms","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-08-01T18:50:51.883366Z","caller":"traceutil/trace.go:171","msg":"trace[1508496083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"109.375291ms","start":"2026-08-01T18:50:51.773968Z","end":"2026-08-01T18:50:51.883344Z","steps":["trace[1508496083] 'agreement among raft nodes before linearized reading' (duration: 109.118545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.883365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.602793ms","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-08-01T18:50:51.88346Z","caller":"traceutil/trace.go:171","msg":"trace[228250003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"108.720715ms","start":"2026-08-01T18:50:51.774719Z","end":"2026-08-01T18:50:51.883439Z","steps":["trace[228250003] 'agreement among raft nodes before linearized reading' (duration: 108.4795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.883368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.280159ms","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-08-01T18:50:51.883593Z","caller":"traceutil/trace.go:171","msg":"trace[2037562767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"109.543195ms","start":"2026-08-01T18:50:51.774035Z","end":"2026-08-01T18:50:51.883578Z","steps":["trace[2037562767] 'agreement among raft nodes before linearized reading' (duration: 109.215718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:50:51.883664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.068465ms","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-08-01T18:50:51.883738Z","caller":"traceutil/trace.go:171","msg":"trace[1959955219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"116.2318ms","start":"2026-08-01T18:50:51.767492Z","end":"2026-08-01T18:50:51.883724Z","steps":["trace[1959955219] 'agreement among raft nodes before linearized reading' (duration: 115.85337ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:52:20.459177Z","caller":"traceutil/trace.go:171","msg":"trace[1827865155] linearizableReadLoop","detail":"{readStateIndex:1828; appliedIndex:1826; }","duration":"104.754192ms","start":"2026-08-01T18:52:20.35439Z","end":"2026-08-01T18:52:20.459144Z","steps":["trace[1827865155] 'read index received' (duration: 51.900905ms)","trace[1827865155] 'applied index is now lower than readState.Index' (duration: 52.851997ms)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:52:20.459229Z","caller":"traceutil/trace.go:171","msg":"trace[1710883520] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"109.187974ms","start":"2026-08-01T18:52:20.35001Z","end":"2026-08-01T18:52:20.459198Z","steps":["trace[1710883520] 'process raft request' (duration: 56.201344ms)","trace[1710883520] 'compare' (duration: 52.685593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-01T18:52:20.459534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.624159ms","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-08-01T18:52:20.459573Z","caller":"traceutil/trace.go:171","msg":"trace[260666135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1750; }","duration":"104.70954ms","start":"2026-08-01T18:52:20.354854Z","end":"2026-08-01T18:52:20.459563Z","steps":["trace[260666135] 'agreement among raft nodes before linearized reading' (duration: 104.516636ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:52:20.460312Z","caller":"traceutil/trace.go:171","msg":"trace[1983619437] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"110.216307ms","start":"2026-08-01T18:52:20.350077Z","end":"2026-08-01T18:52:20.460293Z","steps":["trace[1983619437] 'process raft request' (duration: 108.989799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:52:20.460532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.119004ms","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-08-01T18:52:20.460602Z","caller":"traceutil/trace.go:171","msg":"trace[245582693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1750; }","duration":"106.246807ms","start":"2026-08-01T18:52:20.35434Z","end":"2026-08-01T18:52:20.460586Z","steps":["trace[245582693] 'agreement among raft nodes before linearized reading' (duration: 106.019571ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:53:45.3937Z","caller":"traceutil/trace.go:171","msg":"trace[1136562169] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"217.883981ms","start":"2026-08-01T18:53:45.1758Z","end":"2026-08-01T18:53:45.393684Z","steps":["trace[1136562169] 'read index received' (duration: 217.789029ms)","trace[1136562169] 'applied index is now lower than readState.Index' (duration: 94.112µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:53:45.393879Z","caller":"traceutil/trace.go:171","msg":"trace[979849052] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"286.144491ms","start":"2026-08-01T18:53:45.107725Z","end":"2026-08-01T18:53:45.39387Z","steps":["trace[979849052] 'process raft request' (duration: 285.867254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:53:45.394062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.257791ms","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-08-01T18:53:45.394085Z","caller":"traceutil/trace.go:171","msg":"trace[376074816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2138; }","duration":"218.320281ms","start":"2026-08-01T18:53:45.175759Z","end":"2026-08-01T18:53:45.39408Z","steps":["trace[376074816] 'agreement among raft nodes before linearized reading' (duration: 218.234219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:53:45.394195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.539143ms","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-08-01T18:53:45.394213Z","caller":"traceutil/trace.go:171","msg":"trace[261281924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"204.557613ms","start":"2026-08-01T18:53:45.189651Z","end":"2026-08-01T18:53:45.394209Z","steps":["trace[261281924] 'agreement among raft nodes before linearized reading' (duration: 204.511492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:53:45.394274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.246415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:53:45.394291Z","caller":"traceutil/trace.go:171","msg":"trace[194907395] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2138; }","duration":"191.265455ms","start":"2026-08-01T18:53:45.20302Z","end":"2026-08-01T18:53:45.394286Z","steps":["trace[194907395] 'agreement among raft nodes before linearized reading' (duration: 191.236894ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:54:54.587098Z","caller":"traceutil/trace.go:171","msg":"trace[905485005] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"151.659114ms","start":"2026-08-01T18:54:54.435406Z","end":"2026-08-01T18:54:54.587065Z","steps":["trace[905485005] 'process raft request' (duration: 151.51282ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:54:54.609485Z","caller":"traceutil/trace.go:171","msg":"trace[1083706207] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"140.797555ms","start":"2026-08-01T18:54:54.468659Z","end":"2026-08-01T18:54:54.609457Z","steps":["trace[1083706207] 'process raft request' (duration: 135.897032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:54:56.307306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.133509ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-08-01T18:54:57.128226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.551863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-01T18:54:57.128313Z","caller":"traceutil/trace.go:171","msg":"trace[2125189734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2485; }","duration":"261.681626ms","start":"2026-08-01T18:54:56.866608Z","end":"2026-08-01T18:54:57.12829Z","steps":["trace[2125189734] 'range keys from in-memory index tree' (duration: 261.44142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:54:57.12836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.164012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-08-01T18:54:57.128445Z","caller":"traceutil/trace.go:171","msg":"trace[1406238792] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2485; }","duration":"118.297665ms","start":"2026-08-01T18:54:57.010127Z","end":"2026-08-01T18:54:57.128424Z","steps":["trace[1406238792] 'range keys from in-memory index tree' (duration: 118.007459ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:55:03.098941Z","caller":"traceutil/trace.go:171","msg":"trace[1246628915] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"136.431903ms","start":"2026-08-01T18:55:02.96249Z","end":"2026-08-01T18:55:03.098922Z","steps":["trace[1246628915] 'process raft request' (duration: 136.29953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:55:04.045538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853972229451580133,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-01T18:55:04.217895Z","caller":"traceutil/trace.go:171","msg":"trace[1903082257] linearizableReadLoop","detail":"{readStateIndex:2635; appliedIndex:2634; }","duration":"673.068856ms","start":"2026-08-01T18:55:03.544801Z","end":"2026-08-01T18:55:04.21787Z","steps":["trace[1903082257] 'read index received' (duration: 672.941234ms)","trace[1903082257] 'applied index is now lower than readState.Index' (duration: 126.652µs)"],"step_count":2} {"level":"info","ts":"2026-08-01T18:55:04.218062Z","caller":"traceutil/trace.go:171","msg":"trace[1907683828] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"735.079493ms","start":"2026-08-01T18:55:03.482971Z","end":"2026-08-01T18:55:04.218051Z","steps":["trace[1907683828] 'process raft request' (duration: 734.753776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:55:04.218244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:55:03.48295Z","time spent":"735.173925ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-01T18:55:04.218515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.780442ms","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":"warn","ts":"2026-08-01T18:55:04.218678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.655067ms","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-08-01T18:55:04.218757Z","caller":"traceutil/trace.go:171","msg":"trace[186600249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2523; }","duration":"293.77872ms","start":"2026-08-01T18:55:03.92496Z","end":"2026-08-01T18:55:04.218739Z","steps":["trace[186600249] 'agreement among raft nodes before linearized reading' (duration: 293.515464ms)"],"step_count":1} {"level":"info","ts":"2026-08-01T18:55:04.218785Z","caller":"traceutil/trace.go:171","msg":"trace[1952747604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2523; }","duration":"291.043788ms","start":"2026-08-01T18:55:03.927683Z","end":"2026-08-01T18:55:04.218726Z","steps":["trace[1952747604] 'agreement among raft nodes before linearized reading' (duration: 290.734282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:55:04.218578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.561622ms","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-08-01T18:55:04.218916Z","caller":"traceutil/trace.go:171","msg":"trace[794265354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2523; }","duration":"438.90575ms","start":"2026-08-01T18:55:03.779952Z","end":"2026-08-01T18:55:04.218858Z","steps":["trace[794265354] 'agreement among raft nodes before linearized reading' (duration: 438.4415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:55:04.218626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.807443ms","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-08-01T18:55:04.219004Z","caller":"traceutil/trace.go:171","msg":"trace[160833564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2523; }","duration":"674.212992ms","start":"2026-08-01T18:55:03.544779Z","end":"2026-08-01T18:55:04.218992Z","steps":["trace[160833564] 'agreement among raft nodes before linearized reading' (duration: 673.731692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-01T18:55:04.218993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:55:03.779937Z","time spent":"439.040013ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-01T18:55:04.219047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-01T18:55:03.54477Z","time spent":"674.264513ms","remote":"127.0.0.1:35608","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\" "}