{"level":"warn","ts":"2026-07-21T13:16:27.162461Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:16:27.162601Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.68:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.68:2380","--initial-cluster=instance=https://199.19.213.68:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.68:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.68: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-07-21T13:16:27.162695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:16:27.16271Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.68:2380"]} {"level":"info","ts":"2026-07-21T13:16:27.162744Z","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-07-21T13:16:27.163572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"]} {"level":"info","ts":"2026-07-21T13:16:27.163674Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.68:2380"],"listen-peer-urls":["https://199.19.213.68:2380"],"advertise-client-urls":["https://199.19.213.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.68: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-07-21T13:16:27.167413Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.458865ms"} {"level":"info","ts":"2026-07-21T13:16:27.17405Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"78a0acde880f698","cluster-id":"45d27515d88ba308"} {"level":"info","ts":"2026-07-21T13:16:27.174119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T13:16:27.17414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became follower at term 0"} {"level":"info","ts":"2026-07-21T13:16:27.174158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 78a0acde880f698 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T13:16:27.174165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became follower at term 1"} {"level":"info","ts":"2026-07-21T13:16:27.174195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=(543258584536905368)"} {"level":"warn","ts":"2026-07-21T13:16:27.180508Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T13:16:27.183829Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T13:16:27.18484Z","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-07-21T13:16:27.188372Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"78a0acde880f698","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T13:16:27.188752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"78a0acde880f698","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T13:16:27.188938Z","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-07-21T13:16:27.189029Z","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-07-21T13:16:27.189045Z","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-07-21T13:16:27.198306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=(543258584536905368)"} {"level":"info","ts":"2026-07-21T13:16:27.198405Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45d27515d88ba308","local-member-id":"78a0acde880f698","added-peer-id":"78a0acde880f698","added-peer-peer-urls":["https://199.19.213.68:2380"]} {"level":"info","ts":"2026-07-21T13:16:27.199929Z","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-07-21T13:16:27.200144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"78a0acde880f698","initial-advertise-peer-urls":["https://199.19.213.68:2380"],"listen-peer-urls":["https://199.19.213.68:2380"],"advertise-client-urls":["https://199.19.213.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T13:16:27.200168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T13:16:27.200334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.68:2380"} {"level":"info","ts":"2026-07-21T13:16:27.200357Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.68:2380"} {"level":"info","ts":"2026-07-21T13:16:27.975082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T13:16:27.975171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T13:16:27.975255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 received MsgPreVoteResp from 78a0acde880f698 at term 1"} {"level":"info","ts":"2026-07-21T13:16:27.975286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became candidate at term 2"} {"level":"info","ts":"2026-07-21T13:16:27.975302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 received MsgVoteResp from 78a0acde880f698 at term 2"} {"level":"info","ts":"2026-07-21T13:16:27.975318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became leader at term 2"} {"level":"info","ts":"2026-07-21T13:16:27.975335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 78a0acde880f698 elected leader 78a0acde880f698 at term 2"} {"level":"info","ts":"2026-07-21T13:16:27.976407Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"78a0acde880f698","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.68:2379]}","request-path":"/0/members/78a0acde880f698/attributes","cluster-id":"45d27515d88ba308","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T13:16:27.976406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:16:27.976519Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:16:27.976473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:16:27.976918Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T13:16:27.976962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T13:16:27.977777Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45d27515d88ba308","local-member-id":"78a0acde880f698","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:16:27.977885Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:16:27.977922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:16:27.980775Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T13:16:27.981042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.68:2379"} {"level":"info","ts":"2026-07-21T13:16:30.567515Z","caller":"traceutil/trace.go:171","msg":"trace[1305002721] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"107.626785ms","start":"2026-07-21T13:16:30.459868Z","end":"2026-07-21T13:16:30.567495Z","steps":["trace[1305002721] 'process raft request' (duration: 101.807756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.323239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.123307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:16:33.323399Z","caller":"traceutil/trace.go:171","msg":"trace[1085268901] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"368.054291ms","start":"2026-07-21T13:16:32.955316Z","end":"2026-07-21T13:16:33.32337Z","steps":["trace[1085268901] 'read index received' (duration: 219.16512ms)","trace[1085268901] 'applied index is now lower than readState.Index' (duration: 148.887841ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:33.323457Z","caller":"traceutil/trace.go:171","msg":"trace[286025241] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"431.704165ms","start":"2026-07-21T13:16:32.891719Z","end":"2026-07-21T13:16:33.323423Z","steps":["trace[286025241] 'process raft request' (duration: 282.819585ms)","trace[286025241] 'compare' (duration: 147.956013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:33.323578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.250964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-21T13:16:33.323617Z","caller":"traceutil/trace.go:171","msg":"trace[2122114320] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:279; }","duration":"368.320825ms","start":"2026-07-21T13:16:32.955286Z","end":"2026-07-21T13:16:33.323607Z","steps":["trace[2122114320] 'agreement among raft nodes before linearized reading' (duration: 368.183932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.323606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:32.8917Z","time spent":"431.839148ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.323666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:32.955274Z","time spent":"368.383776ms","remote":"127.0.0.1:46858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-21T13:16:33.378633Z","caller":"traceutil/trace.go:171","msg":"trace[1483231015] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"324.056104ms","start":"2026-07-21T13:16:33.054561Z","end":"2026-07-21T13:16:33.378617Z","steps":["trace[1483231015] 'process raft request' (duration: 323.927172ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.378674Z","caller":"traceutil/trace.go:171","msg":"trace[2051913152] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"419.439443ms","start":"2026-07-21T13:16:32.959218Z","end":"2026-07-21T13:16:33.378657Z","steps":["trace[2051913152] 'process raft request' (duration: 419.224469ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.378688Z","caller":"traceutil/trace.go:171","msg":"trace[425641565] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"421.519803ms","start":"2026-07-21T13:16:32.957114Z","end":"2026-07-21T13:16:33.378634Z","steps":["trace[425641565] 'process raft request' (duration: 421.280229ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.378603Z","caller":"traceutil/trace.go:171","msg":"trace[1969790523] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"422.932559ms","start":"2026-07-21T13:16:32.955596Z","end":"2026-07-21T13:16:33.378529Z","steps":["trace[1969790523] 'process raft request' (duration: 422.626003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.378786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:32.959211Z","time spent":"419.499994ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.378807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:32.955587Z","time spent":"423.189803ms","remote":"127.0.0.1:46500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.378821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:33.05454Z","time spent":"324.171046ms","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.378793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:32.957108Z","time spent":"421.624564ms","remote":"127.0.0.1:46702","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-07-21T13:16:33.378871Z","caller":"traceutil/trace.go:171","msg":"trace[2014925239] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"306.953423ms","start":"2026-07-21T13:16:33.071877Z","end":"2026-07-21T13:16:33.378831Z","steps":["trace[2014925239] 'process raft request' (duration: 306.710329ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.378706Z","caller":"traceutil/trace.go:171","msg":"trace[955915864] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"307.899522ms","start":"2026-07-21T13:16:33.070795Z","end":"2026-07-21T13:16:33.378695Z","steps":["trace[955915864] 'process raft request' (duration: 307.741299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.37905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:33.070765Z","time spent":"308.249757ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:16:33.379075Z","caller":"traceutil/trace.go:171","msg":"trace[1525364012] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"306.153239ms","start":"2026-07-21T13:16:33.07289Z","end":"2026-07-21T13:16:33.379043Z","steps":["trace[1525364012] 'process raft request' (duration: 305.832963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.379071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:33.07187Z","time spent":"307.103606ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.37913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:33.072853Z","time spent":"306.249081ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:16:33.379221Z","caller":"traceutil/trace.go:171","msg":"trace[587919479] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"307.133958ms","start":"2026-07-21T13:16:33.072077Z","end":"2026-07-21T13:16:33.379211Z","steps":["trace[587919479] 'process raft request' (duration: 306.541787ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.379244Z","caller":"traceutil/trace.go:171","msg":"trace[293210061] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"306.484845ms","start":"2026-07-21T13:16:33.072748Z","end":"2026-07-21T13:16:33.379233Z","steps":["trace[293210061] 'process raft request' (duration: 305.910624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.379265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:33.072039Z","time spent":"307.204638ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.379297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:33.072732Z","time spent":"306.540056ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:16:33.379297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.823536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:33.379338Z","caller":"traceutil/trace.go:171","msg":"trace[929054909] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:286; }","duration":"423.881008ms","start":"2026-07-21T13:16:32.955444Z","end":"2026-07-21T13:16:33.379325Z","steps":["trace[929054909] 'agreement among raft nodes before linearized reading' (duration: 423.798576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.379364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:32.955441Z","time spent":"423.916338ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-07-21T13:16:33.668331Z","caller":"traceutil/trace.go:171","msg":"trace[1182608219] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"126.829568ms","start":"2026-07-21T13:16:33.54148Z","end":"2026-07-21T13:16:33.668309Z","steps":["trace[1182608219] 'read index received' (duration: 126.743285ms)","trace[1182608219] 'applied index is now lower than readState.Index' (duration: 85.273µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:33.668461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.977181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-21T13:16:33.668484Z","caller":"traceutil/trace.go:171","msg":"trace[523535146] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:305; }","duration":"127.050082ms","start":"2026-07-21T13:16:33.541428Z","end":"2026-07-21T13:16:33.668478Z","steps":["trace[523535146] 'agreement among raft nodes before linearized reading' (duration: 126.97454ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.669107Z","caller":"traceutil/trace.go:171","msg":"trace[966085997] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"139.486244ms","start":"2026-07-21T13:16:33.529609Z","end":"2026-07-21T13:16:33.669095Z","steps":["trace[966085997] 'process raft request' (duration: 138.583197ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:34.214443Z","caller":"traceutil/trace.go:171","msg":"trace[1439355545] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"157.911807ms","start":"2026-07-21T13:16:34.056515Z","end":"2026-07-21T13:16:34.214426Z","steps":["trace[1439355545] 'read index received' (duration: 157.692562ms)","trace[1439355545] 'applied index is now lower than readState.Index' (duration: 218.755µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:34.214546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.021049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-21T13:16:34.214564Z","caller":"traceutil/trace.go:171","msg":"trace[1919608442] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:316; }","duration":"158.0981ms","start":"2026-07-21T13:16:34.056459Z","end":"2026-07-21T13:16:34.214557Z","steps":["trace[1919608442] 'agreement among raft nodes before linearized reading' (duration: 158.046919ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:34.2146Z","caller":"traceutil/trace.go:171","msg":"trace[10408847] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"173.773593ms","start":"2026-07-21T13:16:34.040768Z","end":"2026-07-21T13:16:34.214542Z","steps":["trace[10408847] 'process raft request' (duration: 173.63105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:34.214729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.057909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-07-21T13:16:34.214749Z","caller":"traceutil/trace.go:171","msg":"trace[814166021] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:316; }","duration":"158.12427ms","start":"2026-07-21T13:16:34.056619Z","end":"2026-07-21T13:16:34.214744Z","steps":["trace[814166021] 'agreement among raft nodes before linearized reading' (duration: 158.09041ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:34.214636Z","caller":"traceutil/trace.go:171","msg":"trace[1541523461] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"189.145751ms","start":"2026-07-21T13:16:34.025456Z","end":"2026-07-21T13:16:34.214602Z","steps":["trace[1541523461] 'process raft request' (duration: 188.813914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:34.214817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.024489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-21T13:16:34.21537Z","caller":"traceutil/trace.go:171","msg":"trace[1483050185] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:316; }","duration":"158.539878ms","start":"2026-07-21T13:16:34.056723Z","end":"2026-07-21T13:16:34.215263Z","steps":["trace[1483050185] 'agreement among raft nodes before linearized reading' (duration: 157.980788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:34.21483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.159391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-07-21T13:16:34.215487Z","caller":"traceutil/trace.go:171","msg":"trace[1409334966] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:316; }","duration":"158.893185ms","start":"2026-07-21T13:16:34.05657Z","end":"2026-07-21T13:16:34.215464Z","steps":["trace[1409334966] 'agreement among raft nodes before linearized reading' (duration: 158.10144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:34.760609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599098580,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:16:34.772737Z","caller":"traceutil/trace.go:171","msg":"trace[203569993] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"513.051965ms","start":"2026-07-21T13:16:34.259664Z","end":"2026-07-21T13:16:34.772716Z","steps":["trace[203569993] 'process raft request' (duration: 512.911723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:34.773026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:34.259637Z","time spent":"513.177028ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:16:34.773243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.525214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:34.773271Z","caller":"traceutil/trace.go:171","msg":"trace[995718096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:318; }","duration":"513.558285ms","start":"2026-07-21T13:16:34.259704Z","end":"2026-07-21T13:16:34.773263Z","steps":["trace[995718096] 'agreement among raft nodes before linearized reading' (duration: 513.499664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:34.773291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:34.259586Z","time spent":"513.700698ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-07-21T13:16:34.773562Z","caller":"traceutil/trace.go:171","msg":"trace[724689992] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:331; }","duration":"512.824581ms","start":"2026-07-21T13:16:34.259907Z","end":"2026-07-21T13:16:34.772732Z","steps":["trace[724689992] 'read index received' (duration: 512.81304ms)","trace[724689992] 'applied index is now lower than readState.Index' (duration: 8.74µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:34.773743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.61181ms","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-07-21T13:16:34.773778Z","caller":"traceutil/trace.go:171","msg":"trace[1202781213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:318; }","duration":"283.757773ms","start":"2026-07-21T13:16:34.490008Z","end":"2026-07-21T13:16:34.773766Z","steps":["trace[1202781213] 'agreement among raft nodes before linearized reading' (duration: 283.663002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:37.117842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.749566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:16:37.117988Z","caller":"traceutil/trace.go:171","msg":"trace[1509614164] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"217.167333ms","start":"2026-07-21T13:16:36.900796Z","end":"2026-07-21T13:16:37.117964Z","steps":["trace[1509614164] 'process raft request' (duration: 68.221943ms)","trace[1509614164] 'compare' (duration: 148.562603ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:37.118385Z","caller":"traceutil/trace.go:171","msg":"trace[8706474] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"213.544285ms","start":"2026-07-21T13:16:36.904824Z","end":"2026-07-21T13:16:37.118369Z","steps":["trace[8706474] 'process raft request' (duration: 213.388162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:37.118714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.40213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-21T13:16:37.11883Z","caller":"traceutil/trace.go:171","msg":"trace[1114620127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:361; }","duration":"177.587173ms","start":"2026-07-21T13:16:36.941218Z","end":"2026-07-21T13:16:37.118806Z","steps":["trace[1114620127] 'agreement among raft nodes before linearized reading' (duration: 177.246817ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:37.118316Z","caller":"traceutil/trace.go:171","msg":"trace[149987414] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:372; }","duration":"177.018553ms","start":"2026-07-21T13:16:36.941279Z","end":"2026-07-21T13:16:37.118298Z","steps":["trace[149987414] 'read index received' (duration: 27.844579ms)","trace[149987414] 'applied index is now lower than readState.Index' (duration: 149.172544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:37.119039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.883256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-21T13:16:37.1191Z","caller":"traceutil/trace.go:171","msg":"trace[1708131383] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:361; }","duration":"127.997108ms","start":"2026-07-21T13:16:36.991084Z","end":"2026-07-21T13:16:37.119081Z","steps":["trace[1708131383] 'agreement among raft nodes before linearized reading' (duration: 127.886376ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:37.413644Z","caller":"traceutil/trace.go:171","msg":"trace[1904276140] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:375; }","duration":"273.45448ms","start":"2026-07-21T13:16:37.140167Z","end":"2026-07-21T13:16:37.413621Z","steps":["trace[1904276140] 'read index received' (duration: 12.207827ms)","trace[1904276140] 'applied index is now lower than readState.Index' (duration: 261.245483ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:37.413755Z","caller":"traceutil/trace.go:171","msg":"trace[184516077] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"283.178882ms","start":"2026-07-21T13:16:37.130566Z","end":"2026-07-21T13:16:37.413745Z","steps":["trace[184516077] 'process raft request' (duration: 243.013062ms)","trace[184516077] 'compare' (duration: 39.825863ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:37.413875Z","caller":"traceutil/trace.go:171","msg":"trace[2034439016] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"282.447159ms","start":"2026-07-21T13:16:37.131409Z","end":"2026-07-21T13:16:37.413856Z","steps":["trace[2034439016] 'process raft request' (duration: 282.153303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:37.413971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.799487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-21T13:16:37.414004Z","caller":"traceutil/trace.go:171","msg":"trace[333362704] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:365; }","duration":"273.882748ms","start":"2026-07-21T13:16:37.140114Z","end":"2026-07-21T13:16:37.413997Z","steps":["trace[333362704] 'agreement among raft nodes before linearized reading' (duration: 273.795497ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:37.414148Z","caller":"traceutil/trace.go:171","msg":"trace[1791009494] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"283.339705ms","start":"2026-07-21T13:16:37.130663Z","end":"2026-07-21T13:16:37.414003Z","steps":["trace[1791009494] 'process raft request' (duration: 282.855426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:37.414132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.039345ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:37.414438Z","caller":"traceutil/trace.go:171","msg":"trace[781991135] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:365; }","duration":"123.346212ms","start":"2026-07-21T13:16:37.291083Z","end":"2026-07-21T13:16:37.414429Z","steps":["trace[781991135] 'agreement among raft nodes before linearized reading' (duration: 123.030105ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:38.924977Z","caller":"traceutil/trace.go:171","msg":"trace[1762466888] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"117.987248ms","start":"2026-07-21T13:16:38.806964Z","end":"2026-07-21T13:16:38.924951Z","steps":["trace[1762466888] 'process raft request' (duration: 117.836235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:40.539121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599098750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:16:41.042261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599098750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:16:41.553633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599098750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:16:42.054457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599098750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:16:42.55546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599098750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:16:42.910091Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.871167288s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:16:42.910878Z","caller":"traceutil/trace.go:171","msg":"trace[501573231] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"2.87179692s","start":"2026-07-21T13:16:40.039048Z","end":"2026-07-21T13:16:42.910845Z","steps":["trace[501573231] 'read index received' (duration: 2.87129683s)","trace[501573231] 'applied index is now lower than readState.Index' (duration: 498.7µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:42.91093Z","caller":"traceutil/trace.go:171","msg":"trace[838481905] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"2.872122546s","start":"2026-07-21T13:16:40.038769Z","end":"2026-07-21T13:16:42.910891Z","steps":["trace[838481905] 'process raft request' (duration: 2.8717837s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.911123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.872049165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T13:16:42.911176Z","caller":"traceutil/trace.go:171","msg":"trace[1719335482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"2.872131226s","start":"2026-07-21T13:16:40.039028Z","end":"2026-07-21T13:16:42.911159Z","steps":["trace[1719335482] 'agreement among raft nodes before linearized reading' (duration: 2.872006984s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.911227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:40.038962Z","time spent":"2.872254878s","remote":"127.0.0.1:46702","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-07-21T13:16:42.911221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:40.038728Z","time spent":"2.87236429s","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:16:42.911254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.980412064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T13:16:42.911316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414281102s","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-07-21T13:16:42.911395Z","caller":"traceutil/trace.go:171","msg":"trace[969751256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"1.980593896s","start":"2026-07-21T13:16:40.930763Z","end":"2026-07-21T13:16:42.911357Z","steps":["trace[969751256] 'agreement among raft nodes before linearized reading' (duration: 1.980348532s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.911473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:40.930747Z","time spent":"1.980711169s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:16:42.911479Z","caller":"traceutil/trace.go:171","msg":"trace[1777831263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"1.414466416s","start":"2026-07-21T13:16:41.496962Z","end":"2026-07-21T13:16:42.911428Z","steps":["trace[1777831263] 'agreement among raft nodes before linearized reading' (duration: 1.41415834s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.911583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:41.496874Z","time spent":"1.414697361s","remote":"127.0.0.1:46702","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-07-21T13:16:42.911654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78257999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:42.911706Z","caller":"traceutil/trace.go:171","msg":"trace[319239091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"1.782652611s","start":"2026-07-21T13:16:41.129043Z","end":"2026-07-21T13:16:42.911695Z","steps":["trace[319239091] 'agreement among raft nodes before linearized reading' (duration: 1.78258303s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.91174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:41.129026Z","time spent":"1.782706883s","remote":"127.0.0.1:46428","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-07-21T13:16:42.911942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.147232ms","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-07-21T13:16:42.912035Z","caller":"traceutil/trace.go:171","msg":"trace[866744402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:400; }","duration":"953.258374ms","start":"2026-07-21T13:16:41.958734Z","end":"2026-07-21T13:16:42.911992Z","steps":["trace[866744402] 'agreement among raft nodes before linearized reading' (duration: 953.148022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.91208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:41.958704Z","time spent":"953.364777ms","remote":"127.0.0.1:46702","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-07-21T13:16:42.912121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.808806536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.68\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T13:16:42.91223Z","caller":"traceutil/trace.go:171","msg":"trace[937226699] range","detail":"{range_begin:/registry/masterleases/199.19.213.68; range_end:; response_count:1; response_revision:400; }","duration":"1.808978799s","start":"2026-07-21T13:16:41.103218Z","end":"2026-07-21T13:16:42.912197Z","steps":["trace[937226699] 'agreement among raft nodes before linearized reading' (duration: 1.808692664s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:42.912283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:41.103194Z","time spent":"1.809078391s","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.68\" "} {"level":"warn","ts":"2026-07-21T13:16:43.086692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.587607ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-07-21T13:16:43.087054Z","caller":"traceutil/trace.go:171","msg":"trace[903363235] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:414; }","duration":"169.249217ms","start":"2026-07-21T13:16:42.917785Z","end":"2026-07-21T13:16:43.087034Z","steps":["trace[903363235] 'read index received' (duration: 50.371125ms)","trace[903363235] 'applied index is now lower than readState.Index' (duration: 118.877042ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:43.08708Z","caller":"traceutil/trace.go:171","msg":"trace[364986015] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"170.502619ms","start":"2026-07-21T13:16:42.916537Z","end":"2026-07-21T13:16:43.08704Z","steps":["trace[364986015] 'process raft request' (duration: 170.279985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:43.087246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.44938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-07-21T13:16:43.087266Z","caller":"traceutil/trace.go:171","msg":"trace[1702063474] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"169.576534ms","start":"2026-07-21T13:16:42.917672Z","end":"2026-07-21T13:16:43.087248Z","steps":["trace[1702063474] 'process raft request' (duration: 169.306268ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:43.087283Z","caller":"traceutil/trace.go:171","msg":"trace[1521427814] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:404; }","duration":"169.533702ms","start":"2026-07-21T13:16:42.917739Z","end":"2026-07-21T13:16:43.087272Z","steps":["trace[1521427814] 'agreement among raft nodes before linearized reading' (duration: 169.400549ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:43.087373Z","caller":"traceutil/trace.go:171","msg":"trace[1489104303] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"169.341739ms","start":"2026-07-21T13:16:42.918021Z","end":"2026-07-21T13:16:43.087363Z","steps":["trace[1489104303] 'process raft request' (duration: 168.979531ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:43.087423Z","caller":"traceutil/trace.go:171","msg":"trace[1102889707] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"170.717843ms","start":"2026-07-21T13:16:42.916694Z","end":"2026-07-21T13:16:43.087412Z","steps":["trace[1102889707] 'process raft request' (duration: 170.253775ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:53.522837Z","caller":"traceutil/trace.go:171","msg":"trace[1892515938] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"386.487747ms","start":"2026-07-21T13:16:53.136327Z","end":"2026-07-21T13:16:53.522815Z","steps":["trace[1892515938] 'read index received' (duration: 386.266382ms)","trace[1892515938] 'applied index is now lower than readState.Index' (duration: 220.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:53.523196Z","caller":"traceutil/trace.go:171","msg":"trace[101637632] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"387.76797ms","start":"2026-07-21T13:16:53.135414Z","end":"2026-07-21T13:16:53.523182Z","steps":["trace[101637632] 'process raft request' (duration: 387.261441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:53.523309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.135365Z","time spent":"387.859382ms","remote":"127.0.0.1:46702","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-07-21T13:16:53.523484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.161649ms","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-07-21T13:16:53.523519Z","caller":"traceutil/trace.go:171","msg":"trace[202395788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"387.224031ms","start":"2026-07-21T13:16:53.136283Z","end":"2026-07-21T13:16:53.523507Z","steps":["trace[202395788] 'agreement among raft nodes before linearized reading' (duration: 387.166289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:53.523547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.13626Z","time spent":"387.280732ms","remote":"127.0.0.1:46702","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-07-21T13:16:53.523686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.55382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T13:16:53.523714Z","caller":"traceutil/trace.go:171","msg":"trace[1530464783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"385.607051ms","start":"2026-07-21T13:16:53.138099Z","end":"2026-07-21T13:16:53.523706Z","steps":["trace[1530464783] 'agreement among raft nodes before linearized reading' (duration: 385.55719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:53.523737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.138085Z","time spent":"385.646261ms","remote":"127.0.0.1:46702","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-07-21T13:16:53.523937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.059974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T13:16:53.523971Z","caller":"traceutil/trace.go:171","msg":"trace[1092134934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"383.114824ms","start":"2026-07-21T13:16:53.140845Z","end":"2026-07-21T13:16:53.52396Z","steps":["trace[1092134934] 'agreement among raft nodes before linearized reading' (duration: 383.061223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:53.523997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.140832Z","time spent":"383.156235ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T13:16:54.256268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.807472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:16:54.25654Z","caller":"traceutil/trace.go:171","msg":"trace[1800608461] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:446; }","duration":"338.761308ms","start":"2026-07-21T13:16:53.917758Z","end":"2026-07-21T13:16:54.25652Z","steps":["trace[1800608461] 'read index received' (duration: 312.71202ms)","trace[1800608461] 'applied index is now lower than readState.Index' (duration: 26.048178ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:54.256631Z","caller":"traceutil/trace.go:171","msg":"trace[880742908] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"726.69922ms","start":"2026-07-21T13:16:53.529906Z","end":"2026-07-21T13:16:54.256605Z","steps":["trace[880742908] 'process raft request' (duration: 726.493866ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:54.256654Z","caller":"traceutil/trace.go:171","msg":"trace[1802137835] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"530.297846ms","start":"2026-07-21T13:16:53.726331Z","end":"2026-07-21T13:16:54.256629Z","steps":["trace[1802137835] 'process raft request' (duration: 530.145133ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:54.256644Z","caller":"traceutil/trace.go:171","msg":"trace[688201888] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"725.402756ms","start":"2026-07-21T13:16:53.53121Z","end":"2026-07-21T13:16:54.256613Z","steps":["trace[688201888] 'process raft request' (duration: 725.237194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:54.256788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.726243Z","time spent":"530.468978ms","remote":"127.0.0.1:46702","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-07-21T13:16:54.256792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.52989Z","time spent":"726.808203ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:16:54.256743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.024632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:54.256837Z","caller":"traceutil/trace.go:171","msg":"trace[1952068526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"339.117354ms","start":"2026-07-21T13:16:53.917708Z","end":"2026-07-21T13:16:54.256826Z","steps":["trace[1952068526] 'agreement among raft nodes before linearized reading' (duration: 338.86125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:54.256901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.917622Z","time spent":"339.269506ms","remote":"127.0.0.1:46428","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-07-21T13:16:54.256964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.531183Z","time spent":"725.60335ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:16:54.256993Z","caller":"traceutil/trace.go:171","msg":"trace[576308009] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"727.905113ms","start":"2026-07-21T13:16:53.528887Z","end":"2026-07-21T13:16:54.256792Z","steps":["trace[576308009] 'process raft request' (duration: 360.46167ms)","trace[576308009] 'compare' (duration: 366.612888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:54.257344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:16:53.528867Z","time spent":"728.332341ms","remote":"127.0.0.1:46702","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-07-21T13:17:08.633658Z","caller":"traceutil/trace.go:171","msg":"trace[1810308320] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"255.500725ms","start":"2026-07-21T13:17:08.378134Z","end":"2026-07-21T13:17:08.633635Z","steps":["trace[1810308320] 'read index received' (duration: 255.296401ms)","trace[1810308320] 'applied index is now lower than readState.Index' (duration: 203.424µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:08.633829Z","caller":"traceutil/trace.go:171","msg":"trace[1843961763] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"279.207453ms","start":"2026-07-21T13:17:08.354594Z","end":"2026-07-21T13:17:08.633802Z","steps":["trace[1843961763] 'process raft request' (duration: 278.879618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:08.633877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.117584ms","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-07-21T13:17:08.633912Z","caller":"traceutil/trace.go:171","msg":"trace[1728757148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"247.192566ms","start":"2026-07-21T13:17:08.386709Z","end":"2026-07-21T13:17:08.633902Z","steps":["trace[1728757148] 'agreement among raft nodes before linearized reading' (duration: 247.125004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:08.633907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.734399ms","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-07-21T13:17:08.634129Z","caller":"traceutil/trace.go:171","msg":"trace[1989015953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"255.971513ms","start":"2026-07-21T13:17:08.378094Z","end":"2026-07-21T13:17:08.634066Z","steps":["trace[1989015953] 'agreement among raft nodes before linearized reading' (duration: 255.690269ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:08.745803Z","caller":"traceutil/trace.go:171","msg":"trace[1020811265] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"108.12152ms","start":"2026-07-21T13:17:08.637665Z","end":"2026-07-21T13:17:08.745787Z","steps":["trace[1020811265] 'process raft request' (duration: 63.254751ms)","trace[1020811265] 'compare' (duration: 44.684446ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:09.742538Z","caller":"traceutil/trace.go:171","msg":"trace[1642234116] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"127.072299ms","start":"2026-07-21T13:17:09.615428Z","end":"2026-07-21T13:17:09.7425Z","steps":["trace[1642234116] 'process raft request' (duration: 126.834604ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:10.4858Z","caller":"traceutil/trace.go:171","msg":"trace[391315224] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"248.259786ms","start":"2026-07-21T13:17:10.237519Z","end":"2026-07-21T13:17:10.485779Z","steps":["trace[391315224] 'process raft request' (duration: 152.060356ms)","trace[391315224] 'compare' (duration: 96.103859ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:13.452979Z","caller":"traceutil/trace.go:171","msg":"trace[253483618] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"215.211881ms","start":"2026-07-21T13:17:13.237732Z","end":"2026-07-21T13:17:13.452944Z","steps":["trace[253483618] 'process raft request' (duration: 214.929866ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:13.591673Z","caller":"traceutil/trace.go:171","msg":"trace[208396015] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"289.89297ms","start":"2026-07-21T13:17:13.301758Z","end":"2026-07-21T13:17:13.591651Z","steps":["trace[208396015] 'read index received' (duration: 150.980388ms)","trace[208396015] 'applied index is now lower than readState.Index' (duration: 138.911382ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:13.591823Z","caller":"traceutil/trace.go:171","msg":"trace[136243384] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"305.203775ms","start":"2026-07-21T13:17:13.286532Z","end":"2026-07-21T13:17:13.591736Z","steps":["trace[136243384] 'process raft request' (duration: 302.511526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:13.591991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:13.286507Z","time spent":"305.413778ms","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:17:13.591859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.080973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:13.592093Z","caller":"traceutil/trace.go:171","msg":"trace[1356002698] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:630; }","duration":"290.333847ms","start":"2026-07-21T13:17:13.301723Z","end":"2026-07-21T13:17:13.592057Z","steps":["trace[1356002698] 'agreement among raft nodes before linearized reading' (duration: 290.041492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:14.414225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:17:15.577378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.073707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:17:15.577955Z","caller":"traceutil/trace.go:171","msg":"trace[774455467] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.785022344s","start":"2026-07-21T13:17:13.792917Z","end":"2026-07-21T13:17:15.57794Z","steps":["trace[774455467] 'process raft request' (duration: 1.784624276s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.578012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:13.792886Z","time spent":"1.785097644s","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:17:15.578049Z","caller":"traceutil/trace.go:171","msg":"trace[921797559] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.272253361s","start":"2026-07-21T13:17:14.305733Z","end":"2026-07-21T13:17:15.577987Z","steps":["trace[921797559] 'process raft request' (duration: 1.272115738s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:15.578077Z","caller":"traceutil/trace.go:171","msg":"trace[184748938] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:655; }","duration":"1.664074563s","start":"2026-07-21T13:17:13.913957Z","end":"2026-07-21T13:17:15.578032Z","steps":["trace[184748938] 'read index received' (duration: 665.367976ms)","trace[184748938] 'applied index is now lower than readState.Index' (duration: 998.702357ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:15.578102Z","caller":"traceutil/trace.go:171","msg":"trace[408290980] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.102774973s","start":"2026-07-21T13:17:14.475302Z","end":"2026-07-21T13:17:15.578077Z","steps":["trace[408290980] 'process raft request' (duration: 1.10256917s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:15.578117Z","caller":"traceutil/trace.go:171","msg":"trace[1686495753] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.718425368s","start":"2026-07-21T13:17:13.859663Z","end":"2026-07-21T13:17:15.578088Z","steps":["trace[1686495753] 'process raft request' (duration: 1.718115613s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.578238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.475277Z","time spent":"1.102890134s","remote":"127.0.0.1:46702","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-07-21T13:17:15.578241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:13.859591Z","time spent":"1.718589792s","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:17:15.578263Z","caller":"traceutil/trace.go:171","msg":"trace[1583496103] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.672557506s","start":"2026-07-21T13:17:13.905697Z","end":"2026-07-21T13:17:15.578255Z","steps":["trace[1583496103] 'process raft request' (duration: 1.672132789s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:15.578273Z","caller":"traceutil/trace.go:171","msg":"trace[805211690] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.809761957s","start":"2026-07-21T13:17:13.768487Z","end":"2026-07-21T13:17:15.578248Z","steps":["trace[805211690] 'process raft request' (duration: 810.748013ms)","trace[805211690] 'compare' (duration: 997.939235ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:15.578277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664293608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:15.578291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.305706Z","time spent":"1.272457893s","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:17:15.578305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:13.90568Z","time spent":"1.672608176s","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:17:15.578314Z","caller":"traceutil/trace.go:171","msg":"trace[525872342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.664377869s","start":"2026-07-21T13:17:13.913925Z","end":"2026-07-21T13:17:15.578303Z","steps":["trace[525872342] 'agreement among raft nodes before linearized reading' (duration: 1.664273477s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.578335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:13.76847Z","time spent":"1.809830858s","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:15.578346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:13.913907Z","time spent":"1.664432739s","remote":"127.0.0.1:46428","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-07-21T13:17:15.59904Z","caller":"traceutil/trace.go:171","msg":"trace[1692673854] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"291.493048ms","start":"2026-07-21T13:17:15.307521Z","end":"2026-07-21T13:17:15.599015Z","steps":["trace[1692673854] 'process raft request' (duration: 291.306965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.59956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.3039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-21T13:17:15.599617Z","caller":"traceutil/trace.go:171","msg":"trace[466740376] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"897.410423ms","start":"2026-07-21T13:17:14.702193Z","end":"2026-07-21T13:17:15.599604Z","steps":["trace[466740376] 'agreement among raft nodes before linearized reading' (duration: 897.23228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.59965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.702175Z","time spent":"897.468353ms","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:17:15.599714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.017377ms","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-07-21T13:17:15.59976Z","caller":"traceutil/trace.go:171","msg":"trace[1126414426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"920.107779ms","start":"2026-07-21T13:17:14.679637Z","end":"2026-07-21T13:17:15.599745Z","steps":["trace[1126414426] 'agreement among raft nodes before linearized reading' (duration: 919.963797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.599854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.264749ms","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-07-21T13:17:15.599875Z","caller":"traceutil/trace.go:171","msg":"trace[526722656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:640; }","duration":"652.31638ms","start":"2026-07-21T13:17:14.947553Z","end":"2026-07-21T13:17:15.599869Z","steps":["trace[526722656] 'agreement among raft nodes before linearized reading' (duration: 652.273489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.599862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496862395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:15.599972Z","caller":"traceutil/trace.go:171","msg":"trace[2145659968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:640; }","duration":"1.497030138s","start":"2026-07-21T13:17:14.102913Z","end":"2026-07-21T13:17:15.599943Z","steps":["trace[2145659968] 'agreement among raft nodes before linearized reading' (duration: 1.496794444s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.600019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.317483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-21T13:17:15.600052Z","caller":"traceutil/trace.go:171","msg":"trace[899059193] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"698.382855ms","start":"2026-07-21T13:17:14.901661Z","end":"2026-07-21T13:17:15.600044Z","steps":["trace[899059193] 'agreement among raft nodes before linearized reading' (duration: 698.324794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.600008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.350868ms","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-07-21T13:17:15.600084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.901645Z","time spent":"698.431336ms","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:17:15.600095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.641861ms","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-07-21T13:17:15.599814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.838646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-21T13:17:15.60012Z","caller":"traceutil/trace.go:171","msg":"trace[1244461869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"850.478271ms","start":"2026-07-21T13:17:14.749602Z","end":"2026-07-21T13:17:15.60008Z","steps":["trace[1244461869] 'agreement among raft nodes before linearized reading' (duration: 850.306378ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:15.600139Z","caller":"traceutil/trace.go:171","msg":"trace[1104641910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"828.720211ms","start":"2026-07-21T13:17:14.771406Z","end":"2026-07-21T13:17:15.600126Z","steps":["trace[1104641910] 'agreement among raft nodes before linearized reading' (duration: 828.631779ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:15.600148Z","caller":"traceutil/trace.go:171","msg":"trace[1170358874] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"497.201272ms","start":"2026-07-21T13:17:15.102932Z","end":"2026-07-21T13:17:15.600133Z","steps":["trace[1170358874] 'agreement among raft nodes before linearized reading' (duration: 496.825685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.600193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:15.102913Z","time spent":"497.269274ms","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:17:15.599898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.947533Z","time spent":"652.35969ms","remote":"127.0.0.1:46702","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-07-21T13:17:15.600183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.771384Z","time spent":"828.789483ms","remote":"127.0.0.1:46702","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-07-21T13:17:15.600037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.102894Z","time spent":"1.49713415s","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:17:15.600152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.749585Z","time spent":"850.558772ms","remote":"127.0.0.1:46702","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-07-21T13:17:15.599848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.679617Z","time spent":"920.2215ms","remote":"127.0.0.1:46702","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-07-21T13:17:15.600669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097911255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:15.59958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.876832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:15.600749Z","caller":"traceutil/trace.go:171","msg":"trace[1370640697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"352.084702ms","start":"2026-07-21T13:17:15.248658Z","end":"2026-07-21T13:17:15.600742Z","steps":["trace[1370640697] 'agreement among raft nodes before linearized reading' (duration: 350.599296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.600793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:15.248641Z","time spent":"352.145524ms","remote":"127.0.0.1:46428","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-07-21T13:17:15.600701Z","caller":"traceutil/trace.go:171","msg":"trace[1678638665] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:640; }","duration":"1.097968736s","start":"2026-07-21T13:17:14.502721Z","end":"2026-07-21T13:17:15.600689Z","steps":["trace[1678638665] 'agreement among raft nodes before linearized reading' (duration: 1.097924936s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:15.600985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:14.502703Z","time spent":"1.098275722s","remote":"127.0.0.1:46480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-21T13:17:17.799078Z","caller":"traceutil/trace.go:171","msg":"trace[285117126] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"119.374764ms","start":"2026-07-21T13:17:17.679692Z","end":"2026-07-21T13:17:17.799067Z","steps":["trace[285117126] 'process raft request' (duration: 119.119308ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:17.79934Z","caller":"traceutil/trace.go:171","msg":"trace[1158482642] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"118.937416ms","start":"2026-07-21T13:17:17.680374Z","end":"2026-07-21T13:17:17.799311Z","steps":["trace[1158482642] 'process raft request' (duration: 118.63081ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:17.798933Z","caller":"traceutil/trace.go:171","msg":"trace[752561029] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"121.647943ms","start":"2026-07-21T13:17:17.67723Z","end":"2026-07-21T13:17:17.798878Z","steps":["trace[752561029] 'process raft request' (duration: 116.064644ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:18.155819Z","caller":"traceutil/trace.go:171","msg":"trace[804639875] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"180.96332ms","start":"2026-07-21T13:17:17.974836Z","end":"2026-07-21T13:17:18.155799Z","steps":["trace[804639875] 'read index received' (duration: 180.4056ms)","trace[804639875] 'applied index is now lower than readState.Index' (duration: 556.88µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:18.155967Z","caller":"traceutil/trace.go:171","msg":"trace[1054089985] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"182.190653ms","start":"2026-07-21T13:17:17.973725Z","end":"2026-07-21T13:17:18.155916Z","steps":["trace[1054089985] 'process raft request' (duration: 181.745086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.156043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.183214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.156075Z","caller":"traceutil/trace.go:171","msg":"trace[911827201] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:674; }","duration":"181.270246ms","start":"2026-07-21T13:17:17.974795Z","end":"2026-07-21T13:17:18.156065Z","steps":["trace[911827201] 'agreement among raft nodes before linearized reading' (duration: 181.119653ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:18.392105Z","caller":"traceutil/trace.go:171","msg":"trace[15151498] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"236.184137ms","start":"2026-07-21T13:17:18.155886Z","end":"2026-07-21T13:17:18.39207Z","steps":["trace[15151498] 'read index received' (duration: 146.033628ms)","trace[15151498] 'applied index is now lower than readState.Index' (duration: 90.149119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:18.392333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.443283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:18.392329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.801938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:18.39233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.895399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.392396Z","caller":"traceutil/trace.go:171","msg":"trace[1511296978] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:675; }","duration":"292.912809ms","start":"2026-07-21T13:17:18.099465Z","end":"2026-07-21T13:17:18.392378Z","steps":["trace[1511296978] 'agreement among raft nodes before linearized reading' (duration: 292.773537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.392433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.684906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:18.392445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.23084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.392466Z","caller":"traceutil/trace.go:171","msg":"trace[1203870410] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:675; }","duration":"399.733228ms","start":"2026-07-21T13:17:17.992724Z","end":"2026-07-21T13:17:18.392457Z","steps":["trace[1203870410] 'agreement among raft nodes before linearized reading' (duration: 399.680917ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:18.392461Z","caller":"traceutil/trace.go:171","msg":"trace[617863725] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:675; }","duration":"293.081253ms","start":"2026-07-21T13:17:18.099345Z","end":"2026-07-21T13:17:18.392427Z","steps":["trace[617863725] 'agreement among raft nodes before linearized reading' (duration: 292.781108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.392498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:17.992715Z","time spent":"399.773568ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"info","ts":"2026-07-21T13:17:18.392501Z","caller":"traceutil/trace.go:171","msg":"trace[1475720754] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:675; }","duration":"328.291531ms","start":"2026-07-21T13:17:18.064191Z","end":"2026-07-21T13:17:18.392482Z","steps":["trace[1475720754] 'agreement among raft nodes before linearized reading' (duration: 328.21264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.392561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:18.064188Z","time spent":"328.362382ms","remote":"127.0.0.1:43384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:17:18.392671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.214034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.392727Z","caller":"traceutil/trace.go:171","msg":"trace[12800143] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:675; }","duration":"293.276386ms","start":"2026-07-21T13:17:18.099436Z","end":"2026-07-21T13:17:18.392712Z","steps":["trace[12800143] 'agreement among raft nodes before linearized reading' (duration: 293.192464ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:18.392759Z","caller":"traceutil/trace.go:171","msg":"trace[1174155304] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"332.13474ms","start":"2026-07-21T13:17:18.060598Z","end":"2026-07-21T13:17:18.392732Z","steps":["trace[1174155304] 'process raft request' (duration: 241.40991ms)","trace[1174155304] 'compare' (duration: 89.868904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:18.392798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.346102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.392837Z","caller":"traceutil/trace.go:171","msg":"trace[1762384510] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:675; }","duration":"417.383533ms","start":"2026-07-21T13:17:17.97544Z","end":"2026-07-21T13:17:18.392824Z","steps":["trace[1762384510] 'agreement among raft nodes before linearized reading' (duration: 417.319842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.39233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.95365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:18.392879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:17.975412Z","time spent":"417.457065ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"info","ts":"2026-07-21T13:17:18.392896Z","caller":"traceutil/trace.go:171","msg":"trace[641605948] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:675; }","duration":"270.573262ms","start":"2026-07-21T13:17:18.122302Z","end":"2026-07-21T13:17:18.392875Z","steps":["trace[641605948] 'agreement among raft nodes before linearized reading' (duration: 269.862559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.392911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.71974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.392956Z","caller":"traceutil/trace.go:171","msg":"trace[1726198727] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:675; }","duration":"328.772031ms","start":"2026-07-21T13:17:18.064173Z","end":"2026-07-21T13:17:18.392945Z","steps":["trace[1726198727] 'agreement among raft nodes before linearized reading' (duration: 328.70621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.392994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:18.06417Z","time spent":"328.815751ms","remote":"127.0.0.1:43384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-07-21T13:17:18.39306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.143726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.3931Z","caller":"traceutil/trace.go:171","msg":"trace[537519401] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:675; }","duration":"418.200017ms","start":"2026-07-21T13:17:17.974889Z","end":"2026-07-21T13:17:18.393089Z","steps":["trace[537519401] 'agreement among raft nodes before linearized reading' (duration: 418.140706ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:18.392384Z","caller":"traceutil/trace.go:171","msg":"trace[1811897129] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:675; }","duration":"399.540884ms","start":"2026-07-21T13:17:17.992826Z","end":"2026-07-21T13:17:18.392367Z","steps":["trace[1811897129] 'agreement among raft nodes before linearized reading' (duration: 399.420712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.393131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.031575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:18.392328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.632268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.39317Z","caller":"traceutil/trace.go:171","msg":"trace[782716865] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:675; }","duration":"329.089215ms","start":"2026-07-21T13:17:18.064071Z","end":"2026-07-21T13:17:18.39316Z","steps":["trace[782716865] 'agreement among raft nodes before linearized reading' (duration: 329.034364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.393191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.334072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:18.393205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:18.064059Z","time spent":"329.139297ms","remote":"127.0.0.1:43384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T13:17:18.393178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:17.992821Z","time spent":"400.320368ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-07-21T13:17:18.393232Z","caller":"traceutil/trace.go:171","msg":"trace[1427434248] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:675; }","duration":"151.445953ms","start":"2026-07-21T13:17:18.241773Z","end":"2026-07-21T13:17:18.393219Z","steps":["trace[1427434248] 'agreement among raft nodes before linearized reading' (duration: 151.388672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.393136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:17.974871Z","time spent":"418.255278ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:17:18.392901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:18.060576Z","time spent":"332.244473ms","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4414,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:17:18.393192Z","caller":"traceutil/trace.go:171","msg":"trace[1265904640] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:675; }","duration":"399.535545ms","start":"2026-07-21T13:17:17.993638Z","end":"2026-07-21T13:17:18.393174Z","steps":["trace[1265904640] 'agreement among raft nodes before linearized reading' (duration: 398.575638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.392831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.495881ms","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-07-21T13:17:18.393443Z","caller":"traceutil/trace.go:171","msg":"trace[747893613] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:675; }","duration":"271.136492ms","start":"2026-07-21T13:17:18.122295Z","end":"2026-07-21T13:17:18.393432Z","steps":["trace[747893613] 'agreement among raft nodes before linearized reading' (duration: 270.49916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:18.393443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:17.993633Z","time spent":"399.78364ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-07-21T13:17:18.393744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.984651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:18.39381Z","caller":"traceutil/trace.go:171","msg":"trace[1509232062] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:675; }","duration":"270.827946ms","start":"2026-07-21T13:17:18.122965Z","end":"2026-07-21T13:17:18.393793Z","steps":["trace[1509232062] 'agreement among raft nodes before linearized reading' (duration: 269.971501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.353582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.504102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:21.353675Z","caller":"traceutil/trace.go:171","msg":"trace[1258755297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"441.639195ms","start":"2026-07-21T13:17:20.912016Z","end":"2026-07-21T13:17:21.353656Z","steps":["trace[1258755297] 'range keys from in-memory index tree' (duration: 441.384151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.353708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:20.912002Z","time spent":"441.698896ms","remote":"127.0.0.1:46436","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-07-21T13:17:21.605103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099284,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:17:21.626507Z","caller":"traceutil/trace.go:171","msg":"trace[243383780] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"522.049395ms","start":"2026-07-21T13:17:21.104441Z","end":"2026-07-21T13:17:21.626491Z","steps":["trace[243383780] 'read index received' (duration: 521.523706ms)","trace[243383780] 'applied index is now lower than readState.Index' (duration: 525.029µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:21.626608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.148057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.68\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T13:17:21.626634Z","caller":"traceutil/trace.go:171","msg":"trace[1694667688] range","detail":"{range_begin:/registry/masterleases/199.19.213.68; range_end:; response_count:1; response_revision:690; }","duration":"522.214878ms","start":"2026-07-21T13:17:21.104412Z","end":"2026-07-21T13:17:21.626627Z","steps":["trace[1694667688] 'agreement among raft nodes before linearized reading' (duration: 522.139537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.626661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.104398Z","time spent":"522.256989ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.68\" "} {"level":"info","ts":"2026-07-21T13:17:21.627907Z","caller":"traceutil/trace.go:171","msg":"trace[1952541733] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"564.734985ms","start":"2026-07-21T13:17:21.063138Z","end":"2026-07-21T13:17:21.627873Z","steps":["trace[1952541733] 'process raft request' (duration: 563.000423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.628032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.063099Z","time spent":"564.862337ms","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:21.931938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.90933ms","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-07-21T13:17:21.932046Z","caller":"traceutil/trace.go:171","msg":"trace[886093911] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:690; }","duration":"168.027502ms","start":"2026-07-21T13:17:21.764002Z","end":"2026-07-21T13:17:21.93203Z","steps":["trace[886093911] 'range keys from in-memory index tree' (duration: 167.89371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.932121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.629062Z","time spent":"303.051186ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T13:17:21.988679Z","caller":"traceutil/trace.go:171","msg":"trace[58590452] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"301.098671ms","start":"2026-07-21T13:17:21.687552Z","end":"2026-07-21T13:17:21.98865Z","steps":["trace[58590452] 'read index received' (duration: 182.540231ms)","trace[58590452] 'applied index is now lower than readState.Index' (duration: 118.55697ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:21.988846Z","caller":"traceutil/trace.go:171","msg":"trace[2144546087] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"306.045769ms","start":"2026-07-21T13:17:21.682761Z","end":"2026-07-21T13:17:21.988807Z","steps":["trace[2144546087] 'process raft request' (duration: 305.705413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.989011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.68274Z","time spent":"306.193032ms","remote":"127.0.0.1:46814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:17:21.989065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.488867ms","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-07-21T13:17:21.98912Z","caller":"traceutil/trace.go:171","msg":"trace[877411168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"301.55913ms","start":"2026-07-21T13:17:21.687545Z","end":"2026-07-21T13:17:21.989104Z","steps":["trace[877411168] 'agreement among raft nodes before linearized reading' (duration: 301.235093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:21.989207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.687492Z","time spent":"301.701742ms","remote":"127.0.0.1:46702","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-07-21T13:17:22.02897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.039624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:22.029036Z","caller":"traceutil/trace.go:171","msg":"trace[1372223378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"117.153486ms","start":"2026-07-21T13:17:21.911865Z","end":"2026-07-21T13:17:22.029018Z","steps":["trace[1372223378] 'agreement among raft nodes before linearized reading' (duration: 117.001522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.029393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.80888ms","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-07-21T13:17:22.029433Z","caller":"traceutil/trace.go:171","msg":"trace[582643386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"216.86213ms","start":"2026-07-21T13:17:21.812558Z","end":"2026-07-21T13:17:22.029421Z","steps":["trace[582643386] 'agreement among raft nodes before linearized reading' (duration: 216.719228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.029604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.652513ms","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-07-21T13:17:22.029636Z","caller":"traceutil/trace.go:171","msg":"trace[1531316702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"217.720606ms","start":"2026-07-21T13:17:21.811906Z","end":"2026-07-21T13:17:22.029627Z","steps":["trace[1531316702] 'agreement among raft nodes before linearized reading' (duration: 217.636454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.029795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.939728ms","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-07-21T13:17:22.029821Z","caller":"traceutil/trace.go:171","msg":"trace[1827970587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"218.999209ms","start":"2026-07-21T13:17:21.810815Z","end":"2026-07-21T13:17:22.029814Z","steps":["trace[1827970587] 'agreement among raft nodes before linearized reading' (duration: 218.921058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.029966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.676107ms","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-07-21T13:17:22.029993Z","caller":"traceutil/trace.go:171","msg":"trace[55912644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"289.724558ms","start":"2026-07-21T13:17:21.740261Z","end":"2026-07-21T13:17:22.029985Z","steps":["trace[55912644] 'agreement among raft nodes before linearized reading' (duration: 289.652857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.319106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.268155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:17:22.319204Z","caller":"traceutil/trace.go:171","msg":"trace[977842265] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"290.341495ms","start":"2026-07-21T13:17:22.028848Z","end":"2026-07-21T13:17:22.319189Z","steps":["trace[977842265] 'read index received' (duration: 13.915418ms)","trace[977842265] 'applied index is now lower than readState.Index' (duration: 276.425167ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:22.319279Z","caller":"traceutil/trace.go:171","msg":"trace[1514531040] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"320.4073ms","start":"2026-07-21T13:17:21.998859Z","end":"2026-07-21T13:17:22.319266Z","steps":["trace[1514531040] 'process raft request' (duration: 43.902632ms)","trace[1514531040] 'compare' (duration: 276.130882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:22.31933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.998838Z","time spent":"320.470211ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:22.319438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.110885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:22.319522Z","caller":"traceutil/trace.go:171","msg":"trace[519516108] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:693; }","duration":"320.207357ms","start":"2026-07-21T13:17:21.999296Z","end":"2026-07-21T13:17:22.319504Z","steps":["trace[519516108] 'agreement among raft nodes before linearized reading' (duration: 320.068444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.31957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.999269Z","time spent":"320.292338ms","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-07-21T13:17:22.405154Z","caller":"traceutil/trace.go:171","msg":"trace[1721011577] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"368.501615ms","start":"2026-07-21T13:17:22.036632Z","end":"2026-07-21T13:17:22.405134Z","steps":["trace[1721011577] 'process raft request' (duration: 368.441504ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:22.405203Z","caller":"traceutil/trace.go:171","msg":"trace[1556842619] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"368.644558ms","start":"2026-07-21T13:17:22.036525Z","end":"2026-07-21T13:17:22.405169Z","steps":["trace[1556842619] 'process raft request' (duration: 368.442795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.405289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.036624Z","time spent":"368.600288ms","remote":"127.0.0.1:46702","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-07-21T13:17:22.405434Z","caller":"traceutil/trace.go:171","msg":"trace[974534750] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"368.23384ms","start":"2026-07-21T13:17:22.037192Z","end":"2026-07-21T13:17:22.405426Z","steps":["trace[974534750] 'process raft request' (duration: 367.913725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.405474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.03718Z","time spent":"368.274342ms","remote":"127.0.0.1:46702","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-07-21T13:17:22.40548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.036504Z","time spent":"368.771051ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:22.405674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.375691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:22.40574Z","caller":"traceutil/trace.go:171","msg":"trace[827049113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"369.485313ms","start":"2026-07-21T13:17:22.03624Z","end":"2026-07-21T13:17:22.405726Z","steps":["trace[827049113] 'agreement among raft nodes before linearized reading' (duration: 369.37029ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:22.40573Z","caller":"traceutil/trace.go:171","msg":"trace[477726742] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"367.63065ms","start":"2026-07-21T13:17:22.038082Z","end":"2026-07-21T13:17:22.405713Z","steps":["trace[477726742] 'process raft request' (duration: 367.313774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.405775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.03622Z","time spent":"369.547554ms","remote":"127.0.0.1:46436","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-07-21T13:17:22.405847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.038072Z","time spent":"367.715931ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:22.405918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.383401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-21T13:17:22.405945Z","caller":"traceutil/trace.go:171","msg":"trace[263723735] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:697; }","duration":"369.434093ms","start":"2026-07-21T13:17:22.036503Z","end":"2026-07-21T13:17:22.405937Z","steps":["trace[263723735] 'agreement among raft nodes before linearized reading' (duration: 369.386562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.405966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.036493Z","time spent":"369.468693ms","remote":"127.0.0.1:46606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-21T13:17:23.415361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099309,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:17:23.653089Z","caller":"traceutil/trace.go:171","msg":"trace[1549743526] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"738.736151ms","start":"2026-07-21T13:17:22.914332Z","end":"2026-07-21T13:17:23.653068Z","steps":["trace[1549743526] 'read index received' (duration: 738.487817ms)","trace[1549743526] 'applied index is now lower than readState.Index' (duration: 247.534µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:23.653253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.911114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:23.653229Z","caller":"traceutil/trace.go:171","msg":"trace[1382024059] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"779.275233ms","start":"2026-07-21T13:17:22.873922Z","end":"2026-07-21T13:17:23.653197Z","steps":["trace[1382024059] 'process raft request' (duration: 779.025358ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:23.65328Z","caller":"traceutil/trace.go:171","msg":"trace[466357547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"738.993726ms","start":"2026-07-21T13:17:22.914278Z","end":"2026-07-21T13:17:23.653271Z","steps":["trace[466357547] 'agreement among raft nodes before linearized reading' (duration: 738.912765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:23.653302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.914255Z","time spent":"739.041767ms","remote":"127.0.0.1:46428","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-07-21T13:17:23.653355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.873906Z","time spent":"779.391175ms","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:17:23.653472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.191279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-825fk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-21T13:17:23.653525Z","caller":"traceutil/trace.go:171","msg":"trace[2025724806] range","detail":"{range_begin:/registry/pods/kube-system/cilium-825fk; range_end:; response_count:1; response_revision:702; }","duration":"541.2828ms","start":"2026-07-21T13:17:23.112229Z","end":"2026-07-21T13:17:23.653512Z","steps":["trace[2025724806] 'agreement among raft nodes before linearized reading' (duration: 541.158788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:23.653564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:23.112214Z","time spent":"541.340851ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-825fk\" "} {"level":"info","ts":"2026-07-21T13:17:23.890176Z","caller":"traceutil/trace.go:171","msg":"trace[1266412429] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"212.181761ms","start":"2026-07-21T13:17:23.677971Z","end":"2026-07-21T13:17:23.890153Z","steps":["trace[1266412429] 'process raft request' (duration: 171.03579ms)","trace[1266412429] 'compare' (duration: 40.962438ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:24.825582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099317,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:17:25.286513Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.170856947s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:17:25.288578Z","caller":"traceutil/trace.go:171","msg":"trace[1796701675] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"963.992264ms","start":"2026-07-21T13:17:24.32455Z","end":"2026-07-21T13:17:25.288542Z","steps":["trace[1796701675] 'read index received' (duration: 962.163292ms)","trace[1796701675] 'applied index is now lower than readState.Index' (duration: 1.827292ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:25.288754Z","caller":"traceutil/trace.go:171","msg":"trace[1492118076] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"1.1744427s","start":"2026-07-21T13:17:24.114293Z","end":"2026-07-21T13:17:25.288736Z","steps":["trace[1492118076] 'process raft request' (duration: 1.172965044s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:25.289147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.73404ms","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-07-21T13:17:25.289092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.114266Z","time spent":"1.174654394s","remote":"127.0.0.1:46528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:25.289206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.558857ms","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-07-21T13:17:25.289209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.188516ms","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-07-21T13:17:25.289221Z","caller":"traceutil/trace.go:171","msg":"trace[1685219808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:704; }","duration":"878.828551ms","start":"2026-07-21T13:17:24.410373Z","end":"2026-07-21T13:17:25.289201Z","steps":["trace[1685219808] 'agreement among raft nodes before linearized reading' (duration: 878.691859ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:25.289267Z","caller":"traceutil/trace.go:171","msg":"trace[1028029639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:704; }","duration":"878.629727ms","start":"2026-07-21T13:17:24.410614Z","end":"2026-07-21T13:17:25.289243Z","steps":["trace[1028029639] 'agreement among raft nodes before linearized reading' (duration: 878.422944ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:25.289272Z","caller":"traceutil/trace.go:171","msg":"trace[2062510330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:704; }","duration":"879.287719ms","start":"2026-07-21T13:17:24.409968Z","end":"2026-07-21T13:17:25.289256Z","steps":["trace[2062510330] 'agreement among raft nodes before linearized reading' (duration: 879.130946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:25.28928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.410317Z","time spent":"878.949353ms","remote":"127.0.0.1:46702","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-07-21T13:17:25.289308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.409957Z","time spent":"879.343591ms","remote":"127.0.0.1:46702","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-07-21T13:17:25.289308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.410606Z","time spent":"878.690859ms","remote":"127.0.0.1:46702","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-07-21T13:17:25.289442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.102374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:25.289471Z","caller":"traceutil/trace.go:171","msg":"trace[813288212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"372.166845ms","start":"2026-07-21T13:17:24.917293Z","end":"2026-07-21T13:17:25.28946Z","steps":["trace[813288212] 'agreement among raft nodes before linearized reading' (duration: 372.125364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:25.289495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.917268Z","time spent":"372.223096ms","remote":"127.0.0.1:46428","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-07-21T13:17:25.289523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.946532ms","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-07-21T13:17:25.289569Z","caller":"traceutil/trace.go:171","msg":"trace[643698033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:704; }","duration":"965.033493ms","start":"2026-07-21T13:17:24.32452Z","end":"2026-07-21T13:17:25.289553Z","steps":["trace[643698033] 'agreement among raft nodes before linearized reading' (duration: 964.88509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:25.289612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.324506Z","time spent":"965.094053ms","remote":"127.0.0.1:46702","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-07-21T13:17:25.308095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.53341ms","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-07-21T13:17:25.308177Z","caller":"traceutil/trace.go:171","msg":"trace[1810026255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"898.685843ms","start":"2026-07-21T13:17:24.409468Z","end":"2026-07-21T13:17:25.308154Z","steps":["trace[1810026255] 'agreement among raft nodes before linearized reading' (duration: 879.536294ms)","trace[1810026255] 'range keys from in-memory index tree' (duration: 18.909225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:25.308217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:24.409402Z","time spent":"898.807576ms","remote":"127.0.0.1:46702","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-07-21T13:17:25.489273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.205357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:17:25.489428Z","caller":"traceutil/trace.go:171","msg":"trace[379389599] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"147.778472ms","start":"2026-07-21T13:17:25.341626Z","end":"2026-07-21T13:17:25.489404Z","steps":["trace[379389599] 'process raft request' (duration: 29.31722ms)","trace[379389599] 'compare' (duration: 118.023244ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:25.540016Z","caller":"traceutil/trace.go:171","msg":"trace[367951022] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"198.218378ms","start":"2026-07-21T13:17:25.341765Z","end":"2026-07-21T13:17:25.539984Z","steps":["trace[367951022] 'process raft request' (duration: 197.908482ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:25.541798Z","caller":"traceutil/trace.go:171","msg":"trace[771439153] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"198.739986ms","start":"2026-07-21T13:17:25.342978Z","end":"2026-07-21T13:17:25.541718Z","steps":["trace[771439153] 'process raft request' (duration: 196.913853ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:25.542238Z","caller":"traceutil/trace.go:171","msg":"trace[1383111509] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"198.126585ms","start":"2026-07-21T13:17:25.344095Z","end":"2026-07-21T13:17:25.542221Z","steps":["trace[1383111509] 'process raft request' (duration: 197.974413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:25.542398Z","caller":"traceutil/trace.go:171","msg":"trace[347040064] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"198.566493ms","start":"2026-07-21T13:17:25.343792Z","end":"2026-07-21T13:17:25.542359Z","steps":["trace[347040064] 'process raft request' (duration: 197.77881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:27.41519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099321,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:17:27.915705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099321,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:17:28.260721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.721129819s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:17:28.261367Z","caller":"traceutil/trace.go:171","msg":"trace[1815023792] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"2.857712641s","start":"2026-07-21T13:17:25.403626Z","end":"2026-07-21T13:17:28.261339Z","steps":["trace[1815023792] 'process raft request' (duration: 2.857543028s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.261669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:25.4036Z","time spent":"2.857961396s","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:17:28.266407Z","caller":"traceutil/trace.go:171","msg":"trace[720998018] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"2.598861939s","start":"2026-07-21T13:17:25.667522Z","end":"2026-07-21T13:17:28.266384Z","steps":["trace[720998018] 'process raft request' (duration: 2.598516483s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.266558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:25.667487Z","time spent":"2.598992231s","remote":"127.0.0.1:46702","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-07-21T13:17:28.271883Z","caller":"traceutil/trace.go:171","msg":"trace[1842045312] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:734; }","duration":"1.357188333s","start":"2026-07-21T13:17:26.914678Z","end":"2026-07-21T13:17:28.271866Z","steps":["trace[1842045312] 'read index received' (duration: 1.346509965s)","trace[1842045312] 'applied index is now lower than readState.Index' (duration: 10.677618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:28.272077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357393837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:28.272104Z","caller":"traceutil/trace.go:171","msg":"trace[1955096815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"1.357423658s","start":"2026-07-21T13:17:26.914671Z","end":"2026-07-21T13:17:28.272095Z","steps":["trace[1955096815] 'agreement among raft nodes before linearized reading' (duration: 1.357376087s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.272125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.914617Z","time spent":"1.357503108s","remote":"127.0.0.1:46428","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-07-21T13:17:28.272302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.796727ms","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-07-21T13:17:28.272321Z","caller":"traceutil/trace.go:171","msg":"trace[394146368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"778.852788ms","start":"2026-07-21T13:17:27.493462Z","end":"2026-07-21T13:17:28.272315Z","steps":["trace[394146368] 'agreement among raft nodes before linearized reading' (duration: 778.776547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.272338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:27.493443Z","time spent":"778.891259ms","remote":"127.0.0.1:46702","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-07-21T13:17:28.27289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.343753ms","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-07-21T13:17:28.272914Z","caller":"traceutil/trace.go:171","msg":"trace[1646882488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"720.413894ms","start":"2026-07-21T13:17:27.552493Z","end":"2026-07-21T13:17:28.272907Z","steps":["trace[1646882488] 'agreement among raft nodes before linearized reading' (duration: 720.353133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.272932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:27.552468Z","time spent":"720.459105ms","remote":"127.0.0.1:46702","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-07-21T13:17:28.273043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.595744ms","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-07-21T13:17:28.273063Z","caller":"traceutil/trace.go:171","msg":"trace[1324746925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"721.629457ms","start":"2026-07-21T13:17:27.551428Z","end":"2026-07-21T13:17:28.273057Z","steps":["trace[1324746925] 'agreement among raft nodes before linearized reading' (duration: 721.568985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.273082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:27.551421Z","time spent":"721.657137ms","remote":"127.0.0.1:46702","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-07-21T13:17:28.273181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.926331ms","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-07-21T13:17:28.273198Z","caller":"traceutil/trace.go:171","msg":"trace[1523560464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"721.965253ms","start":"2026-07-21T13:17:27.551227Z","end":"2026-07-21T13:17:28.273192Z","steps":["trace[1523560464] 'agreement among raft nodes before linearized reading' (duration: 721.914522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.273215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:27.551222Z","time spent":"721.988793ms","remote":"127.0.0.1:46702","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-07-21T13:17:28.273308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.098775ms","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-07-21T13:17:28.273325Z","caller":"traceutil/trace.go:171","msg":"trace[341122264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"722.149716ms","start":"2026-07-21T13:17:27.55117Z","end":"2026-07-21T13:17:28.27332Z","steps":["trace[341122264] 'agreement among raft nodes before linearized reading' (duration: 722.102015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:28.273341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:27.551152Z","time spent":"722.184666ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:17:41.648614Z","caller":"traceutil/trace.go:171","msg":"trace[523260117] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"397.862014ms","start":"2026-07-21T13:17:41.250732Z","end":"2026-07-21T13:17:41.648594Z","steps":["trace[523260117] 'process raft request' (duration: 397.714561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:41.648743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:41.250711Z","time spent":"397.966956ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4856,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:44.852016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:17:45.35272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17769127722599099636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:17:45.355454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070977568s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:17:45.356001Z","caller":"traceutil/trace.go:171","msg":"trace[1470565965] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"1.004272513s","start":"2026-07-21T13:17:44.351698Z","end":"2026-07-21T13:17:45.355971Z","steps":["trace[1470565965] 'read index received' (duration: 1.003964397s)","trace[1470565965] 'applied index is now lower than readState.Index' (duration: 306.936µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:45.356373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.735825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:45.356454Z","caller":"traceutil/trace.go:171","msg":"trace[1432071884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"105.846487ms","start":"2026-07-21T13:17:45.250582Z","end":"2026-07-21T13:17:45.356429Z","steps":["trace[1432071884] 'agreement among raft nodes before linearized reading' (duration: 105.725525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:45.356462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004747371s","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-07-21T13:17:45.356684Z","caller":"traceutil/trace.go:171","msg":"trace[2136595916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:831; }","duration":"1.005010076s","start":"2026-07-21T13:17:44.351647Z","end":"2026-07-21T13:17:45.356657Z","steps":["trace[2136595916] 'agreement among raft nodes before linearized reading' (duration: 1.004626519s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:45.356647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.059269ms","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-07-21T13:17:45.35673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:44.351623Z","time spent":"1.005096527s","remote":"127.0.0.1:46702","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-07-21T13:17:45.356768Z","caller":"traceutil/trace.go:171","msg":"trace[1210909048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"976.215854ms","start":"2026-07-21T13:17:44.380527Z","end":"2026-07-21T13:17:45.356743Z","steps":["trace[1210909048] 'agreement among raft nodes before linearized reading' (duration: 975.869627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:45.356748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.767532ms","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-07-21T13:17:45.356828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:44.380515Z","time spent":"976.303605ms","remote":"127.0.0.1:46702","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-07-21T13:17:45.356686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.362488ms","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-07-21T13:17:45.356865Z","caller":"traceutil/trace.go:171","msg":"trace[961954820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"992.931544ms","start":"2026-07-21T13:17:44.36391Z","end":"2026-07-21T13:17:45.356842Z","steps":["trace[961954820] 'agreement among raft nodes before linearized reading' (duration: 992.556417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:45.356909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:44.363898Z","time spent":"993.003065ms","remote":"127.0.0.1:46702","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-07-21T13:17:45.356955Z","caller":"traceutil/trace.go:171","msg":"trace[1739108019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"975.585212ms","start":"2026-07-21T13:17:44.381295Z","end":"2026-07-21T13:17:45.35688Z","steps":["trace[1739108019] 'agreement among raft nodes before linearized reading' (duration: 975.194145ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:45.356936Z","caller":"traceutil/trace.go:171","msg":"trace[2112079254] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"1.072534415s","start":"2026-07-21T13:17:44.284337Z","end":"2026-07-21T13:17:45.356872Z","steps":["trace[2112079254] 'process raft request' (duration: 1.071405885s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:45.357008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:44.38129Z","time spent":"975.705694ms","remote":"127.0.0.1:46702","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-07-21T13:17:45.357083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.901807ms","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-07-21T13:17:45.357168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:44.284306Z","time spent":"1.072710328s","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:17:45.357186Z","caller":"traceutil/trace.go:171","msg":"trace[1197499075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"1.000060708s","start":"2026-07-21T13:17:44.357096Z","end":"2026-07-21T13:17:45.357156Z","steps":["trace[1197499075] 'agreement among raft nodes before linearized reading' (duration: 999.181173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:45.357241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:44.35707Z","time spent":"1.000163141s","remote":"127.0.0.1:46702","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-07-21T13:17:51.036738Z","caller":"traceutil/trace.go:171","msg":"trace[352230337] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"158.401564ms","start":"2026-07-21T13:17:50.878317Z","end":"2026-07-21T13:17:51.036718Z","steps":["trace[352230337] 'read index received' (duration: 22.127735ms)","trace[352230337] 'applied index is now lower than readState.Index' (duration: 136.273099ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:51.03677Z","caller":"traceutil/trace.go:171","msg":"trace[1284086308] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"166.238361ms","start":"2026-07-21T13:17:50.870508Z","end":"2026-07-21T13:17:51.036746Z","steps":["trace[1284086308] 'process raft request' (duration: 165.494598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:51.037025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.683489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-21T13:17:51.037313Z","caller":"traceutil/trace.go:171","msg":"trace[95414810] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:888; }","duration":"159.015234ms","start":"2026-07-21T13:17:50.878273Z","end":"2026-07-21T13:17:51.037289Z","steps":["trace[95414810] 'agreement among raft nodes before linearized reading' (duration: 158.520425ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:51.481149Z","caller":"traceutil/trace.go:171","msg":"trace[1492514262] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"102.445601ms","start":"2026-07-21T13:17:51.378635Z","end":"2026-07-21T13:17:51.481081Z","steps":["trace[1492514262] 'process raft request' (duration: 95.337997ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:51.73281Z","caller":"traceutil/trace.go:171","msg":"trace[300906079] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"242.107516ms","start":"2026-07-21T13:17:51.490653Z","end":"2026-07-21T13:17:51.73276Z","steps":["trace[300906079] 'process raft request' (duration: 242.030695ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:51.73284Z","caller":"traceutil/trace.go:171","msg":"trace[499656968] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"242.377521ms","start":"2026-07-21T13:17:51.490428Z","end":"2026-07-21T13:17:51.732805Z","steps":["trace[499656968] 'process raft request' (duration: 198.660152ms)","trace[499656968] 'compare' (duration: 43.406014ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:53.451859Z","caller":"traceutil/trace.go:171","msg":"trace[1194362899] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"134.340361ms","start":"2026-07-21T13:17:53.317495Z","end":"2026-07-21T13:17:53.451836Z","steps":["trace[1194362899] 'process raft request' (duration: 134.123926ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:53.601666Z","caller":"traceutil/trace.go:171","msg":"trace[1454152041] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"142.3398ms","start":"2026-07-21T13:17:53.459312Z","end":"2026-07-21T13:17:53.601652Z","steps":["trace[1454152041] 'process raft request' (duration: 136.02829ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:53.820762Z","caller":"traceutil/trace.go:171","msg":"trace[1667771120] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"361.141716ms","start":"2026-07-21T13:17:53.459608Z","end":"2026-07-21T13:17:53.82075Z","steps":["trace[1667771120] 'process raft request' (duration: 273.946753ms)","trace[1667771120] 'compare' (duration: 86.998869ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:53.820821Z","caller":"traceutil/trace.go:171","msg":"trace[747900389] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"359.946875ms","start":"2026-07-21T13:17:53.460864Z","end":"2026-07-21T13:17:53.820811Z","steps":["trace[747900389] 'process raft request' (duration: 359.794892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.820847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:53.459587Z","time spent":"361.215357ms","remote":"127.0.0.1:46626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:17:53.820762Z","caller":"traceutil/trace.go:171","msg":"trace[1213114937] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:935; }","duration":"326.667718ms","start":"2026-07-21T13:17:53.494077Z","end":"2026-07-21T13:17:53.820744Z","steps":["trace[1213114937] 'read index received' (duration: 101.273908ms)","trace[1213114937] 'applied index is now lower than readState.Index' (duration: 225.39301ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:53.820931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:53.460855Z","time spent":"360.008696ms","remote":"127.0.0.1:43384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2315,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:53.821001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.727246ms","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-07-21T13:17:53.821018Z","caller":"traceutil/trace.go:171","msg":"trace[1503114942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"304.780307ms","start":"2026-07-21T13:17:53.516231Z","end":"2026-07-21T13:17:53.821012Z","steps":["trace[1503114942] 'agreement among raft nodes before linearized reading' (duration: 304.717036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.82103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:53.516213Z","time spent":"304.814937ms","remote":"127.0.0.1:46702","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-07-21T13:17:53.821152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.077604ms","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-07-21T13:17:53.821165Z","caller":"traceutil/trace.go:171","msg":"trace[1675626093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"327.122625ms","start":"2026-07-21T13:17:53.494038Z","end":"2026-07-21T13:17:53.821161Z","steps":["trace[1675626093] 'agreement among raft nodes before linearized reading' (duration: 326.755459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.821179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:53.49402Z","time spent":"327.154946ms","remote":"127.0.0.1:46702","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-07-21T13:17:53.821175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.279351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:53.821215Z","caller":"traceutil/trace.go:171","msg":"trace[32696292] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:906; }","duration":"288.344492ms","start":"2026-07-21T13:17:53.532859Z","end":"2026-07-21T13:17:53.821203Z","steps":["trace[32696292] 'agreement among raft nodes before linearized reading' (duration: 288.275411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.821257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.17621ms","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-07-21T13:17:53.821269Z","caller":"traceutil/trace.go:171","msg":"trace[937871431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"299.19649ms","start":"2026-07-21T13:17:53.522069Z","end":"2026-07-21T13:17:53.821265Z","steps":["trace[937871431] 'agreement among raft nodes before linearized reading' (duration: 299.16142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.821349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.894653ms","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-07-21T13:17:53.821361Z","caller":"traceutil/trace.go:171","msg":"trace[5505901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"299.931623ms","start":"2026-07-21T13:17:53.521426Z","end":"2026-07-21T13:17:53.821358Z","steps":["trace[5505901] 'agreement among raft nodes before linearized reading' (duration: 299.898632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.821419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.519723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-l9dcw\" ","response":"range_response_count:1 size:3871"} {"level":"warn","ts":"2026-07-21T13:17:53.821437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.982295ms","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-07-21T13:17:53.821448Z","caller":"traceutil/trace.go:171","msg":"trace[76144024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"300.018685ms","start":"2026-07-21T13:17:53.521426Z","end":"2026-07-21T13:17:53.821445Z","steps":["trace[76144024] 'agreement among raft nodes before linearized reading' (duration: 299.984565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:53.82146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:53.52141Z","time spent":"300.046846ms","remote":"127.0.0.1:46702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:17:53.82145Z","caller":"traceutil/trace.go:171","msg":"trace[1667707660] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-l9dcw; range_end:; response_count:1; response_revision:906; }","duration":"214.567503ms","start":"2026-07-21T13:17:53.606872Z","end":"2026-07-21T13:17:53.82144Z","steps":["trace[1667707660] 'agreement among raft nodes before linearized reading' (duration: 214.479381ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:53.821469Z","caller":"traceutil/trace.go:171","msg":"trace[790758596] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"213.932511ms","start":"2026-07-21T13:17:53.607525Z","end":"2026-07-21T13:17:53.821458Z","steps":["trace[790758596] 'process raft request' (duration: 213.190759ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:53.940341Z","caller":"traceutil/trace.go:171","msg":"trace[1335626394] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"111.90348ms","start":"2026-07-21T13:17:53.828408Z","end":"2026-07-21T13:17:53.940312Z","steps":["trace[1335626394] 'process raft request' (duration: 107.940032ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:54.032101Z","caller":"traceutil/trace.go:171","msg":"trace[1798667267] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:939; }","duration":"116.563131ms","start":"2026-07-21T13:17:53.915517Z","end":"2026-07-21T13:17:54.03208Z","steps":["trace[1798667267] 'read index received' (duration: 20.840792ms)","trace[1798667267] 'applied index is now lower than readState.Index' (duration: 95.721489ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:54.032408Z","caller":"traceutil/trace.go:171","msg":"trace[619106689] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"203.303916ms","start":"2026-07-21T13:17:53.829093Z","end":"2026-07-21T13:17:54.032397Z","steps":["trace[619106689] 'process raft request' (duration: 202.646285ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:54.032615Z","caller":"traceutil/trace.go:171","msg":"trace[1553545347] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"201.983833ms","start":"2026-07-21T13:17:53.830621Z","end":"2026-07-21T13:17:54.032605Z","steps":["trace[1553545347] 'process raft request' (duration: 201.266111ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:54.032762Z","caller":"traceutil/trace.go:171","msg":"trace[865199917] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"202.128596ms","start":"2026-07-21T13:17:53.830621Z","end":"2026-07-21T13:17:54.03275Z","steps":["trace[865199917] 'process raft request' (duration: 201.319202ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:54.032911Z","caller":"traceutil/trace.go:171","msg":"trace[1214791158] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"202.235368ms","start":"2026-07-21T13:17:53.830664Z","end":"2026-07-21T13:17:54.0329Z","steps":["trace[1214791158] 'process raft request' (duration: 201.315031ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:54.033014Z","caller":"traceutil/trace.go:171","msg":"trace[2083732597] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"202.314839ms","start":"2026-07-21T13:17:53.83067Z","end":"2026-07-21T13:17:54.032985Z","steps":["trace[2083732597] 'process raft request' (duration: 201.344282ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:54.033239Z","caller":"traceutil/trace.go:171","msg":"trace[1191470696] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"201.270091ms","start":"2026-07-21T13:17:53.831958Z","end":"2026-07-21T13:17:54.033228Z","steps":["trace[1191470696] 'process raft request' (duration: 200.08895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:54.033557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.928805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:54.033597Z","caller":"traceutil/trace.go:171","msg":"trace[424240925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"118.118839ms","start":"2026-07-21T13:17:53.915468Z","end":"2026-07-21T13:17:54.033587Z","steps":["trace[424240925] 'agreement among raft nodes before linearized reading' (duration: 117.936125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:18:06.598027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.422396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-21T13:18:06.598127Z","caller":"traceutil/trace.go:171","msg":"trace[187806295] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"117.178805ms","start":"2026-07-21T13:18:06.48093Z","end":"2026-07-21T13:18:06.598109Z","steps":["trace[187806295] 'read index received' (duration: 34.27µs)","trace[187806295] 'applied index is now lower than readState.Index' (duration: 117.143205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:18:06.598199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.257866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:18:06.598226Z","caller":"traceutil/trace.go:171","msg":"trace[616253131] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1030; }","duration":"117.325358ms","start":"2026-07-21T13:18:06.480893Z","end":"2026-07-21T13:18:06.598218Z","steps":["trace[616253131] 'agreement among raft nodes before linearized reading' (duration: 117.262797ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:18:21.274579Z","caller":"traceutil/trace.go:171","msg":"trace[1517920961] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"159.215661ms","start":"2026-07-21T13:18:21.115326Z","end":"2026-07-21T13:18:21.274542Z","steps":["trace[1517920961] 'process raft request' (duration: 150.741297ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:18:21.274858Z","caller":"traceutil/trace.go:171","msg":"trace[1310306712] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"135.429417ms","start":"2026-07-21T13:18:21.139398Z","end":"2026-07-21T13:18:21.274828Z","steps":["trace[1310306712] 'process raft request' (duration: 135.294715ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:18:38.606114Z","caller":"traceutil/trace.go:171","msg":"trace[2067195718] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1179; }","duration":"202.691711ms","start":"2026-07-21T13:18:38.403403Z","end":"2026-07-21T13:18:38.606095Z","steps":["trace[2067195718] 'read index received' (duration: 139.208679ms)","trace[2067195718] 'applied index is now lower than readState.Index' (duration: 63.481682ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:18:38.606148Z","caller":"traceutil/trace.go:171","msg":"trace[1424739469] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"203.395852ms","start":"2026-07-21T13:18:38.402725Z","end":"2026-07-21T13:18:38.606121Z","steps":["trace[1424739469] 'process raft request' (duration: 203.208608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:18:38.606379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.955625ms","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-07-21T13:18:38.606427Z","caller":"traceutil/trace.go:171","msg":"trace[1439864652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1137; }","duration":"203.031036ms","start":"2026-07-21T13:18:38.403383Z","end":"2026-07-21T13:18:38.606414Z","steps":["trace[1439864652] 'agreement among raft nodes before linearized reading' (duration: 202.872913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:18:38.606446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.996265ms","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-07-21T13:18:38.606476Z","caller":"traceutil/trace.go:171","msg":"trace[1515161845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"203.045046ms","start":"2026-07-21T13:18:38.403422Z","end":"2026-07-21T13:18:38.606467Z","steps":["trace[1515161845] 'agreement among raft nodes before linearized reading' (duration: 202.915294ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:18:38.606018Z","caller":"traceutil/trace.go:171","msg":"trace[1068838861] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"204.003312ms","start":"2026-07-21T13:18:38.401978Z","end":"2026-07-21T13:18:38.605981Z","steps":["trace[1068838861] 'process raft request' (duration: 140.654804ms)","trace[1068838861] 'compare' (duration: 63.156825ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:18:38.863549Z","caller":"traceutil/trace.go:171","msg":"trace[1973076211] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"135.381614ms","start":"2026-07-21T13:18:38.728121Z","end":"2026-07-21T13:18:38.863503Z","steps":["trace[1973076211] 'read index received' (duration: 133.835868ms)","trace[1973076211] 'applied index is now lower than readState.Index' (duration: 1.544505ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:18:38.86364Z","caller":"traceutil/trace.go:171","msg":"trace[1160123604] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"252.875096ms","start":"2026-07-21T13:18:38.610743Z","end":"2026-07-21T13:18:38.863618Z","steps":["trace[1160123604] 'process raft request' (duration: 251.30968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:18:38.863694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.547998ms","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-07-21T13:18:38.864146Z","caller":"traceutil/trace.go:171","msg":"trace[1254552291] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1139; }","duration":"136.045105ms","start":"2026-07-21T13:18:38.728071Z","end":"2026-07-21T13:18:38.864116Z","steps":["trace[1254552291] 'agreement among raft nodes before linearized reading' (duration: 135.555246ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:18:50.919161Z","caller":"traceutil/trace.go:171","msg":"trace[947708974] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"107.782198ms","start":"2026-07-21T13:18:50.811005Z","end":"2026-07-21T13:18:50.918788Z","steps":["trace[947708974] 'process raft request' (duration: 35.093249ms)","trace[947708974] 'compare' (duration: 72.494436ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:18:51.242727Z","caller":"traceutil/trace.go:171","msg":"trace[505723830] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"133.469688ms","start":"2026-07-21T13:18:51.109239Z","end":"2026-07-21T13:18:51.242709Z","steps":["trace[505723830] 'read index received' (duration: 81.10667ms)","trace[505723830] 'applied index is now lower than readState.Index' (duration: 52.360718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:18:51.242895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.643401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.68\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T13:18:51.242922Z","caller":"traceutil/trace.go:171","msg":"trace[244099055] range","detail":"{range_begin:/registry/masterleases/199.19.213.68; range_end:; response_count:1; response_revision:1179; }","duration":"133.725091ms","start":"2026-07-21T13:18:51.109188Z","end":"2026-07-21T13:18:51.242914Z","steps":["trace[244099055] 'agreement among raft nodes before linearized reading' (duration: 133.59794ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:18:51.242905Z","caller":"traceutil/trace.go:171","msg":"trace[155206484] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"319.496798ms","start":"2026-07-21T13:18:50.923369Z","end":"2026-07-21T13:18:51.242865Z","steps":["trace[155206484] 'process raft request' (duration: 266.932586ms)","trace[155206484] 'compare' (duration: 52.323358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:18:51.24308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:18:50.923354Z","time spent":"319.63007ms","remote":"127.0.0.1:46702","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-07-21T13:19:39.659416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.104014ms","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-07-21T13:19:39.659572Z","caller":"traceutil/trace.go:171","msg":"trace[759904614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"115.298326ms","start":"2026-07-21T13:19:39.544252Z","end":"2026-07-21T13:19:39.659551Z","steps":["trace[759904614] 'range keys from in-memory index tree' (duration: 114.94253ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:19:49.202901Z","caller":"traceutil/trace.go:171","msg":"trace[618042408] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"287.87718ms","start":"2026-07-21T13:19:48.914996Z","end":"2026-07-21T13:19:49.202873Z","steps":["trace[618042408] 'read index received' (duration: 287.626976ms)","trace[618042408] 'applied index is now lower than readState.Index' (duration: 248.964µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:19:49.203036Z","caller":"traceutil/trace.go:171","msg":"trace[796730257] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"312.868229ms","start":"2026-07-21T13:19:48.890129Z","end":"2026-07-21T13:19:49.202997Z","steps":["trace[796730257] 'process raft request' (duration: 312.550254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:19:49.203245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:19:48.890107Z","time spent":"313.031042ms","remote":"127.0.0.1:46702","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-07-21T13:19:49.20325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.216475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:19:49.203347Z","caller":"traceutil/trace.go:171","msg":"trace[1282782060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1405; }","duration":"288.367157ms","start":"2026-07-21T13:19:48.91496Z","end":"2026-07-21T13:19:49.203327Z","steps":["trace[1282782060] 'agreement among raft nodes before linearized reading' (duration: 288.083693ms)"],"step_count":1}